builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-021 starttime: 1506561646.82 results: success (0) revision: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:46.819576) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:46.819987) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:46.820261) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:46.837527) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:46.837789) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 18:20:46-- 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% 20.8M=0.001s 2017-09-27 18:20:46 (20.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143191 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:46.998586) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:46.998897) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021721 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:47.051450) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:47.051794) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 --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 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 18:20:47,098 truncating revision to first 12 chars 2017-09-27 18:20:47,098 Setting DEBUG logging. 2017-09-27 18:20:47,098 attempt 1/10 2017-09-27 18:20:47,098 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5ebe2e8980c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 18:20:47,475 unpacking tar archive at: mozilla-central-5ebe2e8980c6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.655985 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:47.724183) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:47.727539) ========= script_repo_revision: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:47.727914) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:47.728180) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 18:20:47.743502) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-27 18:20:47.743774) ========= /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=df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:20:47 INFO - MultiFileLogger online at 20170927 18:20:47 in /builds/slave/test 18:20:47 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 18:20:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:20:47 INFO - {'append_to_log': False, 18:20:47 INFO - 'base_work_dir': '/builds/slave/test', 18:20:47 INFO - 'blob_upload_branch': 'Firefox', 18:20:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:20:47 INFO - 'branch': 'Firefox', 18:20:47 INFO - 'buildbot_json_path': 'buildprops.json', 18:20:47 INFO - 'code_coverage': False, 18:20:47 INFO - 'config_files': ('talos/linux_config.py',), 18:20:47 INFO - 'default_actions': ('clobber', 18:20:47 INFO - 'read-buildbot-config', 18:20:47 INFO - 'download-and-extract', 18:20:47 INFO - 'populate-webroot', 18:20:47 INFO - 'create-virtualenv', 18:20:47 INFO - 'install', 18:20:47 INFO - 'setup-mitmproxy', 18:20:47 INFO - 'run-tests'), 18:20:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:20:47 INFO - 'disable_ccov_upload': False, 18:20:47 INFO - 'disable_stylo': False, 18:20:47 INFO - 'download_minidump_stackwalk': True, 18:20:47 INFO - 'download_symbols': 'ondemand', 18:20:47 INFO - 'enable_stylo': False, 18:20:47 INFO - 'enable_webrender': False, 18:20:47 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:20:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:20:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:20:47 INFO - 'gecko_profile': False, 18:20:47 INFO - 'gecko_profile_interval': 0, 18:20:47 INFO - 'installer_path': 'installer.exe', 18:20:47 INFO - 'log_level': 'info', 18:20:47 INFO - 'log_name': 'talos', 18:20:47 INFO - 'log_to_console': True, 18:20:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:20:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:20:47 INFO - 'opt_config_files': (), 18:20:47 INFO - 'pip_index': False, 18:20:47 INFO - 'suite': 'dromaeojs-e10s', 18:20:47 INFO - 'system_bits': '32', 18:20:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:20:47 INFO - 'title': 'talos-linux64-ix-021', 18:20:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:20:47 INFO - 'use_talos_json': True, 18:20:47 INFO - 'verify': 'False', 18:20:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:20:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:20:47 INFO - 'work_dir': 'build'} 18:20:47 INFO - [mozharness: 2017-09-28 01:20:47.940538Z] Running clobber step. 18:20:47 INFO - Running pre-action listener: _resource_record_pre_action 18:20:47 INFO - Running main action method: clobber 18:20:47 INFO - rmtree: /builds/slave/test/build 18:20:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:20:49 INFO - Running post-action listener: _resource_record_post_action 18:20:49 INFO - [mozharness: 2017-09-28 01:20:49.028175Z] Finished clobber step (success) 18:20:49 INFO - [mozharness: 2017-09-28 01:20:49.028273Z] Running read-buildbot-config step. 18:20:49 INFO - Running pre-action listener: _resource_record_pre_action 18:20:49 INFO - Running main action method: read_buildbot_config 18:20:49 INFO - Using buildbot properties: 18:20:49 INFO - { 18:20:49 INFO - "project": "", 18:20:49 INFO - "product": "firefox", 18:20:49 INFO - "who": "kwierso@gmail.com", 18:20:49 INFO - "installer_path": "public/build/target.tar.bz2", 18:20:49 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:20:49 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:20:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:20:49 INFO - "stage_platform": "linux64-pgo", 18:20:49 INFO - "basedir": "/builds/slave/test", 18:20:49 INFO - "buildnumber": 214, 18:20:49 INFO - "platform": "ubuntu64_hw", 18:20:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:20:49 INFO - "slavebuilddir": "test", 18:20:49 INFO - "taskId": "Ld_sHrzhQJizEKRjerg3wg", 18:20:49 INFO - "branch": "mozilla-central", 18:20:49 INFO - "script_repo_revision": "production", 18:20:49 INFO - "revision": "5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369", 18:20:49 INFO - "slavename": "talos-linux64-ix-021", 18:20:49 INFO - "repo_path": "mozilla-central" 18:20:49 INFO - } 18:20:49 INFO - Finding installer, test and symbols from parent task. 18:20:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ld_sHrzhQJizEKRjerg3wg'}, attempt #1 18:20:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ld_sHrzhQJizEKRjerg3wg'}, attempt #1 18:20:49 INFO - Task dependencies: Nd2wp_F6SlCE3VuynIQczQ 18:20:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ'}, attempt #1 18:20:50 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.tar.bz2 18:20:50 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.test_packages.json 18:20:50 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.crashreporter-symbols.zip 18:20:50 INFO - Running post-action listener: _resource_record_post_action 18:20:50 INFO - [mozharness: 2017-09-28 01:20:50.431293Z] Finished read-buildbot-config step (success) 18:20:50 INFO - [mozharness: 2017-09-28 01:20:50.431513Z] Running download-and-extract step. 18:20:50 INFO - Running pre-action listener: _resource_record_pre_action 18:20:50 INFO - Running main action method: download_and_extract 18:20:50 INFO - mkdir: /builds/slave/test/build/tests 18:20:50 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')]} 18:20:50 INFO - trying https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.test_packages.json 18:20:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:20:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:20:50 INFO - Downloaded 989 bytes. 18:20:50 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:20:50 INFO - Using the following test package requirements: 18:20:50 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:20:50 INFO - u'common': [u'target.common.tests.zip'], 18:20:50 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:20:50 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:20:50 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:20:50 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:20:50 INFO - u'mozbase': [u'target.common.tests.zip'], 18:20:50 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:20:50 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:20:50 INFO - u'web-platform': [u'target.common.tests.zip', 18:20:50 INFO - u'target.web-platform.tests.tar.gz'], 18:20:50 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:20:50 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:20:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.common.tests.zip 18:20:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:20:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.common.tests.zip into memory 18:20:52 INFO - Content-Length response header: 55351016 18:20:52 INFO - Bytes received: 55351016 18:21:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.talos.tests.zip 18:21:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:21:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.talos.tests.zip into memory 18:21:03 INFO - Content-Length response header: 14521162 18:21:03 INFO - Bytes received: 14521162 18:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:04 INFO - trying https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.tar.bz2 18:21:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:21:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:21:05 INFO - Downloaded 62424915 bytes. 18:21:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.tar.bz2 18:21:05 INFO - mkdir: /builds/slave/test/properties 18:21:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:21:05 INFO - Writing to file /builds/slave/test/properties/build_url 18:21:05 INFO - Contents: 18:21:05 INFO - build_url:https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.tar.bz2 18:21:05 INFO - Running post-action listener: _resource_record_post_action 18:21:05 INFO - Running post-action listener: find_tests_for_verification 18:21:05 INFO - Running post-action listener: set_extra_try_arguments 18:21:05 INFO - [mozharness: 2017-09-28 01:21:05.328957Z] Finished download-and-extract step (success) 18:21:05 INFO - [mozharness: 2017-09-28 01:21:05.329185Z] Running populate-webroot step. 18:21:05 INFO - Running pre-action listener: _resource_record_pre_action 18:21:05 INFO - Running main action method: populate_webroot 18:21:05 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:21:05 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['tresize', 18:21:05 INFO - 'tcanvasmark']}, 18:21:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:21:05 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['dromaeo_css', 18:21:05 INFO - 'kraken']}, 18:21:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:21:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:21:05 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:21:05 INFO - 'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:21:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:21:05 INFO - 'tests': ['damp', 'tps']}, 18:21:05 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:21:05 INFO - 'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['damp', 'tps']}, 18:21:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:21:05 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['dromaeo_dom']}, 18:21:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:21:05 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['basic_compositor_video', 18:21:05 INFO - 'glvideo']}, 18:21:05 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:21:05 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:21:05 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:21:05 INFO - 'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['ts_paint_webext', 18:21:05 INFO - 'tp5o_webext']}, 18:21:05 INFO - 'other-e10s': {'tests': ['a11yr', 18:21:05 INFO - 'ts_paint', 18:21:05 INFO - 'tpaint', 18:21:05 INFO - 'sessionrestore', 18:21:05 INFO - 'sessionrestore_many_windows', 18:21:05 INFO - 'sessionrestore_no_auto_restore', 18:21:05 INFO - 'tabpaint', 18:21:05 INFO - 'cpstartup']}, 18:21:05 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['a11yr', 18:21:05 INFO - 'ts_paint', 18:21:05 INFO - 'tpaint', 18:21:05 INFO - 'sessionrestore', 18:21:05 INFO - 'sessionrestore_many_windows', 18:21:05 INFO - 'sessionrestore_no_auto_restore', 18:21:05 INFO - 'tabpaint', 18:21:05 INFO - 'cpstartup']}, 18:21:05 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 18:21:05 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 18:21:05 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['perf_reftest_singletons']}, 18:21:05 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['perf_reftest']}, 18:21:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:21:05 INFO - 'tsvgr_opacity', 18:21:05 INFO - 'tart', 18:21:05 INFO - 'tscrollx', 18:21:05 INFO - 'tsvg_static']}, 18:21:05 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['tsvgx', 18:21:05 INFO - 'tsvgr_opacity', 18:21:05 INFO - 'tart', 18:21:05 INFO - 'tscrollx', 18:21:05 INFO - 'tsvg_static']}, 18:21:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:21:05 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:21:05 INFO - 'talos_options': ['--disable-stylo'], 18:21:05 INFO - 'tests': ['tp5o']}, 18:21:05 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:21:05 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:21:05 INFO - 'talos_options': ['--mitmproxy', 18:21:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:21:05 INFO - '--firstNonBlankPaint'], 18:21:05 INFO - 'tests': ['tp6_google', 18:21:05 INFO - 'tp6_youtube', 18:21:05 INFO - 'tp6_amazon', 18:21:05 INFO - 'tp6_facebook']}, 18:21:05 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:21:05 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:21:05 INFO - 'talos_options': ['--disable-stylo', 18:21:05 INFO - '--mitmproxy', 18:21:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:21:05 INFO - '--firstNonBlankPaint'], 18:21:05 INFO - 'tests': ['tp6_google', 18:21:05 INFO - 'tp6_youtube', 18:21:05 INFO - 'tp6_amazon', 18:21:05 INFO - 'tp6_facebook']}, 18:21:05 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:21:05 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:21:05 INFO - 'talos_options': ['--stylo-threads=1', 18:21:05 INFO - '--mitmproxy', 18:21:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:21:05 INFO - '--firstNonBlankPaint'], 18:21:05 INFO - 'tests': ['tp6_google', 18:21:05 INFO - 'tp6_youtube', 18:21:05 INFO - 'tp6_amazon', 18:21:05 INFO - 'tp6_facebook']}, 18:21:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:21:05 INFO - 'talos_options': ['--xperf_path', 18:21:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:21:05 INFO - 'tests': ['tp5n']}, 18:21:05 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:21:05 INFO - 'talos_options': ['--disable-stylo', 18:21:05 INFO - '--xperf_path', 18:21:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:21:05 INFO - 'tests': ['tp5n']}}} 18:21:05 INFO - Running post-action listener: _resource_record_post_action 18:21:05 INFO - [mozharness: 2017-09-28 01:21:05.344964Z] Finished populate-webroot step (success) 18:21:05 INFO - [mozharness: 2017-09-28 01:21:05.345054Z] Running create-virtualenv step. 18:21:05 INFO - Running pre-action listener: _resource_record_pre_action 18:21:05 INFO - Running main action method: create_virtualenv 18:21:05 INFO - Creating virtualenv /builds/slave/test/build/venv 18:21:05 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:21:05 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:21:05 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:21:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:21:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:21:06 INFO - Installing distribute.............................................................................................................................................................................................done. 18:21:06 INFO - Installing pip...............done. 18:21:06 INFO - Return code: 0 18:21:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:21:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:21:06 INFO - Reading from file tmpfile_stdout 18:21:06 INFO - Output received: 18:21:06 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:21:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:06 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 0x1acb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c93b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e389c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e35d60>, '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 0x1e36a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e36ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', '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 18:21:06 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 18:21:06 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 18:21:06 INFO - Using env: {'DISPLAY': ':0', 18:21:06 INFO - 'HOME': '/home/cltbld', 18:21:06 INFO - 'LANG': 'en_US.UTF-8', 18:21:06 INFO - 'LANGUAGE': 'en_US:en', 18:21:06 INFO - 'LOGNAME': 'cltbld', 18:21:06 INFO - 'MAIL': '/var/mail/cltbld', 18:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:06 INFO - 'MOZ_NO_REMOTE': '1', 18:21:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:06 INFO - 'NO_EM_RESTART': '1', 18:21:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:06 INFO - 'PWD': '/builds/slave/test', 18:21:06 INFO - 'SHELL': '/bin/bash', 18:21:06 INFO - 'SHLVL': '1', 18:21:06 INFO - 'TERM': 'linux', 18:21:06 INFO - 'TMOUT': '86400', 18:21:06 INFO - 'USER': 'cltbld', 18:21:06 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:06 INFO - '_': '/tools/buildbot/bin/python'} 18:21:07 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:21:07 INFO - Downloading/unpacking pip>=1.5 18:21:07 INFO - Running setup.py egg_info for package pip 18:21:07 INFO - warning: no files found matching 'pip/cacert.pem' 18:21:07 INFO - warning: no files found matching '*.html' under directory 'docs' 18:21:07 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:21:07 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:21:07 INFO - Installing collected packages: pip 18:21:07 INFO - Found existing installation: pip 1.1 18:21:07 INFO - Uninstalling pip: 18:21:07 INFO - Successfully uninstalled pip 18:21:07 INFO - Running setup.py install for pip 18:21:07 INFO - warning: no files found matching 'pip/cacert.pem' 18:21:07 INFO - warning: no files found matching '*.html' under directory 'docs' 18:21:07 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:21:07 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:21:07 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:21:07 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:21:07 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:21:07 INFO - Successfully installed pip 18:21:07 INFO - Cleaning up... 18:21:07 INFO - Return code: 0 18:21:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:07 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 0x1acb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c93b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e389c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e35d60>, '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 0x1e36a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e36ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', '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 18:21:07 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 18:21:07 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 18:21:07 INFO - Using env: {'DISPLAY': ':0', 18:21:07 INFO - 'HOME': '/home/cltbld', 18:21:07 INFO - 'LANG': 'en_US.UTF-8', 18:21:07 INFO - 'LANGUAGE': 'en_US:en', 18:21:07 INFO - 'LOGNAME': 'cltbld', 18:21:07 INFO - 'MAIL': '/var/mail/cltbld', 18:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:07 INFO - 'MOZ_NO_REMOTE': '1', 18:21:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:07 INFO - 'NO_EM_RESTART': '1', 18:21:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:07 INFO - 'PWD': '/builds/slave/test', 18:21:07 INFO - 'SHELL': '/bin/bash', 18:21:07 INFO - 'SHLVL': '1', 18:21:07 INFO - 'TERM': 'linux', 18:21:07 INFO - 'TMOUT': '86400', 18:21:07 INFO - 'USER': 'cltbld', 18:21:07 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:07 INFO - '_': '/tools/buildbot/bin/python'} 18:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:08 INFO - Downloading/unpacking psutil>=3.1.1 18:21:08 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 18:21:08 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 18:21:08 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 18:21:08 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 18:21:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:21:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:21:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:21:09 INFO - Installing collected packages: psutil 18:21:09 INFO - Running setup.py install for psutil 18:21:09 INFO - building 'psutil._psutil_linux' extension 18:21:09 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 18:21:09 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 18:21:09 INFO - building 'psutil._psutil_posix' extension 18:21:09 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 18:21:09 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 18:21:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:21:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:21:09 INFO - Successfully installed psutil 18:21:09 INFO - Cleaning up... 18:21:09 INFO - Return code: 0 18:21:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:09 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 0x1acb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c93b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e389c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e35d60>, '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 0x1e36a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e36ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', '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 18:21:09 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 18:21:09 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 18:21:09 INFO - Using env: {'DISPLAY': ':0', 18:21:09 INFO - 'HOME': '/home/cltbld', 18:21:09 INFO - 'LANG': 'en_US.UTF-8', 18:21:09 INFO - 'LANGUAGE': 'en_US:en', 18:21:09 INFO - 'LOGNAME': 'cltbld', 18:21:09 INFO - 'MAIL': '/var/mail/cltbld', 18:21:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:09 INFO - 'MOZ_NO_REMOTE': '1', 18:21:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:09 INFO - 'NO_EM_RESTART': '1', 18:21:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:09 INFO - 'PWD': '/builds/slave/test', 18:21:09 INFO - 'SHELL': '/bin/bash', 18:21:09 INFO - 'SHLVL': '1', 18:21:09 INFO - 'TERM': 'linux', 18:21:09 INFO - 'TMOUT': '86400', 18:21:09 INFO - 'USER': 'cltbld', 18:21:09 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:09 INFO - '_': '/tools/buildbot/bin/python'} 18:21:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:21:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:21:11 INFO - Installing collected packages: mozsystemmonitor 18:21:11 INFO - Running setup.py install for mozsystemmonitor 18:21:11 INFO - Successfully installed mozsystemmonitor 18:21:11 INFO - Cleaning up... 18:21:11 INFO - Return code: 0 18:21:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1acb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c93b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e389c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e35d60>, '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 0x1e36a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e36ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', '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 18:21:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:21:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:21:11 INFO - Using env: {'DISPLAY': ':0', 18:21:11 INFO - 'HOME': '/home/cltbld', 18:21:11 INFO - 'LANG': 'en_US.UTF-8', 18:21:11 INFO - 'LANGUAGE': 'en_US:en', 18:21:11 INFO - 'LOGNAME': 'cltbld', 18:21:11 INFO - 'MAIL': '/var/mail/cltbld', 18:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:11 INFO - 'MOZ_NO_REMOTE': '1', 18:21:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:11 INFO - 'NO_EM_RESTART': '1', 18:21:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:11 INFO - 'PWD': '/builds/slave/test', 18:21:11 INFO - 'SHELL': '/bin/bash', 18:21:11 INFO - 'SHLVL': '1', 18:21:11 INFO - 'TERM': 'linux', 18:21:11 INFO - 'TMOUT': '86400', 18:21:11 INFO - 'USER': 'cltbld', 18:21:11 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:11 INFO - '_': '/tools/buildbot/bin/python'} 18:21:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:12 INFO - Downloading/unpacking jsonschema==2.5.1 18:21:12 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 18:21:12 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 18:21:12 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 18:21:12 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 18:21:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:21:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:21:13 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 18:21:13 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 18:21:13 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 18:21:13 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 18:21:13 INFO - Downloading functools32-3.2.3-2.tar.gz 18:21:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:21:13 INFO - warning: no files found matching '*.txt' 18:21:13 INFO - no previously-included directories found matching 'build' 18:21:13 INFO - no previously-included directories found matching 'dist' 18:21:13 INFO - no previously-included directories found matching '.git*' 18:21:13 INFO - Installing collected packages: jsonschema, functools32 18:21:13 INFO - Running setup.py install for functools32 18:21:13 INFO - warning: no files found matching '*.txt' 18:21:13 INFO - no previously-included directories found matching 'build' 18:21:13 INFO - no previously-included directories found matching 'dist' 18:21:13 INFO - no previously-included directories found matching '.git*' 18:21:13 INFO - Successfully installed jsonschema functools32 18:21:13 INFO - Cleaning up... 18:21:13 INFO - Return code: 0 18:21:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1acb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c93b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e389c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e35d60>, '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 0x1e36a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e36ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', '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 18:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:21:13 INFO - Using env: {'DISPLAY': ':0', 18:21:13 INFO - 'HOME': '/home/cltbld', 18:21:13 INFO - 'LANG': 'en_US.UTF-8', 18:21:13 INFO - 'LANGUAGE': 'en_US:en', 18:21:13 INFO - 'LOGNAME': 'cltbld', 18:21:13 INFO - 'MAIL': '/var/mail/cltbld', 18:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:13 INFO - 'MOZ_NO_REMOTE': '1', 18:21:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:13 INFO - 'NO_EM_RESTART': '1', 18:21:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:13 INFO - 'PWD': '/builds/slave/test', 18:21:13 INFO - 'SHELL': '/bin/bash', 18:21:13 INFO - 'SHLVL': '1', 18:21:13 INFO - 'TERM': 'linux', 18:21:13 INFO - 'TMOUT': '86400', 18:21:13 INFO - 'USER': 'cltbld', 18:21:13 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:13 INFO - '_': '/tools/buildbot/bin/python'} 18:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:21:13 INFO - Cleaning up... 18:21:13 INFO - Return code: 0 18:21:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1acb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c93b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e389c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e35d60>, '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 0x1e36a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e36ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', '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 18:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:21:13 INFO - Using env: {'DISPLAY': ':0', 18:21:13 INFO - 'HOME': '/home/cltbld', 18:21:13 INFO - 'LANG': 'en_US.UTF-8', 18:21:13 INFO - 'LANGUAGE': 'en_US:en', 18:21:13 INFO - 'LOGNAME': 'cltbld', 18:21:13 INFO - 'MAIL': '/var/mail/cltbld', 18:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:13 INFO - 'MOZ_NO_REMOTE': '1', 18:21:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:13 INFO - 'NO_EM_RESTART': '1', 18:21:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:13 INFO - 'PWD': '/builds/slave/test', 18:21:13 INFO - 'SHELL': '/bin/bash', 18:21:13 INFO - 'SHLVL': '1', 18:21:13 INFO - 'TERM': 'linux', 18:21:13 INFO - 'TMOUT': '86400', 18:21:13 INFO - 'USER': 'cltbld', 18:21:13 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:13 INFO - '_': '/tools/buildbot/bin/python'} 18:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:14 INFO - Downloading/unpacking blobuploader==1.2.4 18:21:14 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 18:21:14 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 18:21:14 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 18:21:14 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 18:21:15 INFO - Downloading blobuploader-1.2.4.tar.gz 18:21:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:21:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:21:15 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 18:21:15 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 18:21:15 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 18:21:15 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 18:21:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:21:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:21:16 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 18:21:16 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 18:21:16 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 18:21:16 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 18:21:16 INFO - Downloading docopt-0.6.1.tar.gz 18:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:21:16 INFO - Installing collected packages: blobuploader, requests, docopt 18:21:16 INFO - Running setup.py install for blobuploader 18:21:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:21:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:21:16 INFO - Running setup.py install for requests 18:21:17 INFO - Running setup.py install for docopt 18:21:18 INFO - Successfully installed blobuploader requests docopt 18:21:18 INFO - Cleaning up... 18:21:19 INFO - Return code: 0 18:21:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21:19 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')]} 18:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:19 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')]} 18:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:19 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 0x1acb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c93b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e389c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e35d60>, '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 0x1e36a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e36ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', '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 18:21:19 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 18:21:19 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 18:21:19 INFO - Using env: {'DISPLAY': ':0', 18:21:19 INFO - 'HOME': '/home/cltbld', 18:21:19 INFO - 'LANG': 'en_US.UTF-8', 18:21:19 INFO - 'LANGUAGE': 'en_US:en', 18:21:19 INFO - 'LOGNAME': 'cltbld', 18:21:19 INFO - 'MAIL': '/var/mail/cltbld', 18:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:19 INFO - 'MOZ_NO_REMOTE': '1', 18:21:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:19 INFO - 'NO_EM_RESTART': '1', 18:21:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:19 INFO - 'PWD': '/builds/slave/test', 18:21:19 INFO - 'SHELL': '/bin/bash', 18:21:19 INFO - 'SHLVL': '1', 18:21:19 INFO - 'TERM': 'linux', 18:21:19 INFO - 'TMOUT': '86400', 18:21:19 INFO - 'USER': 'cltbld', 18:21:19 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:19 INFO - '_': '/tools/buildbot/bin/python'} 18:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:21:19 INFO - Running setup.py (path:/tmp/pip-51Il2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:21:19 INFO - Running setup.py (path:/tmp/pip-sGtXGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:21:19 INFO - Running setup.py (path:/tmp/pip-KEmkrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:21:19 INFO - Running setup.py (path:/tmp/pip-nSFqlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:21:19 INFO - Running setup.py (path:/tmp/pip-IxFZ89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:21:19 INFO - Running setup.py (path:/tmp/pip-B0zxYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:21:19 INFO - Running setup.py (path:/tmp/pip-V6xj_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:21:19 INFO - Running setup.py (path:/tmp/pip-rmtary-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:21:19 INFO - Running setup.py (path:/tmp/pip-oi5jel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:21:19 INFO - Running setup.py (path:/tmp/pip-amjfym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:21:19 INFO - Running setup.py (path:/tmp/pip-LlFgTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:21:20 INFO - Running setup.py (path:/tmp/pip-r45KbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:21:20 INFO - Running setup.py (path:/tmp/pip-l_wxoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:21:20 INFO - Running setup.py (path:/tmp/pip-DTW47h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:20 INFO - Running setup.py (path:/tmp/pip-3YoFar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:21:20 INFO - Running setup.py (path:/tmp/pip-2dqgyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:21:20 INFO - Running setup.py (path:/tmp/pip-8IPULS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:21:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:21:20 INFO - Running setup.py install for manifestparser 18:21:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:21:20 INFO - Running setup.py install for mozcrash 18:21:20 INFO - Running setup.py install for mozdebug 18:21:20 INFO - Running setup.py install for mozdevice 18:21:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:21:20 INFO - Running setup.py install for mozfile 18:21:20 INFO - Running setup.py install for mozhttpd 18:21:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Running setup.py install for mozinfo 18:21:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Running setup.py install for mozInstall 18:21:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Running setup.py install for mozleak 18:21:21 INFO - Running setup.py install for mozlog 18:21:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Running setup.py install for moznetwork 18:21:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Running setup.py install for mozprocess 18:21:21 INFO - Running setup.py install for mozprofile 18:21:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Running setup.py install for mozrunner 18:21:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:21:21 INFO - Running setup.py install for mozscreenshot 18:21:21 INFO - Running setup.py install for moztest 18:21:22 INFO - Running setup.py install for mozversion 18:21:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:21:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:21:22 INFO - Cleaning up... 18:21:22 INFO - Return code: 0 18:21:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21:22 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')]} 18:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:22 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')]} 18:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:22 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 0x1acb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c93b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e389c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e35d60>, '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 0x1e36a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e36ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', '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 18:21:22 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 18:21:22 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 18:21:22 INFO - Using env: {'DISPLAY': ':0', 18:21:22 INFO - 'HOME': '/home/cltbld', 18:21:22 INFO - 'LANG': 'en_US.UTF-8', 18:21:22 INFO - 'LANGUAGE': 'en_US:en', 18:21:22 INFO - 'LOGNAME': 'cltbld', 18:21:22 INFO - 'MAIL': '/var/mail/cltbld', 18:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:22 INFO - 'MOZ_NO_REMOTE': '1', 18:21:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:22 INFO - 'NO_EM_RESTART': '1', 18:21:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:22 INFO - 'PWD': '/builds/slave/test', 18:21:22 INFO - 'SHELL': '/bin/bash', 18:21:22 INFO - 'SHLVL': '1', 18:21:22 INFO - 'TERM': 'linux', 18:21:22 INFO - 'TMOUT': '86400', 18:21:22 INFO - 'USER': 'cltbld', 18:21:22 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:22 INFO - '_': '/tools/buildbot/bin/python'} 18:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:21:22 INFO - Running setup.py (path:/tmp/pip-vN61uW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:21:22 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)) 18:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:21:22 INFO - Running setup.py (path:/tmp/pip-rMBsse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:21:22 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)) 18:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:21:22 INFO - Running setup.py (path:/tmp/pip-uAMUkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:21:22 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)) 18:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:21:22 INFO - Running setup.py (path:/tmp/pip-AtfZm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:21:22 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)) 18:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:21:22 INFO - Running setup.py (path:/tmp/pip-owjaFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:21:22 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)) 18:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:21:22 INFO - Running setup.py (path:/tmp/pip-MyQcIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:21:22 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)) 18:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:21:22 INFO - Running setup.py (path:/tmp/pip-z_yErG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:21:22 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)) 18:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:21:22 INFO - Running setup.py (path:/tmp/pip-iuYjLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:21:22 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)) 18:21:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:21:22 INFO - Running setup.py (path:/tmp/pip-3B89BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:21:23 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)) 18:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:21:23 INFO - Running setup.py (path:/tmp/pip-yFYDxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:21:23 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)) 18:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:21:23 INFO - Running setup.py (path:/tmp/pip-sGvtmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:21:23 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)) 18:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:21:23 INFO - Running setup.py (path:/tmp/pip-ZecoRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:21:23 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)) 18:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:21:23 INFO - Running setup.py (path:/tmp/pip-GzuE7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:21:23 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)) 18:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:21:23 INFO - Running setup.py (path:/tmp/pip-LneqUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:21:23 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)) 18:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:23 INFO - Running setup.py (path:/tmp/pip-Dq_8De-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:23 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)) 18:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:21:23 INFO - Running setup.py (path:/tmp/pip-Ux6iVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:21:23 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)) 18:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:21:23 INFO - Running setup.py (path:/tmp/pip-3aa7k5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:21:23 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)) 18:21:23 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)) 18:21:23 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)) 18:21:23 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)) 18:21:23 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)) 18:21:23 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)) 18:21:23 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)) 18:21:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:21: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 18:21: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 18:21: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 18:21: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 18:21:24 INFO - Downloading blessings-1.6.tar.gz 18:21:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:21:24 INFO - Installing collected packages: blessings 18:21:24 INFO - Running setup.py install for blessings 18:21:24 INFO - Successfully installed blessings 18:21:24 INFO - Cleaning up... 18:21:24 INFO - Return code: 0 18:21:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:21:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:21:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:21:25 INFO - Reading from file tmpfile_stdout 18:21:25 INFO - Current package versions: 18:21:25 INFO - argparse == 1.2.1 18:21:25 INFO - blessings == 1.6 18:21:25 INFO - blobuploader == 1.2.4 18:21:25 INFO - docopt == 0.6.1 18:21:25 INFO - functools32 == 3.2.3-2 18:21:25 INFO - jsonschema == 2.5.1 18:21:25 INFO - manifestparser == 1.1 18:21:25 INFO - mozInstall == 1.13 18:21:25 INFO - mozcrash == 1.0 18:21:25 INFO - mozdebug == 0.1 18:21:25 INFO - mozdevice == 0.51 18:21:25 INFO - mozfile == 1.2 18:21:25 INFO - mozhttpd == 0.7 18:21:25 INFO - mozinfo == 0.10 18:21:25 INFO - mozleak == 0.1 18:21:25 INFO - mozlog == 3.5 18:21:25 INFO - moznetwork == 0.27 18:21:25 INFO - mozprocess == 0.25 18:21:25 INFO - mozprofile == 0.28 18:21:25 INFO - mozrunner == 6.13 18:21:25 INFO - mozscreenshot == 0.1 18:21:25 INFO - mozsystemmonitor == 0.3 18:21:25 INFO - moztest == 0.8 18:21:25 INFO - mozversion == 1.4 18:21:25 INFO - psutil == 3.1.1 18:21:25 INFO - requests == 1.2.3 18:21:25 INFO - wsgiref == 0.1.2 18:21:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21: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')]} 18:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21: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')]} 18:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:25 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 0x1acb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c93b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e389c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e35d60>, '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 0x1e36a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e36ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', '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 18:21:25 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 18:21:25 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 18:21:25 INFO - Using env: {'DISPLAY': ':0', 18:21:25 INFO - 'HOME': '/home/cltbld', 18:21:25 INFO - 'LANG': 'en_US.UTF-8', 18:21:25 INFO - 'LANGUAGE': 'en_US:en', 18:21:25 INFO - 'LOGNAME': 'cltbld', 18:21:25 INFO - 'MAIL': '/var/mail/cltbld', 18:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:25 INFO - 'MOZ_NO_REMOTE': '1', 18:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:25 INFO - 'NO_EM_RESTART': '1', 18:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:25 INFO - 'PWD': '/builds/slave/test', 18:21:25 INFO - 'SHELL': '/bin/bash', 18:21:25 INFO - 'SHLVL': '1', 18:21:25 INFO - 'TERM': 'linux', 18:21:25 INFO - 'TMOUT': '86400', 18:21:25 INFO - 'USER': 'cltbld', 18:21:25 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:25 INFO - '_': '/tools/buildbot/bin/python'} 18:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:25 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)) 18:21:25 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)) 18:21:25 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)) 18:21:25 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)) 18:21:25 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)) 18:21:25 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)) 18:21:25 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)) 18:21:25 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)) 18:21:25 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)) 18:21:25 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:21: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 18:21: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 18:21: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 18:21: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 18:21:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:21:26 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)) 18:21:26 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)) 18:21:26 INFO - Installing collected packages: simplejson 18:21:26 INFO - Running setup.py install for simplejson 18:21:26 INFO - building 'simplejson._speedups' extension 18:21:26 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 18:21:27 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 18:21:27 INFO - Successfully installed simplejson 18:21:27 INFO - Cleaning up... 18:21:27 INFO - Return code: 0 18:21:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:21: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')]} 18:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21: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')]} 18:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:27 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 0x1acb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c93b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e389c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e35d60>, '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 0x1e36a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e36ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', '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 18:21:27 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 18:21:27 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 18:21:27 INFO - Using env: {'DISPLAY': ':0', 18:21:27 INFO - 'HOME': '/home/cltbld', 18:21:27 INFO - 'LANG': 'en_US.UTF-8', 18:21:27 INFO - 'LANGUAGE': 'en_US:en', 18:21:27 INFO - 'LOGNAME': 'cltbld', 18:21:27 INFO - 'MAIL': '/var/mail/cltbld', 18:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:27 INFO - 'MOZ_NO_REMOTE': '1', 18:21:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:27 INFO - 'NO_EM_RESTART': '1', 18:21:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:27 INFO - 'PWD': '/builds/slave/test', 18:21:27 INFO - 'SHELL': '/bin/bash', 18:21:27 INFO - 'SHLVL': '1', 18:21:27 INFO - 'TERM': 'linux', 18:21:27 INFO - 'TMOUT': '86400', 18:21:27 INFO - 'USER': 'cltbld', 18:21:27 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:27 INFO - '_': '/tools/buildbot/bin/python'} 18:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:21:27 INFO - Cleaning up... 18:21:27 INFO - Return code: 0 18:21:27 INFO - Running post-action listener: _resource_record_post_action 18:21:27 INFO - Running post-action listener: _start_resource_monitoring 18:21:27 INFO - Starting resource monitoring. 18:21:27 INFO - [mozharness: 2017-09-28 01:21:27.885977Z] Finished create-virtualenv step (success) 18:21:27 INFO - [mozharness: 2017-09-28 01:21:27.886489Z] Running install step. 18:21:27 INFO - Running pre-action listener: _resource_record_pre_action 18:21:27 INFO - Running main action method: install 18:21:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:21:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:21:28 INFO - Reading from file tmpfile_stdout 18:21:28 INFO - Detecting whether we're running mozinstall >=1.0... 18:21:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:21:28 INFO - Reading from file tmpfile_stdout 18:21:28 INFO - Output received: 18:21:28 INFO - Usage: mozinstall [options] installer 18:21:28 INFO - Options: 18:21:28 INFO - -h, --help show this help message and exit 18:21:28 INFO - -d DEST, --destination=DEST 18:21:28 INFO - Directory to install application into. [default: 18:21:28 INFO - "/builds/slave/test"] 18:21:28 INFO - --app=APP Application being installed. [default: firefox] 18:21:28 INFO - mkdir: /builds/slave/test/build/application 18:21:28 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'] 18:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:21:45 INFO - Reading from file tmpfile_stdout 18:21:45 INFO - Output received: 18:21:45 INFO - /builds/slave/test/build/application/firefox/firefox 18:21:45 INFO - Running post-action listener: _resource_record_post_action 18:21:45 INFO - [mozharness: 2017-09-28 01:21:45.834411Z] Finished install step (success) 18:21:45 INFO - [mozharness: 2017-09-28 01:21:45.834877Z] Running setup-mitmproxy step. 18:21:45 INFO - Running pre-action listener: _resource_record_pre_action 18:21:45 INFO - Running main action method: setup_mitmproxy 18:21:45 INFO - Skipping: mitmproxy is not required 18:21:45 INFO - Running post-action listener: _resource_record_post_action 18:21:45 INFO - [mozharness: 2017-09-28 01:21:45.836484Z] Finished setup-mitmproxy step (success) 18:21:45 INFO - [mozharness: 2017-09-28 01:21:45.836812Z] Running run-tests step. 18:21:45 INFO - Running pre-action listener: _resource_record_pre_action 18:21:45 INFO - Running pre-action listener: _set_gcov_prefix 18:21:45 INFO - Running main action method: run_tests 18:21:45 WARNING - Try message not found. 18:21:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:21:45 INFO - Python 2.7.3 18:21:45 INFO - Return code: 0 18:21:45 INFO - grabbing minidump binary from tooltool 18:21:45 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')]} 18:21:45 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 0x1e35d60>, '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 0x1e36a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e36ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:21:45 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 18:21:45 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 18:21:45 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 18:21:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:21:45 INFO - Return code: 0 18:21:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:21:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:21:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:21:45 INFO - ENV: RUST_BACKTRACE is now full 18:21:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:21:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:21:45 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/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:21:45 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/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:21:45 INFO - Using env: {'DISPLAY': ':0', 18:21:45 INFO - 'HOME': '/home/cltbld', 18:21:45 INFO - 'LANG': 'en_US.UTF-8', 18:21:45 INFO - 'LANGUAGE': 'en_US:en', 18:21:45 INFO - 'LOGNAME': 'cltbld', 18:21:45 INFO - 'MAIL': '/var/mail/cltbld', 18:21:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:21:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:45 INFO - 'MOZ_NO_REMOTE': '1', 18:21:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:21:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:45 INFO - 'NO_EM_RESTART': '1', 18:21:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:45 INFO - 'PWD': '/builds/slave/test', 18:21:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:21:45 INFO - 'RUST_BACKTRACE': 'full', 18:21:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:21:45 INFO - 'SHELL': '/bin/bash', 18:21:45 INFO - 'SHLVL': '1', 18:21:45 INFO - 'TERM': 'linux', 18:21:45 INFO - 'TMOUT': '86400', 18:21:45 INFO - 'USER': 'cltbld', 18:21:45 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:45 INFO - '_': '/tools/buildbot/bin/python'} 18:21:45 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/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:21:46 INFO - mozversion application_buildid: 20170927235256 18:21:46 INFO - mozversion application_changeset: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 18:21:46 INFO - mozversion application_display_name: Nightly 18:21:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:21:46 INFO - mozversion application_name: Firefox 18:21:46 INFO - mozversion application_remotingname: firefox 18:21:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:21:46 INFO - mozversion application_vendor: Mozilla 18:21:46 INFO - mozversion application_version: 58.0a1 18:21:46 INFO - mozversion platform_buildid: 20170927235256 18:21:46 INFO - mozversion platform_changeset: 5ebe2e8980c6fd3ede2b6617bbbc4073dd9b0369 18:21:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:21:46 INFO - mozversion platform_version: 58.0a1 18:21:46 INFO - using testdate: 1506561706 18:21:46 INFO - actual date: 1506561706 18:21:46 INFO - starting webserver on 'localhost:53164' 18:21:46 INFO - SUITE-START | Running 2 tests 18:21:46 INFO - TEST-START | dromaeo_css 18:21:46 INFO - Initialising browser for dromaeo_css test... 18:21:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53164/getInfo.html -profile /tmp/tmp9bLNBZ/profile 18:21:46 INFO - TEST-INFO | started process 1835 (/builds/slave/test/build/application/firefox/firefox http://localhost:53164/getInfo.html) 18:21:52 INFO - TEST-INFO | 1835: exit 0 18:21:52 INFO - Browser initialized. 18:21:52 INFO - Running cycle 1/1 for dromaeo_css test... 18:21:52 INFO - Using env: {'DISPLAY': ':0', 18:21:52 INFO - 'HOME': '/home/cltbld', 18:21:52 INFO - 'JSGC_DISABLE_POISONING': '1', 18:21:52 INFO - 'LANG': 'en_US.UTF-8', 18:21:52 INFO - 'LANGUAGE': 'en_US:en', 18:21:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:21:52 INFO - 'LOGNAME': 'cltbld', 18:21:52 INFO - 'MAIL': '/var/mail/cltbld', 18:21:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:21:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:21:52 INFO - 'MOZ_CRASHREPORTER': '1', 18:21:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:21:52 INFO - 'MOZ_NO_REMOTE': '1', 18:21:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:21:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:52 INFO - 'NO_EM_RESTART': '1', 18:21:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:52 INFO - 'PWD': '/builds/slave/test', 18:21:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:21:52 INFO - 'RUST_BACKTRACE': 'full', 18:21:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:21:52 INFO - 'SHELL': '/bin/bash', 18:21:52 INFO - 'SHLVL': '1', 18:21:52 INFO - 'TERM': 'linux', 18:21:52 INFO - 'TMOUT': '86400', 18:21:52 INFO - 'USER': 'cltbld', 18:21:52 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:21:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:52 INFO - '_': '/tools/buildbot/bin/python'} 18:21:52 INFO - TEST-INFO | started process 2073 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9bLNBZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:53 INFO - PID 2073 | 18:21:53 INFO - PID 2073 | (/builds/slave/test/build/application/firefox/firefox:2123): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:21:53 INFO - PID 2073 | 18:22:03 INFO - PID 2073 | RSS: Main: 144617472 18:22:03 INFO - PID 2073 | 18:25:01 INFO - PID 2073 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53164/tests/dromaeo/cssquery-ext.html) 18:25:02 INFO - PID 2073 | RSS: Main: 143249408 18:25:02 INFO - PID 2073 | 18:28:03 INFO - PID 2073 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-ext.html (next: http://localhost:53164/tests/dromaeo/cssquery-jquery.html) 18:28:04 INFO - PID 2073 | RSS: Main: 144007168 18:28:04 INFO - PID 2073 | 18:31:06 INFO - PID 2073 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53164/tests/dromaeo/cssquery-mootools.html) 18:31:06 INFO - PID 2073 | RSS: Main: 146071552 18:31:06 INFO - PID 2073 | 18:34:08 INFO - PID 2073 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53164/tests/dromaeo/cssquery-prototype.html) 18:34:08 INFO - PID 2073 | RSS: Main: 145268736 18:34:08 INFO - PID 2073 | 18:37:11 INFO - PID 2073 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53164/tests/dromaeo/cssquery-yui.html) 18:37:12 INFO - PID 2073 | RSS: Main: 148631552 18:37:12 INFO - PID 2073 | 18:40:14 INFO - PID 2073 | Cycle 1(1): loaded http://localhost:53164/tests/dromaeo/cssquery-yui.html (next: http://localhost:53164/tests/dromaeo/cssquery-dojo.html) 18:40:14 INFO - PID 2073 | RSS: Main: 149225472 18:40:14 INFO - PID 2073 | 18:40:14 INFO - PID 2073 | __start_tp_report 18:40:14 INFO - PID 2073 | _x_x_mozilla_page_load 18:40:14 INFO - PID 2073 | _x_x_mozilla_page_load_details 18:40:14 INFO - PID 2073 | |i|pagename|runs| 18:40:14 INFO - PID 2073 | |0;dojo.html;3832.674222691071;3840.3855383138703;3853.614638536145;3863.6329548692884;3892.12427203879;6072.392760723926;6171.074338849175;6216.471599914007;6226.813195604133;6301.936980630192;6126.6324020558795;6191.504679625619;6207.689615519217;6219.3780621937785;6240.282367527736;5559.6664200148125;5626.578006649518;5631.352394474627;5641.68970706611;5685.403032681571;5626.634268772537;5629.155626656014;5684.459976302244;5748.741306641211;5763.3948435414095;5455.1544510600925;5552.194931734896;5610.354809196913;5644.74598643062;5663.32040155184;5607.607467477278;5636.605437619368;5671.517920976722;5685.37460879307;5698.572607054488;5500.559955203614;5531.446855314436;5613.2422123013275;5669.433056694339;5677.630953987978;6187.597806142631;6198.535109866778;6222.160008398853;6225.906611400833;6262.937370626281;6664.766733164316;6684.465242780566;6799.558028728117;6804.353586409283;6808.829779255509;6727.125473688438;6745.08941292929;6822.283660215656;6824.692888819965;6830.180378354579;6154.938450615481;6161.106639537287;6214.65819379934;6243.469305109026;6289.8742025159245;6896.7586134485055;6904.378605925491;6984.79045628632;6992.6853291602;6993.160820701549;6587.571807832572;6594.802155935329;6655.168103987002;6703.363180497845;6720.563163394364;6755.459563234929;6796.694148763218;6823.69293381799;6826.795196144124;6836.179658441019;6632.834179145463;6697.196336439659;6774.220964589037;6787.6266805325195;6791.320867913268;6500.252470965805;6596.340365963461;6683.598984060972;6692;6795.150606174228;4900.926486102711;4907.852764417074;4913.533214344631;4914.0908931847725;4972.453030166679;4523.909521809545;4582.747948862844;4587.541245875385;4587.9082418351445;4592.517785632527;6070.529217967717;6147.569670123138;6161.568690191643;6223.564350495421;6240.469560087444;6561.376669216417;6644.036614690843;6721.630310332881;6789.524733268722;6811.795646130624;6127.785527506516;6197.845053873689;6238.906416403757;6245.812625621239;6250.968745156336;5454.427285135083;5510.201020851954;5531.944680553223;5539.14143307788;5549.112142057332;6754.223264324567;6928;6933.479989000845;6958.860822783618;7050.189228238715;6663.267040625527;6688.899666505104;6689.69896354675;6690.0633911253435;6691.263960964193;6883.139607549057;6961.756338528127;6994.2656021117045;7033.859322813412;7037.859242815218;6659.966700166468;6689.331066893272;6702.899456508058;6751.257361690308;6754.527183097332;6540.051692504655;6711.731530738716;6735.292794256533;6826.965865170642;6830.419414349836;6326.936730632819;6373.426391624776;6385.840353991188;6391.3289104643345;6406.103145559532;6456.580322279225;6459.386358295955;6506.642134682638;6547.869042619026;6550.541462097607;6381.93618063832;6384.840378990562;6457.741690332334;6458.967705161444;6480.12518310041;6405.743770249324;6479.676016199265;6488.351164883663;6570.671466426564;6616.669166541557;6390.712417941111;6440.130582371507;6450.290468048604;6459.095726598186;6467.870642587028 18:40:14 INFO - PID 2073 | |1;ext.html;974.6345120579782;1000.0249756487426;1000.4147573669403;1001.3341128149781;1003.7590978165242;42015.15969680605;45240;45664.77167614165;45716.771416142954;45821.31268030982;42881.142377152515;43335.56664433355;43350.7832460838;43358.566414335844;43364;7684.500507467011;7807.609619519029;7811.453198276123;7812.843743125134;8578.099299573549;8678.435901666371;8796.736097917073;8803.20771130598;8817.95591022044;8823.60293786778;8747.343949203803;8821.558922053904;8847.20375166235;8864.290856731446;8864.911350886505;8746.212840844322;8760.518171500566;8811.251043661296;8812.339074569369;8823.470591764482;8638.049814520398;8664.956675216583;8678;8684.956575217147;8728.170823771732;41002.794986025176;41047.589524104675;41197.58802411998;41218.587814121776;41267.1746565067;3730.9626903730887;3783.281176576442;3788.5453745550435;3789.2989796887664;3791.1659434924272;34198.316033679184;34228.48657270143;34441.65558344434;34521.30957380839;34535;5429.83710488682;5457.263269458612;5522.282103326582;5530.419305972855;5542.612017158801;232151;232712;233677;233832;234185;41134.58865411367;55149.72425137849;55181;55193.44806551883;55226.44773552293;42972;57513.71243143841;58151.12773308404;58177.4182258172;58257;17186.656266874594;17282.22229999653;17301.3944511944;17311.913440432716;17314.307427703003;13896.444142234197;13933.721325573431;13955.023148379516;13965.720685586231;13980.72038559223;15768.369265229492;15833.762493562606;15896.284667190004;15906.681866362607;15932.36270549165;6145.8156255312415;6206.596571222856;6231.501479881598;6279.58709290406;6305.716242769086;1084.951177197028;1093.5297821936485;1096.2216826053432;1117.0337657925998;1125.8817824128512;25794.742052579233;26007;26328.868355658484;26347;26477.47045059088;1195.6950977500856;1196.6350263169816;1209.2079687804503;1215.0947544079718;1223.2966044524435;13555.59333220005;13606.251656159006;13680.93159534196;13871.306434678225;13884.51404200848;293010;293998;294073;294121;294808;59910.70044649749;60083.09875351873;60304.698476507336;60392;60429;1496.7904493370936;1504.8194216693848;1527.2058529564786;1527.3814105287377;1531.8851086168581;25261.115860944778;25530.872345638152;25714.485710285317;25738.742612573635;25819.483610328065;41998.790006051;42412;42541.78729106458;42713.359299611155;42750.35874461948;248212;249006;249071;249345;250977;37029.25941481209;39350.60649393584;39881.40177897276;40285.59714402819;40311.1937761249;51548.48451515437;52366.47633523616;52422;52800.47199527956;53213.467865320854;37805.24389512249;37963.43054854235;38128;38135.42796857994;38733.2253354937;37922.24155516819;38003;38161.23677526489;38335.42496862494;38358;44854.3271850929;44865.10269794651;45010.77494612506;45023;45445.54554454546;36300.63699362973;36323.81838090898;36585.817070914476;36666;36707;27530.218654082866;28249.43501130007;28304.292392690346;28307.716922831332;28314.43371132607 18:40:14 INFO - PID 2073 | |2;jquery.html;593.7001814083887;608.14859197124;627.5168120547178;634.1724050114605;658.2002866517182;23041.769582304172;23671;24431.511369772594;24564.508709825815;24607.384815379624;25521.489570208632;26590.202293931212;26615.733842661568;26668.59997100045;26707.19878403646;6471.385218404256;6513.185851768529;6531.0856480092825;6538.771143009995;6546.672666366687;6679.833004174886;6897.48268879836;6909.447244220451;6918.5502942308585;6919.481038922076;6881.862362752742;6885.036094946711;6887.139107611549;6887.5178737488395;6888.414484768776;6607.075009498674;6875.621840798754;6885.621290829003;6895.965520172367;6900.861982760342;6392.4886009119155;6393.9360606394275;6416.165898433173;6418.582792118544;6428.035794630796;30335.393292134035;30526.54210186849;30897;30917.38165236683;30924.381512369626;2264.3320220534983;2268.5122698619875;2293.7362203346497;2300.344401845483;2304.089884495615;20249.29127480546;20975.580488390147;20994.685079723815;21058.368248952404;21075.68386474204;2504.198656429943;2558.1302357198456;2617.738226177386;2618.973810261892;2632.723564025788;142277;148668;163234;164095;170955;16125.354985800672;16808.411705590246;16841.747373789403;16978.471937525683;17250.913745431193;27008.86495567549;27058.2412143214;27084;27126.321841954108;27190;10873.891261087447;10934.507947142398;11014.191381197903;11017.724556886142;11229.494672739584;10084.394936303845;10121.544530496145;10162.39025658463;10172.440515771703;10211.897881021094;9737.123658870594;9894.80210395788;10050.798984020425;10232.386056836614;10254.846177307347;2821.4216085702383;2831.2497188245134;2883.2215301868378;2953.4536110819568;2993.0721476342405;1051.1433181956681;1063.0910571461443;1077.2190162132392;1077.7575045614674;1080.340991994898;21255;21507;21633.675494867588;21686.674699879513;21748;1126.4255229832845;1140.5323817234894;1144.6508814811395;1144.853399875234;1161.4436654882497;11763.588274410526;11826;11950.701232469259;11961.282323060645;12050.09624278165;636504;645646;657083;657711;664474;130964.34517827349;133442.33278833545;135317.3234133823;136096.31951840178;136248.31875840557;1216.3735676126803;1236.1470585296117;1268.2517314784507;1272.5228039485194;1274.9426275817611;18581.90709046446;18866.3396781112;18906.716399254296;18917.337893173673;18965.620687585895;63396.68301658647;64553;64888.675556623806;65107;67306;78283.60858195672;78392.60803695946;79171.20828791638;79735;80151;15716.842831571996;16061.357545698042;16133.596660083593;16205.513834584981;16241.350345986028;24469.388265292797;24470.388240294134;24657.383565411008;24777.87611062005;24833.627495587218;19802.108905099016;19944.90027549853;19953.20187192496;20021.599568008267;20285.391438256876;17568.912155439142;17878.374256900945;17999.10004499796;18200.817991819913;18292.45122646323;19872.10575524076;19972.400827975183;20138.69791953151;20166.59666806685;20174.49563760859;14846;15714.9214253928;15791.921040395184;15815.209239537748;15877.52367428979;19277.518062048;19615;19706.8029319705;19801.801981979996;19884.50288742793 18:40:14 INFO - PID 2073 | |3;mootools.html;1379.8827099696525;1481.8666320031195;1486.5688950204442;1487.8512148785123;1499.730048591253;16625.418110366125;17367.739483907753;17402.607791376708;17409;17448.738268925943;16693.66612667749;16794.496165115037;16798.32806687734;16804.495865124034;16834.410795622156;2689.072270066824;2707.0795929384008;2737.86310684466;2758.7379198976064;2764.557670772677;2843.9289017774618;2857.8856845726145;2870.512012957802;2910.621619189513;2911.548709949961;2827.575863620463;2828.5898544711004;2930.824150550963;2955.290730224752;2956.408718256357;2873.54023356262;2879.784016198783;2894.4645240630443;2909.767218622505;2911.2139722274974;2678.879450424716;2774.056820680978;2830.6320178376677;2867.3261783480866;2871.181713211725;16762.32950681971;16882.74675879863;16884.915575422045;16893.662126757517;16895.49313520584;7887.132415434297;8221.671133154667;8225.177482251787;8227.794305142359;8229.46508476947;5385.379154996343;5525.11598144295;5573.916391254134;5579.163125531162;5618.887622247573;2092.8221101206414;2129.350548082834;2145.281330754199;2153.569286142778;2200.7359116905914;31338.37323253545;31522.842385788153;31525;31599.210019749462;31629.841850790828;7379.926200738031;7446.292602202782;7471.178170401252;7624.313811756969;7645.235476452311;20177.697334539993;20185.59628807445;20286.89856550708;20376.184952602027;20382.796172038386;8615.483071015758;8783.385163038525;8787.824243515093;8789.296856251485;8791.296696264284;7820.726274580363;7910.723124690723;8011;8057.59712014397;8077.232662897015;5838.416158384128;5903.0555111182;5934.13954976526;5947.851303717355;5973.910391344133;1756.3852651571847;1790.5523619095227;1791.1402526787215;1798.2896755781394;1799.4601619514094;958.8705524754068;983.6999715086824;985.5959056786826;1013.8732658417698;1019.8062368150028;8436.694888648495;8543.829123417498;8588.785280368042;8680.262177714965;8715.782105447415;781.8553567590014;808.9676412943417;816.77946954322;817.965273928481;827.7765003449035;5165.9225111623355;5313.123334649817;5860.70696465175;5883.882322353529;6022.337542870281;23928.88035559811;24572.877135614566;25394;25749.3562660935;25824;23615;24413.877930610233;24665.753342466345;24672.876635616707;24866.627000594643;12305.876941230652;12354.135210535356;12594.362732844855;12666.36668166588;12670.556530521388;9356.532173391439;9536.046395360408;9552.474613896302;9594.856077158987;9639.277054221102;20346.796532034492;20421.48946276355;20422.79577204209;20796.1681532737;21302.7869721307;21099.683504747132;21262.043208055366;21469.355919322446;21554.892225538773;22621.773782262626;12195.573154939535;12225.388730563258;12290.262584244974;12301.138920275494;12308.323042232763;14479.276036197938;14589.124652520883;14626.707465850835;14785.630359241106;14891.03208291501;12461.1900226485;12645.93677031609;12677.936610316889;12678.809817852554;12744.299063551594;11536.538538458368;11570.479328430072;11663.88336116628;11841.46713397882;11898.881011189776;10448.582056717647;10733.409662468639;10814.188935829687;10818.567257309935;10826.621068262888;8399.664013439638;8480.872786908078;8567.571621419029;8623.568821559022;8640.092790257184;8247.628856701584;8375.916240837758;8402.033766116974;8422.284105851071;8504.617292221741 18:40:14 INFO - PID 2073 | |4;prototype.html;562.8198976327577;569.4561693582629;573.549763435703;578.4967078641582;588.1031427073713;6196.845078873031;6205.317415084337;6226.43962043416;6235.501159907208;6251.7499300028;6166.136740856284;6175.228096487939;6209.316975132731;6217.129601855732;6275.780347687821;5106.387233531981;5118.513741194581;5150.459201783815;5166.819161329354;5168.457311982244;5209.218617207412;5225.346831646044;5264.578833693296;5265.9473405266035;5266.947330526684;5204.193350030751;5228.947710522903;5247.819240670857;5247.947520524803;5281.366236051679;5234.764435600388;5237.292965449673;5252.921206181911;5265.289185959923;5268.710220937847;4908.680935739166;4948.529889660476;4972.105021096201;4985.930838701935;4986.800527978877;6160.0759886017;6239.500839932794;6266;6353.269374022;6361.872762544769;6164.7534098636;6165.506759459233;6220.222472190976;6264;6265.530085243625;6764.898526522106;6787.185537735503;6795.592264464148;6800.489963252776;6804.251532331439;5228.111221092423;5447.428020057877;5504.862378440571;5547.583931205176;5548.001359755242;6704.396604305636;6741.157355330583;6855.211650660188;6911.861762764716;6917.619530925797;5672.426371386083;5681.545476361881;5686.943130568724;5699.914501282484;5701.543876489871;5757;5781.971090144607;5784.681842498619;5796.159556864315;5800.622959507618;6163;6187.628742275478;6204.720787564571;6225.750969961241;6247;6746.257911629717;6746.650669866046;6794.218664853605;6810.455163586901;6829.521933464709;1993.8604297699312;2000.0199902048182;2006.6388050150822;2010.0452285164722;2023.8482113841521;1452.561963655973;1458.2854401343266;1499.1005396761855;1523.1470376589145;1542.0978727444347;5882.499987501024;5946.275580081728;5957.791477298274;6035.849103772353;6090.847728806727;6648.102506161703;6712.060311556386;6716.798496045127;6793.762218322335;6796;6125.081237814275;6130.509559235251;6144.9246381882995;6152.692365381802;6189.535784816157;3490.441529355291;3499.440089585705;3553.933819854085;3556.0043187907427;3594.748367614242;6902.171739391205;6907.758228461979;6930.584164950018;6985.6507174641065;6996.055532503149;6924.030635711005;6936.2023367312395;6969.23338432772;6980.267071957473;7018.192907815666;6914.585124892523;6924.515283930076;6943.604456462513;6998.860022799516;7028.964855175692;6542.887709089371;6640.535162538769;6672.797976667785;6686.01891590768;6717.56335838169;6727.360900714424;6748.595084294958;6764.3573860483175;6768.357006084414;6810.012548180387;6860.416864566568;6876.862462750617;6949.30506949301;6990.881458966541;7017.035230135571;5516.668999860021;5621.634593751393;5637.8590535236945;5653.604247702621;5656.886862262814;6469.676516174079;6568;6593.80218593443;6665.266820649627;6729.259781424137;6406.231252249573;6429;6490.7079181436;6498.317676644074;6609.768658096944;6436.910283064062;6480.25477070143;6481.449076828522;6550.803475895731;6554.180727409074;6224.782132625517;6280.403361680633;6321.841953951188;6362.786792669672;6446.935530644633;6397.360263973565;6447.4197322241225;6631.1048008520165;6663.800085997428;6682.59426606431;6348.555601107878;6390.90413643805;6403.391677790602;6406.967965160145;6430.7749228776765 18:40:14 INFO - PID 2073 | |5;yui.html;956.8995255498172;960.8222478841413;974.0503009565674;985.0543478260869;990.163312001359;19252;19762.802371976275;19929.40211793645;19953.501162470944;20005.499862503446;18894;19065.046747662596;19089.90455047723;19105.52236194096;19113.90443047783;1045.8483519889612;1066.648006157966;1071.2982996137541;1072.1208608940672;1073.2540884085563;1049.8110340138771;1051.5846240734904;1051.984220236697;1052.384355152233;1053.0680347892094;1095.9232853700244;1113.2930589075913;1137.7838210739974;1138.0554140063769;1141.8401423800674;1113.4544073404015;1115.1970581181536;1119.6585041562362;1120.5349779841356;1125.1561329003248;785.9135495095535;812.9390295727844;814.6700586262573;815.0260438775665;825.793551612097;20592.691109633368;20727.689084663743;20841.478963026046;21063.052162652562;21068.26261080855;21758.347249582537;22027.449313767123;22310.442238944157;22467.887660561755;22507.21224757142;17625.294988200458;17960.01219932903;18260;18261.54346141344;18357.724634130365;3894.5131858517684;3929.9978505481204;3930.0960779020697;3930.1746633207063;3932.0563064864514;138455;166189;169645.15177424156;173866;174604;18525.53686157857;18816.341428049953;18940;19009.14458849355;19028.80971190298;33406.83296583501;33447.331053378795;33868.32263354769;33988.66011339904;34065.48901766475;9585.520723963775;9736.415815050977;9801.215902727765;9815.263855210887;9835.164011058998;4482.193205223026;4616.60758835496;4628.861134165981;4630.351750754897;4630.444346678353;5952.880942381128;6056.848578785478;6072.969635151796;6090.756369745249;6094.329623741385;1192.2548407245472;1196.5871774237874;1198.568515334479;1205.6202296276645;1215.471269997554;713.5968177979425;731.7438896386328;740.8592367450167;740.9481336306515;765.9578723170167;12519.874801252054;12822.551210707581;12924.870751292367;12964.22214667123;13339.665558527218;680.4148432348177;687.9449644028466;699.0527834783896;701.8070030741485;704.8555046215616;6036.275646922398;6121.204243448412;6123.785667501616;6185.84535386619;6221.9377806222265;154598;155400;156077;156754;159006;106394;110484;129922;135635.32182339026;142069;118213;119804;121809.39095304467;123094.38452807679;123977;12220.633380998584;12230.75538489243;12367.62897113088;12456.12807103494;12829;54718.45281547133;56463.71768141297;56800.715996419756;57031.14453283121;57764;68843.31156688368;69689;69813;70281;71340.28659713337;19579.902100489406;19919.701204481655;20001.799982000575;20111.79888201158;20132;21436.785632144103;21733.34799956004;21850.12599496066;21869.562608748056;21999.670004950258;18944.147513361655;19159.137838797007;19348.4195474136;19484.805151948298;19487.415377538695;19953.800461995776;20025;20041.39875803728;20094.89952550228;20170.69743953871;18789.624207516048;18886.622267554296;18893.433197004113;19001.80998190056;19219.423417297505;14658.19379934114;14663.56009319722;14682;14723.411063557765;14870.033447825857;13401.798973015608;13478.123921945042;13524.797128043285;13527.25600091965;13724.039317247696 18:40:14 INFO - PID 2073 | __end_tp_report 18:40:14 INFO - PID 2073 | __start_cc_report 18:40:14 INFO - PID 2073 | _x_x_mozilla_cycle_collect,270 18:40:14 INFO - PID 2073 | __end_cc_report 18:40:14 INFO - PID 2073 | __startTimestamp1506562814326__endTimestamp 18:40:14 INFO - PID 2073 | 18:40:14 INFO - PID 2073 | ------- Summary: start ------- 18:40:14 INFO - PID 2073 | Number of tests: 6 18:40:14 INFO - PID 2073 | 18:40:14 INFO - PID 2073 | [#0] dojo.html Cycles:165 Average:6199.80 Median:6405.74 stddev:714.63 (11.2%) stddev-sans-first:692.27 18:40:14 INFO - PID 2073 | Values: 3832.7 3840.4 3853.6 3863.6 3892.1 6072.4 6171.1 6216.5 6226.8 6301.9 6126.6 6191.5 6207.7 6219.4 6240.3 5559.7 5626.6 5631.4 5641.7 5685.4 5626.6 5629.2 5684.5 5748.7 5763.4 5455.2 5552.2 5610.4 5644.7 5663.3 5607.6 5636.6 5671.5 5685.4 5698.6 5500.6 5531.4 5613.2 5669.4 5677.6 6187.6 6198.5 6222.2 6225.9 6262.9 6664.8 6684.5 6799.6 6804.4 6808.8 6727.1 6745.1 6822.3 6824.7 6830.2 6154.9 6161.1 6214.7 6243.5 6289.9 6896.8 6904.4 6984.8 6992.7 6993.2 6587.6 6594.8 6655.2 6703.4 6720.6 6755.5 6796.7 6823.7 6826.8 6836.2 6632.8 6697.2 6774.2 6787.6 6791.3 6500.3 6596.3 6683.6 6692.0 6795.2 4900.9 4907.9 4913.5 4914.1 4972.5 4523.9 4582.7 4587.5 4587.9 4592.5 6070.5 6147.6 6161.6 6223.6 6240.5 6561.4 6644.0 6721.6 6789.5 6811.8 6127.8 6197.8 6238.9 6245.8 6251.0 5454.4 5510.2 5531.9 5539.1 5549.1 6754.2 6928.0 6933.5 6958.9 7050.2 6663.3 6688.9 6689.7 6690.1 6691.3 6883.1 6961.8 6994.3 7033.9 7037.9 6660.0 6689.3 6702.9 6751.3 6754.5 6540.1 6711.7 6735.3 6827.0 6830.4 6326.9 6373.4 6385.8 6391.3 6406.1 6456.6 6459.4 6506.6 6547.9 6550.5 6381.9 6384.8 6457.7 6459.0 6480.1 6405.7 6479.7 6488.4 6570.7 6616.7 6390.7 6440.1 6450.3 6459.1 6467.9 18:40:14 INFO - PID 2073 | 18:40:14 INFO - PID 2073 | [#1] ext.html Cycles:180 Average:44562.06 Median:38148.33 stddev:67618.44 (177.3%) stddev-sans-first:67728.92 18:40:14 INFO - PID 2073 | Values: 974.6 1000.0 1000.4 1001.3 1003.8 42015.2 45240.0 45664.8 45716.8 45821.3 42881.1 43335.6 43350.8 43358.6 43364.0 7684.5 7807.6 7811.5 7812.8 8578.1 8678.4 8796.7 8803.2 8818.0 8823.6 8747.3 8821.6 8847.2 8864.3 8864.9 8746.2 8760.5 8811.3 8812.3 8823.5 8638.0 8665.0 8678.0 8685.0 8728.2 41002.8 41047.6 41197.6 41218.6 41267.2 3731.0 3783.3 3788.5 3789.3 3791.2 34198.3 34228.5 34441.7 34521.3 34535.0 5429.8 5457.3 5522.3 5530.4 5542.6 232151.0 232712.0 233677.0 233832.0 234185.0 41134.6 55149.7 55181.0 55193.4 55226.4 42972.0 57513.7 58151.1 58177.4 58257.0 17186.7 17282.2 17301.4 17311.9 17314.3 13896.4 13933.7 13955.0 13965.7 13980.7 15768.4 15833.8 15896.3 15906.7 15932.4 6145.8 6206.6 6231.5 6279.6 6305.7 1085.0 1093.5 1096.2 1117.0 1125.9 25794.7 26007.0 26328.9 26347.0 26477.5 1195.7 1196.6 1209.2 1215.1 1223.3 13555.6 13606.3 13680.9 13871.3 13884.5 293010.0 293998.0 294073.0 294121.0 294808.0 59910.7 60083.1 60304.7 60392.0 60429.0 1496.8 1504.8 1527.2 1527.4 1531.9 25261.1 25530.9 25714.5 25738.7 25819.5 41998.8 42412.0 42541.8 42713.4 42750.4 248212.0 249006.0 249071.0 249345.0 250977.0 37029.3 39350.6 39881.4 40285.6 40311.2 51548.5 52366.5 52422.0 52800.5 53213.5 37805.2 37963.4 38128.0 38135.4 38733.2 37922.2 38003.0 38161.2 38335.4 38358.0 44854.3 44865.1 45010.8 45023.0 45445.5 36300.6 36323.8 36585.8 36666.0 36707.0 27530.2 28249.4 28304.3 28307.7 28314.4 18:40:14 INFO - PID 2073 | 18:40:14 INFO - PID 2073 | [#2] jquery.html Cycles:180 Average:41601.12 Median:21660.18 stddev:108917.91 (502.8%) stddev-sans-first:109179.93 18:40:14 INFO - PID 2073 | Values: 593.7 608.1 627.5 634.2 658.2 23041.8 23671.0 24431.5 24564.5 24607.4 25521.5 26590.2 26615.7 26668.6 26707.2 6471.4 6513.2 6531.1 6538.8 6546.7 6679.8 6897.5 6909.4 6918.6 6919.5 6881.9 6885.0 6887.1 6887.5 6888.4 6607.1 6875.6 6885.6 6896.0 6900.9 6392.5 6393.9 6416.2 6418.6 6428.0 30335.4 30526.5 30897.0 30917.4 30924.4 2264.3 2268.5 2293.7 2300.3 2304.1 20249.3 20975.6 20994.7 21058.4 21075.7 2504.2 2558.1 2617.7 2619.0 2632.7 142277.0 148668.0 163234.0 164095.0 170955.0 16125.4 16808.4 16841.7 16978.5 17250.9 27008.9 27058.2 27084.0 27126.3 27190.0 10873.9 10934.5 11014.2 11017.7 11229.5 10084.4 10121.5 10162.4 10172.4 10211.9 9737.1 9894.8 10050.8 10232.4 10254.8 2821.4 2831.2 2883.2 2953.5 2993.1 1051.1 1063.1 1077.2 1077.8 1080.3 21255.0 21507.0 21633.7 21686.7 21748.0 1126.4 1140.5 1144.7 1144.9 1161.4 11763.6 11826.0 11950.7 11961.3 12050.1 636504.0 645646.0 657083.0 657711.0 664474.0 130964.3 133442.3 135317.3 136096.3 136248.3 1216.4 1236.1 1268.3 1272.5 1274.9 18581.9 18866.3 18906.7 18917.3 18965.6 63396.7 64553.0 64888.7 65107.0 67306.0 78283.6 78392.6 79171.2 79735.0 80151.0 15716.8 16061.4 16133.6 16205.5 16241.4 24469.4 24470.4 24657.4 24777.9 24833.6 19802.1 19944.9 19953.2 20021.6 20285.4 17568.9 17878.4 17999.1 18200.8 18292.5 19872.1 19972.4 20138.7 20166.6 20174.5 14846.0 15714.9 15791.9 15815.2 15877.5 19277.5 19615.0 19706.8 19801.8 19884.5 18:40:14 INFO - PID 2073 | 18:40:14 INFO - PID 2073 | [#3] mootools.html Cycles:180 Average:10416.97 Median:2748.30 stddev:7731.30 (281.3%) stddev-sans-first:7723.18 18:40:14 INFO - PID 2073 | Values: 1379.9 1481.9 1486.6 1487.9 1499.7 16625.4 17367.7 17402.6 17409.0 17448.7 16693.7 16794.5 16798.3 16804.5 16834.4 2689.1 2707.1 2737.9 2758.7 2764.6 2843.9 2857.9 2870.5 2910.6 2911.5 2827.6 2828.6 2930.8 2955.3 2956.4 2873.5 2879.8 2894.5 2909.8 2911.2 2678.9 2774.1 2830.6 2867.3 2871.2 16762.3 16882.7 16884.9 16893.7 16895.5 7887.1 8221.7 8225.2 8227.8 8229.5 5385.4 5525.1 5573.9 5579.2 5618.9 2092.8 2129.4 2145.3 2153.6 2200.7 31338.4 31522.8 31525.0 31599.2 31629.8 7379.9 7446.3 7471.2 7624.3 7645.2 20177.7 20185.6 20286.9 20376.2 20382.8 8615.5 8783.4 8787.8 8789.3 8791.3 7820.7 7910.7 8011.0 8057.6 8077.2 5838.4 5903.1 5934.1 5947.9 5973.9 1756.4 1790.6 1791.1 1798.3 1799.5 958.9 983.7 985.6 1013.9 1019.8 8436.7 8543.8 8588.8 8680.3 8715.8 781.9 809.0 816.8 818.0 827.8 5165.9 5313.1 5860.7 5883.9 6022.3 23928.9 24572.9 25394.0 25749.4 25824.0 23615.0 24413.9 24665.8 24672.9 24866.6 12305.9 12354.1 12594.4 12666.4 12670.6 9356.5 9536.0 9552.5 9594.9 9639.3 20346.8 20421.5 20422.8 20796.2 21302.8 21099.7 21262.0 21469.4 21554.9 22621.8 12195.6 12225.4 12290.3 12301.1 12308.3 14479.3 14589.1 14626.7 14785.6 14891.0 12461.2 12645.9 12677.9 12678.8 12744.3 11536.5 11570.5 11663.9 11841.5 11898.9 10448.6 10733.4 10814.2 10818.6 10826.6 8399.7 8480.9 8567.6 8623.6 8640.1 8247.6 8375.9 8402.0 8422.3 8504.6 18:40:14 INFO - PID 2073 | 18:40:14 INFO - PID 2073 | [#4] prototype.html Cycles:180 Average:5736.30 Median:6230.97 stddev:1515.78 (24.3%) stddev-sans-first:1469.46 18:40:14 INFO - PID 2073 | Values: 562.8 569.5 573.5 578.5 588.1 6196.8 6205.3 6226.4 6235.5 6251.7 6166.1 6175.2 6209.3 6217.1 6275.8 5106.4 5118.5 5150.5 5166.8 5168.5 5209.2 5225.3 5264.6 5265.9 5266.9 5204.2 5228.9 5247.8 5247.9 5281.4 5234.8 5237.3 5252.9 5265.3 5268.7 4908.7 4948.5 4972.1 4985.9 4986.8 6160.1 6239.5 6266.0 6353.3 6361.9 6164.8 6165.5 6220.2 6264.0 6265.5 6764.9 6787.2 6795.6 6800.5 6804.3 5228.1 5447.4 5504.9 5547.6 5548.0 6704.4 6741.2 6855.2 6911.9 6917.6 5672.4 5681.5 5686.9 5699.9 5701.5 5757.0 5782.0 5784.7 5796.2 5800.6 6163.0 6187.6 6204.7 6225.8 6247.0 6746.3 6746.7 6794.2 6810.5 6829.5 1993.9 2000.0 2006.6 2010.0 2023.8 1452.6 1458.3 1499.1 1523.1 1542.1 5882.5 5946.3 5957.8 6035.8 6090.8 6648.1 6712.1 6716.8 6793.8 6796.0 6125.1 6130.5 6144.9 6152.7 6189.5 3490.4 3499.4 3553.9 3556.0 3594.7 6902.2 6907.8 6930.6 6985.7 6996.1 6924.0 6936.2 6969.2 6980.3 7018.2 6914.6 6924.5 6943.6 6998.9 7029.0 6542.9 6640.5 6672.8 6686.0 6717.6 6727.4 6748.6 6764.4 6768.4 6810.0 6860.4 6876.9 6949.3 6990.9 7017.0 5516.7 5621.6 5637.9 5653.6 5656.9 6469.7 6568.0 6593.8 6665.3 6729.3 6406.2 6429.0 6490.7 6498.3 6609.8 6436.9 6480.3 6481.4 6550.8 6554.2 6224.8 6280.4 6321.8 6362.8 6446.9 6397.4 6447.4 6631.1 6663.8 6682.6 6348.6 6390.9 6403.4 6407.0 6430.8 18:40:14 INFO - PID 2073 | 18:40:14 INFO - PID 2073 | [#5] yui.html Cycles:180 Average:28867.46 Median:19533.66 stddev:43193.59 (221.1%) stddev-sans-first:43263.92 18:40:14 INFO - PID 2073 | Values: 956.9 960.8 974.1 985.1 990.2 19252.0 19762.8 19929.4 19953.5 20005.5 18894.0 19065.0 19089.9 19105.5 19113.9 1045.8 1066.6 1071.3 1072.1 1073.3 1049.8 1051.6 1052.0 1052.4 1053.1 1095.9 1113.3 1137.8 1138.1 1141.8 1113.5 1115.2 1119.7 1120.5 1125.2 785.9 812.9 814.7 815.0 825.8 20592.7 20727.7 20841.5 21063.1 21068.3 21758.3 22027.4 22310.4 22467.9 22507.2 17625.3 17960.0 18260.0 18261.5 18357.7 3894.5 3930.0 3930.1 3930.2 3932.1 138455.0 166189.0 169645.2 173866.0 174604.0 18525.5 18816.3 18940.0 19009.1 19028.8 33406.8 33447.3 33868.3 33988.7 34065.5 9585.5 9736.4 9801.2 9815.3 9835.2 4482.2 4616.6 4628.9 4630.4 4630.4 5952.9 6056.8 6073.0 6090.8 6094.3 1192.3 1196.6 1198.6 1205.6 1215.5 713.6 731.7 740.9 740.9 766.0 12519.9 12822.6 12924.9 12964.2 13339.7 680.4 687.9 699.1 701.8 704.9 6036.3 6121.2 6123.8 6185.8 6221.9 154598.0 155400.0 156077.0 156754.0 159006.0 106394.0 110484.0 129922.0 135635.3 142069.0 118213.0 119804.0 121809.4 123094.4 123977.0 12220.6 12230.8 12367.6 12456.1 12829.0 54718.5 56463.7 56800.7 57031.1 57764.0 68843.3 69689.0 69813.0 70281.0 71340.3 19579.9 19919.7 20001.8 20111.8 20132.0 21436.8 21733.3 21850.1 21869.6 21999.7 18944.1 19159.1 19348.4 19484.8 19487.4 19953.8 20025.0 20041.4 20094.9 20170.7 18789.6 18886.6 18893.4 19001.8 19219.4 14658.2 14663.6 14682.0 14723.4 14870.0 13401.8 13478.1 13524.8 13527.3 13724.0 18:40:14 INFO - PID 2073 | -------- Summary: end -------- 18:40:14 INFO - PID 2073 | 18:40:14 INFO - TEST-INFO | 2073: exit 0 18:40:14 INFO - TEST-OK | dromaeo_css | took 1108608ms 18:40:14 INFO - TEST-START | kraken 18:40:14 INFO - Initialising browser for kraken test... 18:40:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53164/getInfo.html -profile /tmp/tmpffdHB6/profile 18:40:14 INFO - TEST-INFO | started process 2435 (/builds/slave/test/build/application/firefox/firefox http://localhost:53164/getInfo.html) 18:40:21 INFO - TEST-INFO | 2435: exit 0 18:40:21 INFO - Browser initialized. 18:40:21 INFO - Running cycle 1/1 for kraken test... 18:40:21 INFO - Using env: {'DISPLAY': ':0', 18:40:21 INFO - 'HOME': '/home/cltbld', 18:40:21 INFO - 'JSGC_DISABLE_POISONING': '1', 18:40:21 INFO - 'LANG': 'en_US.UTF-8', 18:40:21 INFO - 'LANGUAGE': 'en_US:en', 18:40:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:40:21 INFO - 'LOGNAME': 'cltbld', 18:40:21 INFO - 'MAIL': '/var/mail/cltbld', 18:40:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:40:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:40:21 INFO - 'MOZ_CRASHREPORTER': '1', 18:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:40:21 INFO - 'MOZ_NO_REMOTE': '1', 18:40:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:40:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:40:21 INFO - 'NO_EM_RESTART': '1', 18:40:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:21 INFO - 'PWD': '/builds/slave/test', 18:40:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:40:21 INFO - 'RUST_BACKTRACE': 'full', 18:40:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:40:21 INFO - 'SHELL': '/bin/bash', 18:40:21 INFO - 'SHLVL': '1', 18:40:21 INFO - 'TERM': 'linux', 18:40:21 INFO - 'TMOUT': '86400', 18:40:21 INFO - 'USER': 'cltbld', 18:40:21 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296', 18:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:21 INFO - '_': '/tools/buildbot/bin/python'} 18:40:21 INFO - TEST-INFO | started process 2743 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpffdHB6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:40:22 INFO - PID 2743 | 18:40:22 INFO - PID 2743 | (/builds/slave/test/build/application/firefox/firefox:2795): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:40:22 INFO - PID 2743 | 18:40:32 INFO - PID 2743 | RSS: Main: 141438976 18:40:32 INFO - PID 2743 | 18:40:35 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53164/tests/kraken/driver.html?testName=audio-beat-detection) 18:40:35 INFO - PID 2743 | RSS: Main: 147283968 18:40:35 INFO - PID 2743 | 18:40:38 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53164/tests/kraken/driver.html?testName=audio-dft) 18:40:39 INFO - PID 2743 | RSS: Main: 147271680 18:40:39 INFO - PID 2743 | 18:40:42 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53164/tests/kraken/driver.html?testName=audio-fft) 18:40:42 INFO - PID 2743 | RSS: Main: 143749120 18:40:42 INFO - PID 2743 | 18:40:44 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53164/tests/kraken/driver.html?testName=audio-oscillator) 18:40:45 INFO - PID 2743 | RSS: Main: 144654336 18:40:45 INFO - PID 2743 | 18:40:47 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53164/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:40:48 INFO - PID 2743 | RSS: Main: 144568320 18:40:48 INFO - PID 2743 | 18:40:51 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53164/tests/kraken/driver.html?testName=imaging-darkroom) 18:40:51 INFO - PID 2743 | RSS: Main: 144715776 18:40:51 INFO - PID 2743 | 18:40:55 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53164/tests/kraken/driver.html?testName=imaging-desaturate) 18:40:55 INFO - PID 2743 | RSS: Main: 145920000 18:40:55 INFO - PID 2743 | 18:40:58 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53164/tests/kraken/driver.html?testName=json-parse-financial) 18:40:59 INFO - PID 2743 | RSS: Main: 147165184 18:40:59 INFO - PID 2743 | 18:41:00 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53164/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:41:01 INFO - PID 2743 | RSS: Main: 147603456 18:41:01 INFO - PID 2743 | 18:41:03 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:41:03 INFO - PID 2743 | RSS: Main: 147853312 18:41:03 INFO - PID 2743 | 18:41:05 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:41:06 INFO - PID 2743 | RSS: Main: 148094976 18:41:06 INFO - PID 2743 | 18:41:08 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:41:09 INFO - PID 2743 | RSS: Main: 147628032 18:41:09 INFO - PID 2743 | 18:41:12 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:41:12 INFO - PID 2743 | RSS: Main: 147574784 18:41:12 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | Cycle 1(1): loaded http://localhost:53164/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53164/tests/kraken/driver.html?testName=ai-astar) 18:41:14 INFO - PID 2743 | RSS: Main: 149114880 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | __start_tp_report 18:41:14 INFO - PID 2743 | _x_x_mozilla_page_load 18:41:14 INFO - PID 2743 | _x_x_mozilla_page_load_details 18:41:14 INFO - PID 2743 | |i|pagename|runs| 18:41:14 INFO - PID 2743 | |0;ai-astar;105;107;105;107;105;108;105;105;106;105 18:41:14 INFO - PID 2743 | |1;audio-beat-detection;114;122;112;119;111;139;111;113;114;116 18:41:14 INFO - PID 2743 | |2;audio-dft;165;162;162;163;161;162;163;163;162;168 18:41:14 INFO - PID 2743 | |3;audio-fft;78;72;75;74;72;96;73;74;73;73 18:41:14 INFO - PID 2743 | |4;audio-oscillator;78;78;78;78;78;78;78;77;81;81 18:41:14 INFO - PID 2743 | |5;imaging-gaussian-blur;111;114;115;113;114;114;115;115;115;114 18:41:14 INFO - PID 2743 | |6;imaging-darkroom;152;149;151;151;151;151;150;151;150;149 18:41:14 INFO - PID 2743 | |7;imaging-desaturate;96;94;99;99;100;95;93;95;96;94 18:41:14 INFO - PID 2743 | |8;json-parse-financial;43;44;43;43;44;44;47;45;45;42 18:41:14 INFO - PID 2743 | |9;json-stringify-tinderbox;39;39;38;39;38;40;39;38;39;39 18:41:14 INFO - PID 2743 | |10;stanford-crypto-aes;70;70;71;73;72;72;74;76;70;68 18:41:14 INFO - PID 2743 | |11;stanford-crypto-ccm;106;105;115;109;109;107;104;106;101;105 18:41:14 INFO - PID 2743 | |12;stanford-crypto-pbkdf2;152;149;156;149;151;150;151;146;154;148 18:41:14 INFO - PID 2743 | |13;stanford-crypto-sha256-iterative;60;60;59;58;59;60;67;59;59;64 18:41:14 INFO - PID 2743 | __end_tp_report 18:41:14 INFO - PID 2743 | __start_cc_report 18:41:14 INFO - PID 2743 | _x_x_mozilla_cycle_collect,709 18:41:14 INFO - PID 2743 | __end_cc_report 18:41:14 INFO - PID 2743 | __startTimestamp1506562874580__endTimestamp 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | ------- Summary: start ------- 18:41:14 INFO - PID 2743 | Number of tests: 14 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#0] ai-astar Cycles:10 Average:105.80 Median:105.50 stddev:1.14 (1.1%) stddev-sans-first:1.17 18:41:14 INFO - PID 2743 | Values: 105.0 107.0 105.0 107.0 105.0 108.0 105.0 105.0 106.0 105.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#1] audio-beat-detection Cycles:10 Average:117.10 Median:115.00 stddev:8.46 (7.4%) stddev-sans-first:8.90 18:41:14 INFO - PID 2743 | Values: 114.0 122.0 112.0 119.0 111.0 139.0 111.0 113.0 114.0 116.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:2.02 (1.2%) stddev-sans-first:2.03 18:41:14 INFO - PID 2743 | Values: 165.0 162.0 162.0 163.0 161.0 162.0 163.0 163.0 162.0 168.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#3] audio-fft Cycles:10 Average:76.00 Median:74.00 stddev:7.24 (9.8%) stddev-sans-first:7.64 18:41:14 INFO - PID 2743 | Values: 78.0 72.0 75.0 74.0 72.0 96.0 73.0 74.0 73.0 73.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:78.00 stddev:1.35 (1.7%) stddev-sans-first:1.42 18:41:14 INFO - PID 2743 | Values: 78.0 78.0 78.0 78.0 78.0 78.0 78.0 77.0 81.0 81.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.50 stddev:1.25 (1.1%) stddev-sans-first:0.71 18:41:14 INFO - PID 2743 | Values: 111.0 114.0 115.0 113.0 114.0 114.0 115.0 115.0 115.0 114.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:151.00 stddev:0.97 (0.6%) stddev-sans-first:0.87 18:41:14 INFO - PID 2743 | Values: 152.0 149.0 151.0 151.0 151.0 151.0 150.0 151.0 150.0 149.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:95.50 stddev:2.42 (2.5%) stddev-sans-first:2.57 18:41:14 INFO - PID 2743 | Values: 96.0 94.0 99.0 99.0 100.0 95.0 93.0 95.0 96.0 94.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#8] json-parse-financial Cycles:10 Average:44.00 Median:44.00 stddev:1.41 (3.2%) stddev-sans-first:1.45 18:41:14 INFO - PID 2743 | Values: 43.0 44.0 43.0 43.0 44.0 44.0 47.0 45.0 45.0 42.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#9] json-stringify-tinderbox Cycles:10 Average:38.80 Median:39.00 stddev:0.63 (1.6%) stddev-sans-first:0.67 18:41:14 INFO - PID 2743 | Values: 39.0 39.0 38.0 39.0 38.0 40.0 39.0 38.0 39.0 39.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#10] stanford-crypto-aes Cycles:10 Average:71.60 Median:72.00 stddev:2.32 (3.2%) stddev-sans-first:2.39 18:41:14 INFO - PID 2743 | Values: 70.0 70.0 71.0 73.0 72.0 72.0 74.0 76.0 70.0 68.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#11] stanford-crypto-ccm Cycles:10 Average:106.70 Median:106.50 stddev:3.74 (3.5%) stddev-sans-first:3.96 18:41:14 INFO - PID 2743 | Values: 106.0 105.0 115.0 109.0 109.0 107.0 104.0 106.0 101.0 105.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.60 Median:151.00 stddev:2.91 (1.9%) stddev-sans-first:3.05 18:41:14 INFO - PID 2743 | Values: 152.0 149.0 156.0 149.0 151.0 150.0 151.0 146.0 154.0 148.0 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.50 Median:60.00 stddev:2.80 (4.7%) stddev-sans-first:2.96 18:41:14 INFO - PID 2743 | Values: 60.0 60.0 59.0 58.0 59.0 60.0 67.0 59.0 59.0 64.0 18:41:14 INFO - PID 2743 | -------- Summary: end -------- 18:41:14 INFO - PID 2743 | 18:41:14 INFO - PID 2743 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:41:15 INFO - TEST-INFO | 2743: exit 0 18:41:15 INFO - TEST-OK | kraken | took 60474ms 18:41:15 INFO - SUITE-END | took 1169s 18:41:15 INFO - Completed test suite (00:19:29) 18:41:15 INFO - javascript benchmark 18:41:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9203.66728893979, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3832.674222691071, 3840.3855383138703, 3853.614638536145, 3863.6329548692884, 3892.12427203879, 6072.392760723926, 6171.074338849175, 6216.471599914007, 6226.813195604133, 6301.936980630192, 6126.6324020558795, 6191.504679625619, 6207.689615519217, 6219.3780621937785, 6240.282367527736, 5559.6664200148125, 5626.578006649518, 5631.352394474627, 5641.68970706611, 5685.403032681571, 5626.634268772537, 5629.155626656014, 5684.459976302244, 5748.741306641211, 5763.3948435414095, 5455.1544510600925, 5552.194931734896, 5610.354809196913, 5644.74598643062, 5663.32040155184, 5607.607467477278, 5636.605437619368, 5671.517920976722, 5685.37460879307, 5698.572607054488, 5500.559955203614, 5531.446855314436, 5613.2422123013275, 5669.433056694339, 5677.630953987978, 6187.597806142631, 6198.535109866778, 6222.160008398853, 6225.906611400833, 6262.937370626281, 6664.766733164316, 6684.465242780566, 6799.558028728117, 6804.353586409283, 6808.829779255509, 6727.125473688438, 6745.08941292929, 6822.283660215656, 6824.692888819965, 6830.180378354579, 6154.938450615481, 6161.106639537287, 6214.65819379934, 6243.469305109026, 6289.8742025159245, 6896.7586134485055, 6904.378605925491, 6984.79045628632, 6992.6853291602, 6993.160820701549, 6587.571807832572, 6594.802155935329, 6655.168103987002, 6703.363180497845, 6720.563163394364, 6755.459563234929, 6796.694148763218, 6823.69293381799, 6826.795196144124, 6836.179658441019, 6632.834179145463, 6697.196336439659, 6774.220964589037, 6787.6266805325195, 6791.320867913268, 6500.252470965805, 6596.340365963461, 6683.598984060972, 6692.0, 6795.150606174228, 4900.926486102711, 4907.852764417074, 4913.533214344631, 4914.0908931847725, 4972.453030166679, 4523.909521809545, 4582.747948862844, 4587.541245875385, 4587.9082418351445, 4592.517785632527, 6070.529217967717, 6147.569670123138, 6161.568690191643, 6223.564350495421, 6240.469560087444, 6561.376669216417, 6644.036614690843, 6721.630310332881, 6789.524733268722, 6811.795646130624, 6127.785527506516, 6197.845053873689, 6238.906416403757, 6245.812625621239, 6250.968745156336, 5454.427285135083, 5510.201020851954, 5531.944680553223, 5539.14143307788, 5549.112142057332, 6754.223264324567, 6928.0, 6933.479989000845, 6958.860822783618, 7050.189228238715, 6663.267040625527, 6688.899666505104, 6689.69896354675, 6690.0633911253435, 6691.263960964193, 6883.139607549057, 6961.756338528127, 6994.2656021117045, 7033.859322813412, 7037.859242815218, 6659.966700166468, 6689.331066893272, 6702.899456508058, 6751.257361690308, 6754.527183097332, 6540.051692504655, 6711.731530738716, 6735.292794256533, 6826.965865170642, 6830.419414349836, 6326.936730632819, 6373.426391624776, 6385.840353991188, 6391.3289104643345, 6406.103145559532, 6456.580322279225, 6459.386358295955, 6506.642134682638, 6547.869042619026, 6550.541462097607, 6381.93618063832, 6384.840378990562, 6457.741690332334, 6458.967705161444, 6480.12518310041, 6405.743770249324, 6479.676016199265, 6488.351164883663, 6570.671466426564, 6616.669166541557, 6390.712417941111, 6440.130582371507, 6450.290468048604, 6459.095726598186, 6467.870642587028], "value": 6152.827866457223, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [974.6345120579782, 1000.0249756487426, 1000.4147573669403, 1001.3341128149781, 1003.7590978165242, 42015.15969680605, 45240.0, 45664.77167614165, 45716.771416142954, 45821.31268030982, 42881.142377152515, 43335.56664433355, 43350.7832460838, 43358.566414335844, 43364.0, 7684.500507467011, 7807.609619519029, 7811.453198276123, 7812.843743125134, 8578.099299573549, 8678.435901666371, 8796.736097917073, 8803.20771130598, 8817.95591022044, 8823.60293786778, 8747.343949203803, 8821.558922053904, 8847.20375166235, 8864.290856731446, 8864.911350886505, 8746.212840844322, 8760.518171500566, 8811.251043661296, 8812.339074569369, 8823.470591764482, 8638.049814520398, 8664.956675216583, 8678.0, 8684.956575217147, 8728.170823771732, 41002.794986025176, 41047.589524104675, 41197.58802411998, 41218.587814121776, 41267.1746565067, 3730.9626903730887, 3783.281176576442, 3788.5453745550435, 3789.2989796887664, 3791.1659434924272, 34198.316033679184, 34228.48657270143, 34441.65558344434, 34521.30957380839, 34535.0, 5429.83710488682, 5457.263269458612, 5522.282103326582, 5530.419305972855, 5542.612017158801, 232151.0, 232712.0, 233677.0, 233832.0, 234185.0, 41134.58865411367, 55149.72425137849, 55181.0, 55193.44806551883, 55226.44773552293, 42972.0, 57513.71243143841, 58151.12773308404, 58177.4182258172, 58257.0, 17186.656266874594, 17282.22229999653, 17301.3944511944, 17311.913440432716, 17314.307427703003, 13896.444142234197, 13933.721325573431, 13955.023148379516, 13965.720685586231, 13980.72038559223, 15768.369265229492, 15833.762493562606, 15896.284667190004, 15906.681866362607, 15932.36270549165, 6145.8156255312415, 6206.596571222856, 6231.501479881598, 6279.58709290406, 6305.716242769086, 1084.951177197028, 1093.5297821936485, 1096.2216826053432, 1117.0337657925998, 1125.8817824128512, 25794.742052579233, 26007.0, 26328.868355658484, 26347.0, 26477.47045059088, 1195.6950977500856, 1196.6350263169816, 1209.2079687804503, 1215.0947544079718, 1223.2966044524435, 13555.59333220005, 13606.251656159006, 13680.93159534196, 13871.306434678225, 13884.51404200848, 293010.0, 293998.0, 294073.0, 294121.0, 294808.0, 59910.70044649749, 60083.09875351873, 60304.698476507336, 60392.0, 60429.0, 1496.7904493370936, 1504.8194216693848, 1527.2058529564786, 1527.3814105287377, 1531.8851086168581, 25261.115860944778, 25530.872345638152, 25714.485710285317, 25738.742612573635, 25819.483610328065, 41998.790006051, 42412.0, 42541.78729106458, 42713.359299611155, 42750.35874461948, 248212.0, 249006.0, 249071.0, 249345.0, 250977.0, 37029.25941481209, 39350.60649393584, 39881.40177897276, 40285.59714402819, 40311.1937761249, 51548.48451515437, 52366.47633523616, 52422.0, 52800.47199527956, 53213.467865320854, 37805.24389512249, 37963.43054854235, 38128.0, 38135.42796857994, 38733.2253354937, 37922.24155516819, 38003.0, 38161.23677526489, 38335.42496862494, 38358.0, 44854.3271850929, 44865.10269794651, 45010.77494612506, 45023.0, 45445.54554454546, 36300.63699362973, 36323.81838090898, 36585.817070914476, 36666.0, 36707.0, 27530.218654082866, 28249.43501130007, 28304.292392690346, 28307.716922831332, 28314.43371132607], "value": 19348.40754916158, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.7001814083887, 608.14859197124, 627.5168120547178, 634.1724050114605, 658.2002866517182, 23041.769582304172, 23671.0, 24431.511369772594, 24564.508709825815, 24607.384815379624, 25521.489570208632, 26590.202293931212, 26615.733842661568, 26668.59997100045, 26707.19878403646, 6471.385218404256, 6513.185851768529, 6531.0856480092825, 6538.771143009995, 6546.672666366687, 6679.833004174886, 6897.48268879836, 6909.447244220451, 6918.5502942308585, 6919.481038922076, 6881.862362752742, 6885.036094946711, 6887.139107611549, 6887.5178737488395, 6888.414484768776, 6607.075009498674, 6875.621840798754, 6885.621290829003, 6895.965520172367, 6900.861982760342, 6392.4886009119155, 6393.9360606394275, 6416.165898433173, 6418.582792118544, 6428.035794630796, 30335.393292134035, 30526.54210186849, 30897.0, 30917.38165236683, 30924.381512369626, 2264.3320220534983, 2268.5122698619875, 2293.7362203346497, 2300.344401845483, 2304.089884495615, 20249.29127480546, 20975.580488390147, 20994.685079723815, 21058.368248952404, 21075.68386474204, 2504.198656429943, 2558.1302357198456, 2617.738226177386, 2618.973810261892, 2632.723564025788, 142277.0, 148668.0, 163234.0, 164095.0, 170955.0, 16125.354985800672, 16808.411705590246, 16841.747373789403, 16978.471937525683, 17250.913745431193, 27008.86495567549, 27058.2412143214, 27084.0, 27126.321841954108, 27190.0, 10873.891261087447, 10934.507947142398, 11014.191381197903, 11017.724556886142, 11229.494672739584, 10084.394936303845, 10121.544530496145, 10162.39025658463, 10172.440515771703, 10211.897881021094, 9737.123658870594, 9894.80210395788, 10050.798984020425, 10232.386056836614, 10254.846177307347, 2821.4216085702383, 2831.2497188245134, 2883.2215301868378, 2953.4536110819568, 2993.0721476342405, 1051.1433181956681, 1063.0910571461443, 1077.2190162132392, 1077.7575045614674, 1080.340991994898, 21255.0, 21507.0, 21633.675494867588, 21686.674699879513, 21748.0, 1126.4255229832845, 1140.5323817234894, 1144.6508814811395, 1144.853399875234, 1161.4436654882497, 11763.588274410526, 11826.0, 11950.701232469259, 11961.282323060645, 12050.09624278165, 636504.0, 645646.0, 657083.0, 657711.0, 664474.0, 130964.34517827349, 133442.33278833545, 135317.3234133823, 136096.31951840178, 136248.31875840557, 1216.3735676126803, 1236.1470585296117, 1268.2517314784507, 1272.5228039485194, 1274.9426275817611, 18581.90709046446, 18866.3396781112, 18906.716399254296, 18917.337893173673, 18965.620687585895, 63396.68301658647, 64553.0, 64888.675556623806, 65107.0, 67306.0, 78283.60858195672, 78392.60803695946, 79171.20828791638, 79735.0, 80151.0, 15716.842831571996, 16061.357545698042, 16133.596660083593, 16205.513834584981, 16241.350345986028, 24469.388265292797, 24470.388240294134, 24657.383565411008, 24777.87611062005, 24833.627495587218, 19802.108905099016, 19944.90027549853, 19953.20187192496, 20021.599568008267, 20285.391438256876, 17568.912155439142, 17878.374256900945, 17999.10004499796, 18200.817991819913, 18292.45122646323, 19872.10575524076, 19972.400827975183, 20138.69791953151, 20166.59666806685, 20174.49563760859, 14846.0, 15714.9214253928, 15791.921040395184, 15815.209239537748, 15877.52367428979, 19277.518062048, 19615.0, 19706.8029319705, 19801.801981979996, 19884.50288742793], "value": 13036.998613183523, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1379.8827099696525, 1481.8666320031195, 1486.5688950204442, 1487.8512148785123, 1499.730048591253, 16625.418110366125, 17367.739483907753, 17402.607791376708, 17409.0, 17448.738268925943, 16693.66612667749, 16794.496165115037, 16798.32806687734, 16804.495865124034, 16834.410795622156, 2689.072270066824, 2707.0795929384008, 2737.86310684466, 2758.7379198976064, 2764.557670772677, 2843.9289017774618, 2857.8856845726145, 2870.512012957802, 2910.621619189513, 2911.548709949961, 2827.575863620463, 2828.5898544711004, 2930.824150550963, 2955.290730224752, 2956.408718256357, 2873.54023356262, 2879.784016198783, 2894.4645240630443, 2909.767218622505, 2911.2139722274974, 2678.879450424716, 2774.056820680978, 2830.6320178376677, 2867.3261783480866, 2871.181713211725, 16762.32950681971, 16882.74675879863, 16884.915575422045, 16893.662126757517, 16895.49313520584, 7887.132415434297, 8221.671133154667, 8225.177482251787, 8227.794305142359, 8229.46508476947, 5385.379154996343, 5525.11598144295, 5573.916391254134, 5579.163125531162, 5618.887622247573, 2092.8221101206414, 2129.350548082834, 2145.281330754199, 2153.569286142778, 2200.7359116905914, 31338.37323253545, 31522.842385788153, 31525.0, 31599.210019749462, 31629.841850790828, 7379.926200738031, 7446.292602202782, 7471.178170401252, 7624.313811756969, 7645.235476452311, 20177.697334539993, 20185.59628807445, 20286.89856550708, 20376.184952602027, 20382.796172038386, 8615.483071015758, 8783.385163038525, 8787.824243515093, 8789.296856251485, 8791.296696264284, 7820.726274580363, 7910.723124690723, 8011.0, 8057.59712014397, 8077.232662897015, 5838.416158384128, 5903.0555111182, 5934.13954976526, 5947.851303717355, 5973.910391344133, 1756.3852651571847, 1790.5523619095227, 1791.1402526787215, 1798.2896755781394, 1799.4601619514094, 958.8705524754068, 983.6999715086824, 985.5959056786826, 1013.8732658417698, 1019.8062368150028, 8436.694888648495, 8543.829123417498, 8588.785280368042, 8680.262177714965, 8715.782105447415, 781.8553567590014, 808.9676412943417, 816.77946954322, 817.965273928481, 827.7765003449035, 5165.9225111623355, 5313.123334649817, 5860.70696465175, 5883.882322353529, 6022.337542870281, 23928.88035559811, 24572.877135614566, 25394.0, 25749.3562660935, 25824.0, 23615.0, 24413.877930610233, 24665.753342466345, 24672.876635616707, 24866.627000594643, 12305.876941230652, 12354.135210535356, 12594.362732844855, 12666.36668166588, 12670.556530521388, 9356.532173391439, 9536.046395360408, 9552.474613896302, 9594.856077158987, 9639.277054221102, 20346.796532034492, 20421.48946276355, 20422.79577204209, 20796.1681532737, 21302.7869721307, 21099.683504747132, 21262.043208055366, 21469.355919322446, 21554.892225538773, 22621.773782262626, 12195.573154939535, 12225.388730563258, 12290.262584244974, 12301.138920275494, 12308.323042232763, 14479.276036197938, 14589.124652520883, 14626.707465850835, 14785.630359241106, 14891.03208291501, 12461.1900226485, 12645.93677031609, 12677.936610316889, 12678.809817852554, 12744.299063551594, 11536.538538458368, 11570.479328430072, 11663.88336116628, 11841.46713397882, 11898.881011189776, 10448.582056717647, 10733.409662468639, 10814.188935829687, 10818.567257309935, 10826.621068262888, 8399.664013439638, 8480.872786908078, 8567.571621419029, 8623.568821559022, 8640.092790257184, 8247.628856701584, 8375.916240837758, 8402.033766116974, 8422.284105851071, 8504.617292221741], "value": 7298.773458484456, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.8198976327577, 569.4561693582629, 573.549763435703, 578.4967078641582, 588.1031427073713, 6196.845078873031, 6205.317415084337, 6226.43962043416, 6235.501159907208, 6251.7499300028, 6166.136740856284, 6175.228096487939, 6209.316975132731, 6217.129601855732, 6275.780347687821, 5106.387233531981, 5118.513741194581, 5150.459201783815, 5166.819161329354, 5168.457311982244, 5209.218617207412, 5225.346831646044, 5264.578833693296, 5265.9473405266035, 5266.947330526684, 5204.193350030751, 5228.947710522903, 5247.819240670857, 5247.947520524803, 5281.366236051679, 5234.764435600388, 5237.292965449673, 5252.921206181911, 5265.289185959923, 5268.710220937847, 4908.680935739166, 4948.529889660476, 4972.105021096201, 4985.930838701935, 4986.800527978877, 6160.0759886017, 6239.500839932794, 6266.0, 6353.269374022, 6361.872762544769, 6164.7534098636, 6165.506759459233, 6220.222472190976, 6264.0, 6265.530085243625, 6764.898526522106, 6787.185537735503, 6795.592264464148, 6800.489963252776, 6804.251532331439, 5228.111221092423, 5447.428020057877, 5504.862378440571, 5547.583931205176, 5548.001359755242, 6704.396604305636, 6741.157355330583, 6855.211650660188, 6911.861762764716, 6917.619530925797, 5672.426371386083, 5681.545476361881, 5686.943130568724, 5699.914501282484, 5701.543876489871, 5757.0, 5781.971090144607, 5784.681842498619, 5796.159556864315, 5800.622959507618, 6163.0, 6187.628742275478, 6204.720787564571, 6225.750969961241, 6247.0, 6746.257911629717, 6746.650669866046, 6794.218664853605, 6810.455163586901, 6829.521933464709, 1993.8604297699312, 2000.0199902048182, 2006.6388050150822, 2010.0452285164722, 2023.8482113841521, 1452.561963655973, 1458.2854401343266, 1499.1005396761855, 1523.1470376589145, 1542.0978727444347, 5882.499987501024, 5946.275580081728, 5957.791477298274, 6035.849103772353, 6090.847728806727, 6648.102506161703, 6712.060311556386, 6716.798496045127, 6793.762218322335, 6796.0, 6125.081237814275, 6130.509559235251, 6144.9246381882995, 6152.692365381802, 6189.535784816157, 3490.441529355291, 3499.440089585705, 3553.933819854085, 3556.0043187907427, 3594.748367614242, 6902.171739391205, 6907.758228461979, 6930.584164950018, 6985.6507174641065, 6996.055532503149, 6924.030635711005, 6936.2023367312395, 6969.23338432772, 6980.267071957473, 7018.192907815666, 6914.585124892523, 6924.515283930076, 6943.604456462513, 6998.860022799516, 7028.964855175692, 6542.887709089371, 6640.535162538769, 6672.797976667785, 6686.01891590768, 6717.56335838169, 6727.360900714424, 6748.595084294958, 6764.3573860483175, 6768.357006084414, 6810.012548180387, 6860.416864566568, 6876.862462750617, 6949.30506949301, 6990.881458966541, 7017.035230135571, 5516.668999860021, 5621.634593751393, 5637.8590535236945, 5653.604247702621, 5656.886862262814, 6469.676516174079, 6568.0, 6593.80218593443, 6665.266820649627, 6729.259781424137, 6406.231252249573, 6429.0, 6490.7079181436, 6498.317676644074, 6609.768658096944, 6436.910283064062, 6480.25477070143, 6481.449076828522, 6550.803475895731, 6554.180727409074, 6224.782132625517, 6280.403361680633, 6321.841953951188, 6362.786792669672, 6446.935530644633, 6397.360263973565, 6447.4197322241225, 6631.1048008520165, 6663.800085997428, 6682.59426606431, 6348.555601107878, 6390.90413643805, 6403.391677790602, 6406.967965160145, 6430.7749228776765], "value": 5312.0546452420385, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [956.8995255498172, 960.8222478841413, 974.0503009565674, 985.0543478260869, 990.163312001359, 19252.0, 19762.802371976275, 19929.40211793645, 19953.501162470944, 20005.499862503446, 18894.0, 19065.046747662596, 19089.90455047723, 19105.52236194096, 19113.90443047783, 1045.8483519889612, 1066.648006157966, 1071.2982996137541, 1072.1208608940672, 1073.2540884085563, 1049.8110340138771, 1051.5846240734904, 1051.984220236697, 1052.384355152233, 1053.0680347892094, 1095.9232853700244, 1113.2930589075913, 1137.7838210739974, 1138.0554140063769, 1141.8401423800674, 1113.4544073404015, 1115.1970581181536, 1119.6585041562362, 1120.5349779841356, 1125.1561329003248, 785.9135495095535, 812.9390295727844, 814.6700586262573, 815.0260438775665, 825.793551612097, 20592.691109633368, 20727.689084663743, 20841.478963026046, 21063.052162652562, 21068.26261080855, 21758.347249582537, 22027.449313767123, 22310.442238944157, 22467.887660561755, 22507.21224757142, 17625.294988200458, 17960.01219932903, 18260.0, 18261.54346141344, 18357.724634130365, 3894.5131858517684, 3929.9978505481204, 3930.0960779020697, 3930.1746633207063, 3932.0563064864514, 138455.0, 166189.0, 169645.15177424156, 173866.0, 174604.0, 18525.53686157857, 18816.341428049953, 18940.0, 19009.14458849355, 19028.80971190298, 33406.83296583501, 33447.331053378795, 33868.32263354769, 33988.66011339904, 34065.48901766475, 9585.520723963775, 9736.415815050977, 9801.215902727765, 9815.263855210887, 9835.164011058998, 4482.193205223026, 4616.60758835496, 4628.861134165981, 4630.351750754897, 4630.444346678353, 5952.880942381128, 6056.848578785478, 6072.969635151796, 6090.756369745249, 6094.329623741385, 1192.2548407245472, 1196.5871774237874, 1198.568515334479, 1205.6202296276645, 1215.471269997554, 713.5968177979425, 731.7438896386328, 740.8592367450167, 740.9481336306515, 765.9578723170167, 12519.874801252054, 12822.551210707581, 12924.870751292367, 12964.22214667123, 13339.665558527218, 680.4148432348177, 687.9449644028466, 699.0527834783896, 701.8070030741485, 704.8555046215616, 6036.275646922398, 6121.204243448412, 6123.785667501616, 6185.84535386619, 6221.9377806222265, 154598.0, 155400.0, 156077.0, 156754.0, 159006.0, 106394.0, 110484.0, 129922.0, 135635.32182339026, 142069.0, 118213.0, 119804.0, 121809.39095304467, 123094.38452807679, 123977.0, 12220.633380998584, 12230.75538489243, 12367.62897113088, 12456.12807103494, 12829.0, 54718.45281547133, 56463.71768141297, 56800.715996419756, 57031.14453283121, 57764.0, 68843.31156688368, 69689.0, 69813.0, 70281.0, 71340.28659713337, 19579.902100489406, 19919.701204481655, 20001.799982000575, 20111.79888201158, 20132.0, 21436.785632144103, 21733.34799956004, 21850.12599496066, 21869.562608748056, 21999.670004950258, 18944.147513361655, 19159.137838797007, 19348.4195474136, 19484.805151948298, 19487.415377538695, 19953.800461995776, 20025.0, 20041.39875803728, 20094.89952550228, 20170.69743953871, 18789.624207516048, 18886.622267554296, 18893.433197004113, 19001.80998190056, 19219.423417297505, 14658.19379934114, 14663.56009319722, 14682.0, 14723.411063557765, 14870.033447825857, 13401.798973015608, 13478.123921945042, 13524.797128043285, 13527.25600091965, 13724.039317247696], "value": 10100.153228261792, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1373.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 107.0, 105.0, 107.0, 105.0, 108.0, 105.0, 105.0, 106.0, 105.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 122.0, 112.0, 119.0, 111.0, 139.0, 111.0, 113.0, 114.0, 116.0], "value": 117.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 162.0, 163.0, 161.0, 162.0, 163.0, 163.0, 162.0, 168.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 75.0, 74.0, 72.0, 96.0, 73.0, 74.0, 73.0, 73.0], "value": 76.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 78.0, 78.0, 78.0, 78.0, 77.0, 81.0, 81.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 115.0, 113.0, 114.0, 114.0, 115.0, 115.0, 115.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 149.0, 151.0, 151.0, 151.0, 151.0, 150.0, 151.0, 150.0, 149.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 99.0, 99.0, 100.0, 95.0, 93.0, 95.0, 96.0, 94.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 44.0, 43.0, 43.0, 44.0, 44.0, 47.0, 45.0, 45.0, 42.0], "value": 44.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 38.0, 39.0, 38.0, 40.0, 39.0, 38.0, 39.0, 39.0], "value": 38.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 71.0, 73.0, 72.0, 72.0, 74.0, 76.0, 70.0, 68.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 115.0, 109.0, 109.0, 107.0, 104.0, 106.0, 101.0, 105.0], "value": 106.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 149.0, 156.0, 149.0, 151.0, 150.0, 151.0, 146.0, 154.0, 148.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 58.0, 59.0, 60.0, 67.0, 59.0, 59.0, 64.0], "value": 60.5, "unit": "score"}]}]} 18:41:15 INFO - Return code: 0 18:41:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:41:15 INFO - # TBPL SUCCESS # 18:41:15 INFO - Running post-action listener: _package_coverage_data 18:41:15 INFO - Running post-action listener: _resource_record_post_action 18:41:15 INFO - [mozharness: 2017-09-28 01:41:15.867532Z] Finished run-tests step (success) 18:41:15 INFO - Running post-run listener: _resource_record_post_run 18:41:15 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 331214848; Read time: 340; Write time: 345340 18:41:15 INFO - TinderboxPrint: CPU usage
13.1% 18:41:15 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 340 18:41:15 INFO - TinderboxPrint: I/O write bytes / time
331,214,848 / 345,340 18:41:15 INFO - TinderboxPrint: CPU idle
8,225.1 (86.9%) 18:41:15 INFO - TinderboxPrint: CPU user
1,188.1 (12.6%) 18:41:15 INFO - TinderboxPrint: Swap in / out
0 / 0 18:41:15 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13922304; Read time: 0; Write time: 3704 18:41:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:41:15 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 8192; Write bytes: 317292544; Read time: 340; Write time: 341636 18:41:16 INFO - Running post-run listener: _upload_blobber_files 18:41:16 INFO - Blob upload gear active. 18:41:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:41:16 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 18:41:16 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'] 18:41:16 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 18:41:16 INFO - (blobuploader) - INFO - Open directory for files ... 18:41:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:41:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:41:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:18 INFO - (blobuploader) - INFO - Done attempting. 18:41:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:41:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:41:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:25 INFO - (blobuploader) - INFO - Done attempting. 18:41:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:41:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:41:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:26 INFO - (blobuploader) - INFO - Done attempting. 18:41:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:41:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:41:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:27 INFO - (blobuploader) - INFO - Done attempting. 18:41:27 INFO - (blobuploader) - INFO - Iteration through files over. 18:41:27 INFO - Return code: 0 18:41:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:41:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:41:27 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c74578b05fa38ddde2d8f5a3f5168bebbf60aa84d1f418ad4395443b05b3f6d4bb593e4bb138a593f42045699cb166dd29d7ea36ed1a9c17a419e4d759a1038", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fcf687c928166eb8f4c0116c54b9dd3820a06959989afd53228ebcc5deac0fca23226f081def341e47b446f07904130f0f81576cb65039371b0024f90e5b90a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6600ad0857aaa4c095e82219caf8184f77b38eb373f23f9b75b576b4d147e85fa2c4488a8d0a9c3a22848ee5d41cb27722f486022fb774a3493b4c4cab2b1bd1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cbb7d713e12244a720d2d69154fdd088a34c239ec8bd2d1c1000563e52481150dae975b00e3f89b0cb4edd62dbe5cc2fff0a644ab0943fb283d669c3597239f0"} 18:41:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:41:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:41:27 INFO - Contents: 18:41:27 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c74578b05fa38ddde2d8f5a3f5168bebbf60aa84d1f418ad4395443b05b3f6d4bb593e4bb138a593f42045699cb166dd29d7ea36ed1a9c17a419e4d759a1038", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fcf687c928166eb8f4c0116c54b9dd3820a06959989afd53228ebcc5deac0fca23226f081def341e47b446f07904130f0f81576cb65039371b0024f90e5b90a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6600ad0857aaa4c095e82219caf8184f77b38eb373f23f9b75b576b4d147e85fa2c4488a8d0a9c3a22848ee5d41cb27722f486022fb774a3493b4c4cab2b1bd1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cbb7d713e12244a720d2d69154fdd088a34c239ec8bd2d1c1000563e52481150dae975b00e3f89b0cb4edd62dbe5cc2fff0a644ab0943fb283d669c3597239f0"} 18:41:27 INFO - Running post-run listener: copy_logs_to_upload_dir 18:41:27 INFO - Copying logs to upload dir... 18:41:27 INFO - mkdir: /builds/slave/test/build/upload/logs 18:41:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.412496 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-27 18:41:27.199416) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 18:41:27.206863) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296 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/5c74578b05fa38ddde2d8f5a3f5168bebbf60aa84d1f418ad4395443b05b3f6d4bb593e4bb138a593f42045699cb166dd29d7ea36ed1a9c17a419e4d759a1038", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fcf687c928166eb8f4c0116c54b9dd3820a06959989afd53228ebcc5deac0fca23226f081def341e47b446f07904130f0f81576cb65039371b0024f90e5b90a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6600ad0857aaa4c095e82219caf8184f77b38eb373f23f9b75b576b4d147e85fa2c4488a8d0a9c3a22848ee5d41cb27722f486022fb774a3493b4c4cab2b1bd1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cbb7d713e12244a720d2d69154fdd088a34c239ec8bd2d1c1000563e52481150dae975b00e3f89b0cb4edd62dbe5cc2fff0a644ab0943fb283d669c3597239f0"} build_url:https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011341 build_url: 'https://queue.taskcluster.net/v1/task/Nd2wp_F6SlCE3VuynIQczQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c74578b05fa38ddde2d8f5a3f5168bebbf60aa84d1f418ad4395443b05b3f6d4bb593e4bb138a593f42045699cb166dd29d7ea36ed1a9c17a419e4d759a1038", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fcf687c928166eb8f4c0116c54b9dd3820a06959989afd53228ebcc5deac0fca23226f081def341e47b446f07904130f0f81576cb65039371b0024f90e5b90a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6600ad0857aaa4c095e82219caf8184f77b38eb373f23f9b75b576b4d147e85fa2c4488a8d0a9c3a22848ee5d41cb27722f486022fb774a3493b4c4cab2b1bd1", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cbb7d713e12244a720d2d69154fdd088a34c239ec8bd2d1c1000563e52481150dae975b00e3f89b0cb4edd62dbe5cc2fff0a644ab0943fb283d669c3597239f0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 18:41:27.237578) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:41:27.237887) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1506561645.298945-1077780296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004573 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 18:41:27.320393) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 18:41:27.323715) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 18:41:27.324038) ========= ========= Total master_lag: 0.21 =========