builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-058 starttime: 1505034573.73 results: success (0) revision: 00baeed193d4f276694912f0298336880280837d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:33.734997) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:33.735557) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:33.735838) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:33.753427) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:33.753761) ========= 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=0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-10 02:09:33-- 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% 98.8M=0s 2017-09-10 02:09:33 (98.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155148 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:33.925659) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:33.925979) ========= 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=0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021417 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:34.030952) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:34.031315) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 00baeed193d4f276694912f0298336880280837d --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 00baeed193d4f276694912f0298336880280837d --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=0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-10 02:09:34,102 truncating revision to first 12 chars 2017-09-10 02:09:34,102 Setting DEBUG logging. 2017-09-10 02:09:34,102 attempt 1/10 2017-09-10 02:09:34,102 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/00baeed193d4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-10 02:09:34,515 unpacking tar archive at: mozilla-central-00baeed193d4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.718291 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:34.766044) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:34.766364) ========= script_repo_revision: 00baeed193d4f276694912f0298336880280837d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:34.766755) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:34.767017) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-10 02:09:34.779852) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 2 secs) (at 2017-09-10 02:09:34.780267) ========= /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=0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:09:34 INFO - MultiFileLogger online at 20170910 02:09:34 in /builds/slave/test 02:09:34 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 02:09:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:09:34 INFO - {'append_to_log': False, 02:09:34 INFO - 'base_work_dir': '/builds/slave/test', 02:09:34 INFO - 'blob_upload_branch': 'Firefox', 02:09:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:09:34 INFO - 'branch': 'Firefox', 02:09:34 INFO - 'buildbot_json_path': 'buildprops.json', 02:09:34 INFO - 'code_coverage': False, 02:09:34 INFO - 'config_files': ('talos/linux_config.py',), 02:09:34 INFO - 'default_actions': ('clobber', 02:09:34 INFO - 'read-buildbot-config', 02:09:34 INFO - 'download-and-extract', 02:09:34 INFO - 'populate-webroot', 02:09:34 INFO - 'create-virtualenv', 02:09:34 INFO - 'install', 02:09:34 INFO - 'setup-mitmproxy', 02:09:34 INFO - 'run-tests'), 02:09:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:09:34 INFO - 'disable_ccov_upload': False, 02:09:34 INFO - 'disable_stylo': False, 02:09:34 INFO - 'download_minidump_stackwalk': True, 02:09:34 INFO - 'download_symbols': 'ondemand', 02:09:34 INFO - 'enable_stylo': False, 02:09:34 INFO - 'enable_webrender': False, 02:09:34 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:09:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:09:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:09:34 INFO - 'gecko_profile': False, 02:09:34 INFO - 'gecko_profile_interval': 0, 02:09:34 INFO - 'installer_path': 'installer.exe', 02:09:34 INFO - 'log_level': 'info', 02:09:34 INFO - 'log_name': 'talos', 02:09:34 INFO - 'log_to_console': True, 02:09:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:09:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:09:34 INFO - 'opt_config_files': (), 02:09:34 INFO - 'pip_index': False, 02:09:34 INFO - 'suite': 'dromaeojs-e10s', 02:09:34 INFO - 'system_bits': '32', 02:09:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:09:34 INFO - 'title': 'talos-linux64-ix-058', 02:09:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:09:34 INFO - 'use_talos_json': True, 02:09:34 INFO - 'verify': 'False', 02:09:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:09:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:09:34 INFO - 'work_dir': 'build'} 02:09:34 INFO - [mozharness: 2017-09-10 09:09:34.996202Z] Running clobber step. 02:09:34 INFO - Running pre-action listener: _resource_record_pre_action 02:09:34 INFO - Running main action method: clobber 02:09:34 INFO - rmtree: /builds/slave/test/build 02:09:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:09:36 INFO - Running post-action listener: _resource_record_post_action 02:09:36 INFO - [mozharness: 2017-09-10 09:09:36.015232Z] Finished clobber step (success) 02:09:36 INFO - [mozharness: 2017-09-10 09:09:36.015328Z] Running read-buildbot-config step. 02:09:36 INFO - Running pre-action listener: _resource_record_pre_action 02:09:36 INFO - Running main action method: read_buildbot_config 02:09:36 INFO - Using buildbot properties: 02:09:36 INFO - { 02:09:36 INFO - "project": "", 02:09:36 INFO - "product": "firefox", 02:09:36 INFO - "who": "archaeopteryx@coole-files.de", 02:09:36 INFO - "installer_path": "public/build/target.tar.bz2", 02:09:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 02:09:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:09:36 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 02:09:36 INFO - "stage_platform": "linux64-qr", 02:09:36 INFO - "basedir": "/builds/slave/test", 02:09:36 INFO - "buildnumber": 43, 02:09:36 INFO - "platform": "ubuntu64_hw_qr", 02:09:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:09:36 INFO - "slavebuilddir": "test", 02:09:36 INFO - "taskId": "P4sVPq-KQY-78AGuLVGO7A", 02:09:36 INFO - "branch": "mozilla-central", 02:09:36 INFO - "script_repo_revision": "production", 02:09:36 INFO - "revision": "00baeed193d4f276694912f0298336880280837d", 02:09:36 INFO - "slavename": "talos-linux64-ix-058", 02:09:36 INFO - "repo_path": "mozilla-central" 02:09:36 INFO - } 02:09:36 INFO - Finding installer, test and symbols from parent task. 02:09:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P4sVPq-KQY-78AGuLVGO7A'}, attempt #1 02:09:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P4sVPq-KQY-78AGuLVGO7A'}, attempt #1 02:09:36 INFO - Task dependencies: DRhsJxRzQc2SbL7C1Z9-Ww 02:09:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww'}, attempt #1 02:09:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.tar.bz2 02:09:37 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.test_packages.json 02:09:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.crashreporter-symbols.zip 02:09:37 INFO - Running post-action listener: _resource_record_post_action 02:09:37 INFO - [mozharness: 2017-09-10 09:09:37.365769Z] Finished read-buildbot-config step (success) 02:09:37 INFO - [mozharness: 2017-09-10 09:09:37.365986Z] Running download-and-extract step. 02:09:37 INFO - Running pre-action listener: _resource_record_pre_action 02:09:37 INFO - Running main action method: download_and_extract 02:09:37 INFO - mkdir: /builds/slave/test/build/tests 02:09:37 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')]} 02:09:37 INFO - trying https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.test_packages.json 02:09:37 INFO - Downloading https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:09:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:09:37 INFO - Downloaded 989 bytes. 02:09:37 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:09:37 INFO - Using the following test package requirements: 02:09:37 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:09:37 INFO - u'common': [u'target.common.tests.zip'], 02:09:37 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:09:37 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:09:37 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:09:37 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:09:37 INFO - u'mozbase': [u'target.common.tests.zip'], 02:09:37 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:09:37 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:09:37 INFO - u'web-platform': [u'target.common.tests.zip', 02:09:37 INFO - u'target.web-platform.tests.tar.gz'], 02:09:37 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:09:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 02:09:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.common.tests.zip 02:09:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:09:37 INFO - Fetch https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.common.tests.zip into memory 02:10:48 INFO - Content-Length response header: 55028609 02:10:48 INFO - Bytes received: 55028609 02:10:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.talos.tests.zip 02:10:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:10:59 INFO - Fetch https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.talos.tests.zip into memory 02:11:00 INFO - Content-Length response header: 14497963 02:11:00 INFO - Bytes received: 14497963 02:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:01 INFO - trying https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.tar.bz2 02:11:01 INFO - Downloading https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:11:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:11:03 INFO - Downloaded 62876814 bytes. 02:11:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.tar.bz2 02:11:03 INFO - mkdir: /builds/slave/test/properties 02:11:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:11:03 INFO - Writing to file /builds/slave/test/properties/build_url 02:11:03 INFO - Contents: 02:11:03 INFO - build_url:https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.tar.bz2 02:11:03 INFO - Running post-action listener: _resource_record_post_action 02:11:03 INFO - Running post-action listener: find_tests_for_verification 02:11:03 INFO - Running post-action listener: set_extra_try_arguments 02:11:03 INFO - [mozharness: 2017-09-10 09:11:03.343135Z] Finished download-and-extract step (success) 02:11:03 INFO - [mozharness: 2017-09-10 09:11:03.343363Z] Running populate-webroot step. 02:11:03 INFO - Running pre-action listener: _resource_record_pre_action 02:11:03 INFO - Running main action method: populate_webroot 02:11:03 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:11:03 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['tresize', 02:11:03 INFO - 'tcanvasmark']}, 02:11:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:11:03 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['dromaeo_css', 02:11:03 INFO - 'kraken']}, 02:11:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:11:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:11:03 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:11:03 INFO - 'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:11:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:11:03 INFO - 'tests': ['damp', 'tps']}, 02:11:03 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:11:03 INFO - 'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['damp', 'tps']}, 02:11:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:11:03 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['dromaeo_dom']}, 02:11:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:11:03 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['basic_compositor_video', 02:11:03 INFO - 'glvideo']}, 02:11:03 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:11:03 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:11:03 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:11:03 INFO - 'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['ts_paint_webext', 02:11:03 INFO - 'tp5o_webext']}, 02:11:03 INFO - 'other-e10s': {'tests': ['a11yr', 02:11:03 INFO - 'ts_paint', 02:11:03 INFO - 'tpaint', 02:11:03 INFO - 'sessionrestore', 02:11:03 INFO - 'sessionrestore_many_windows', 02:11:03 INFO - 'sessionrestore_no_auto_restore', 02:11:03 INFO - 'tabpaint']}, 02:11:03 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['a11yr', 02:11:03 INFO - 'ts_paint', 02:11:03 INFO - 'tpaint', 02:11:03 INFO - 'sessionrestore', 02:11:03 INFO - 'sessionrestore_many_windows', 02:11:03 INFO - 'sessionrestore_no_auto_restore', 02:11:03 INFO - 'tabpaint']}, 02:11:03 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:11:03 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:11:03 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['bloom_basic_singleton']}, 02:11:03 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['bloom_basic']}, 02:11:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:11:03 INFO - 'tsvgr_opacity', 02:11:03 INFO - 'tart', 02:11:03 INFO - 'tscrollx', 02:11:03 INFO - 'tsvg_static']}, 02:11:03 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['tsvgx', 02:11:03 INFO - 'tsvgr_opacity', 02:11:03 INFO - 'tart', 02:11:03 INFO - 'tscrollx', 02:11:03 INFO - 'tsvg_static']}, 02:11:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:11:03 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:11:03 INFO - 'talos_options': ['--disable-stylo'], 02:11:03 INFO - 'tests': ['tp5o']}, 02:11:03 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:11:03 INFO - 'talos_options': ['--mitmproxy', 02:11:03 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:11:03 INFO - '--firstNonBlankPaint'], 02:11:03 INFO - 'tests': ['tp6_google', 02:11:03 INFO - 'tp6_youtube', 02:11:03 INFO - 'tp6_amazon', 02:11:03 INFO - 'tp6_facebook']}, 02:11:03 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:11:03 INFO - 'talos_options': ['--disable-stylo', 02:11:03 INFO - '--mitmproxy', 02:11:03 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:11:03 INFO - '--firstNonBlankPaint'], 02:11:03 INFO - 'tests': ['tp6_google', 02:11:03 INFO - 'tp6_youtube', 02:11:03 INFO - 'tp6_amazon', 02:11:03 INFO - 'tp6_facebook']}, 02:11:03 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:11:03 INFO - 'talos_options': ['--stylo-threads=1', 02:11:03 INFO - '--mitmproxy', 02:11:03 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:11:03 INFO - '--firstNonBlankPaint'], 02:11:03 INFO - 'tests': ['tp6_google', 02:11:03 INFO - 'tp6_youtube', 02:11:03 INFO - 'tp6_amazon', 02:11:03 INFO - 'tp6_facebook']}, 02:11:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:11:03 INFO - 'talos_options': ['--xperf_path', 02:11:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:11:03 INFO - 'tests': ['tp5n']}, 02:11:03 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:11:03 INFO - 'talos_options': ['--disable-stylo', 02:11:03 INFO - '--xperf_path', 02:11:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:11:03 INFO - 'tests': ['tp5n']}}} 02:11:03 INFO - Running post-action listener: _resource_record_post_action 02:11:03 INFO - [mozharness: 2017-09-10 09:11:03.360738Z] Finished populate-webroot step (success) 02:11:03 INFO - [mozharness: 2017-09-10 09:11:03.360827Z] Running create-virtualenv step. 02:11:03 INFO - Running pre-action listener: _resource_record_pre_action 02:11:03 INFO - Running main action method: create_virtualenv 02:11:03 INFO - Creating virtualenv /builds/slave/test/build/venv 02:11:03 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:11:03 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:11:03 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:11:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:11:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:11:04 INFO - Installing distribute.............................................................................................................................................................................................done. 02:11:04 INFO - Installing pip...............done. 02:11:04 INFO - Return code: 0 02:11:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:11:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:11:04 INFO - Reading from file tmpfile_stdout 02:11:04 INFO - Output received: 02:11:04 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:11:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:11: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')]} 02:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11: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')]} 02:11:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:04 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 0x19d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d42a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d46090>, '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 0x1d46d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d40f90>, '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': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', '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 02:11:04 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 02:11:04 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 02:11:04 INFO - Using env: {'DISPLAY': ':0', 02:11:04 INFO - 'HOME': '/home/cltbld', 02:11:04 INFO - 'LANG': 'en_US.UTF-8', 02:11:04 INFO - 'LANGUAGE': 'en_US:en', 02:11:04 INFO - 'LOGNAME': 'cltbld', 02:11:04 INFO - 'MAIL': '/var/mail/cltbld', 02:11:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:04 INFO - 'MOZ_NO_REMOTE': '1', 02:11:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:04 INFO - 'NO_EM_RESTART': '1', 02:11:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:04 INFO - 'PWD': '/builds/slave/test', 02:11:04 INFO - 'SHELL': '/bin/bash', 02:11:04 INFO - 'SHLVL': '1', 02:11:04 INFO - 'TERM': 'linux', 02:11:04 INFO - 'TMOUT': '86400', 02:11:04 INFO - 'USER': 'cltbld', 02:11:04 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:04 INFO - '_': '/tools/buildbot/bin/python'} 02:11:13 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:11:13 INFO - Downloading/unpacking pip>=1.5 02:11:13 INFO - Running setup.py egg_info for package pip 02:11:13 INFO - warning: no files found matching 'pip/cacert.pem' 02:11:13 INFO - warning: no files found matching '*.html' under directory 'docs' 02:11:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:11:13 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:11:13 INFO - Installing collected packages: pip 02:11:13 INFO - Found existing installation: pip 1.1 02:11:13 INFO - Uninstalling pip: 02:11:13 INFO - Successfully uninstalled pip 02:11:13 INFO - Running setup.py install for pip 02:11:13 INFO - warning: no files found matching 'pip/cacert.pem' 02:11:13 INFO - warning: no files found matching '*.html' under directory 'docs' 02:11:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:11:13 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:11:13 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:11:13 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:11:13 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:11:13 INFO - Successfully installed pip 02:11:13 INFO - Cleaning up... 02:11:13 INFO - Return code: 0 02:11:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:11: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')]} 02:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11: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')]} 02:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11: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', '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 0x19d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d42a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d46090>, '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 0x1d46d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d40f90>, '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': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', '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 02:11: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:11: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 psutil>=3.1.1 02:11:13 INFO - Using env: {'DISPLAY': ':0', 02:11:13 INFO - 'HOME': '/home/cltbld', 02:11:13 INFO - 'LANG': 'en_US.UTF-8', 02:11:13 INFO - 'LANGUAGE': 'en_US:en', 02:11:13 INFO - 'LOGNAME': 'cltbld', 02:11:13 INFO - 'MAIL': '/var/mail/cltbld', 02:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:13 INFO - 'MOZ_NO_REMOTE': '1', 02:11:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:13 INFO - 'NO_EM_RESTART': '1', 02:11:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:13 INFO - 'PWD': '/builds/slave/test', 02:11:13 INFO - 'SHELL': '/bin/bash', 02:11:13 INFO - 'SHLVL': '1', 02:11:13 INFO - 'TERM': 'linux', 02:11:13 INFO - 'TMOUT': '86400', 02:11:13 INFO - 'USER': 'cltbld', 02:11:13 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:13 INFO - '_': '/tools/buildbot/bin/python'} 02:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:13 INFO - Downloading/unpacking psutil>=3.1.1 02:11: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 02:11: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 02:11: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 02:11: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 02:11:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:11:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:11:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:11:14 INFO - Installing collected packages: psutil 02:11:14 INFO - Running setup.py install for psutil 02:11:14 INFO - building 'psutil._psutil_linux' extension 02:11:14 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 02:11:15 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 02:11:15 INFO - building 'psutil._psutil_posix' extension 02:11:15 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 02:11:15 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 02:11:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:11:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:11:15 INFO - Successfully installed psutil 02:11:15 INFO - Cleaning up... 02:11:15 INFO - Return code: 0 02:11:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:15 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 0x19d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d42a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d46090>, '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 0x1d46d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d40f90>, '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': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', '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 02:11:15 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 02:11:15 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 02:11:15 INFO - Using env: {'DISPLAY': ':0', 02:11:15 INFO - 'HOME': '/home/cltbld', 02:11:15 INFO - 'LANG': 'en_US.UTF-8', 02:11:15 INFO - 'LANGUAGE': 'en_US:en', 02:11:15 INFO - 'LOGNAME': 'cltbld', 02:11:15 INFO - 'MAIL': '/var/mail/cltbld', 02:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:15 INFO - 'MOZ_NO_REMOTE': '1', 02:11:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:15 INFO - 'NO_EM_RESTART': '1', 02:11:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:15 INFO - 'PWD': '/builds/slave/test', 02:11:15 INFO - 'SHELL': '/bin/bash', 02:11:15 INFO - 'SHLVL': '1', 02:11:15 INFO - 'TERM': 'linux', 02:11:15 INFO - 'TMOUT': '86400', 02:11:15 INFO - 'USER': 'cltbld', 02:11:15 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:15 INFO - '_': '/tools/buildbot/bin/python'} 02:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:11: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 02:11: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 02:11: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 02:11: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 02:11:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:11:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:11:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:11:16 INFO - Installing collected packages: mozsystemmonitor 02:11:16 INFO - Running setup.py install for mozsystemmonitor 02:11:16 INFO - Successfully installed mozsystemmonitor 02:11:16 INFO - Cleaning up... 02:11:16 INFO - Return code: 0 02:11:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:11:16 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')]} 02:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:16 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')]} 02:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:16 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 0x19d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d42a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d46090>, '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 0x1d46d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d40f90>, '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': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', '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 02:11:16 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 02:11:16 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 02:11:16 INFO - Using env: {'DISPLAY': ':0', 02:11:16 INFO - 'HOME': '/home/cltbld', 02:11:16 INFO - 'LANG': 'en_US.UTF-8', 02:11:16 INFO - 'LANGUAGE': 'en_US:en', 02:11:16 INFO - 'LOGNAME': 'cltbld', 02:11:16 INFO - 'MAIL': '/var/mail/cltbld', 02:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:16 INFO - 'MOZ_NO_REMOTE': '1', 02:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:16 INFO - 'NO_EM_RESTART': '1', 02:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:16 INFO - 'PWD': '/builds/slave/test', 02:11:16 INFO - 'SHELL': '/bin/bash', 02:11:16 INFO - 'SHLVL': '1', 02:11:16 INFO - 'TERM': 'linux', 02:11:16 INFO - 'TMOUT': '86400', 02:11:16 INFO - 'USER': 'cltbld', 02:11:16 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:16 INFO - '_': '/tools/buildbot/bin/python'} 02:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:17 INFO - Downloading/unpacking jsonschema==2.5.1 02:11:17 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 02:11:17 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 02:11:17 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 02:11:17 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 02:11:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:11:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:18 INFO - Downloading functools32-3.2.3-2.tar.gz 02:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:11:18 INFO - warning: no files found matching '*.txt' 02:11:18 INFO - no previously-included directories found matching 'build' 02:11:18 INFO - no previously-included directories found matching 'dist' 02:11:18 INFO - no previously-included directories found matching '.git*' 02:11:18 INFO - Installing collected packages: jsonschema, functools32 02:11:18 INFO - Running setup.py install for functools32 02:11:18 INFO - warning: no files found matching '*.txt' 02:11:18 INFO - no previously-included directories found matching 'build' 02:11:18 INFO - no previously-included directories found matching 'dist' 02:11:18 INFO - no previously-included directories found matching '.git*' 02:11:18 INFO - Successfully installed jsonschema functools32 02:11:18 INFO - Cleaning up... 02:11:18 INFO - Return code: 0 02:11:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d42a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d46090>, '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 0x1d46d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d40f90>, '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': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', '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 02:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:11:18 INFO - Using env: {'DISPLAY': ':0', 02:11:18 INFO - 'HOME': '/home/cltbld', 02:11:18 INFO - 'LANG': 'en_US.UTF-8', 02:11:18 INFO - 'LANGUAGE': 'en_US:en', 02:11:18 INFO - 'LOGNAME': 'cltbld', 02:11:18 INFO - 'MAIL': '/var/mail/cltbld', 02:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:18 INFO - 'MOZ_NO_REMOTE': '1', 02:11:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:18 INFO - 'NO_EM_RESTART': '1', 02:11:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:18 INFO - 'PWD': '/builds/slave/test', 02:11:18 INFO - 'SHELL': '/bin/bash', 02:11:18 INFO - 'SHLVL': '1', 02:11:18 INFO - 'TERM': 'linux', 02:11:18 INFO - 'TMOUT': '86400', 02:11:18 INFO - 'USER': 'cltbld', 02:11:18 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:18 INFO - '_': '/tools/buildbot/bin/python'} 02:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:11:18 INFO - Cleaning up... 02:11:18 INFO - Return code: 0 02:11:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d42a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d46090>, '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 0x1d46d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d40f90>, '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': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', '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 02:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:11:18 INFO - Using env: {'DISPLAY': ':0', 02:11:18 INFO - 'HOME': '/home/cltbld', 02:11:18 INFO - 'LANG': 'en_US.UTF-8', 02:11:18 INFO - 'LANGUAGE': 'en_US:en', 02:11:18 INFO - 'LOGNAME': 'cltbld', 02:11:18 INFO - 'MAIL': '/var/mail/cltbld', 02:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:18 INFO - 'MOZ_NO_REMOTE': '1', 02:11:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:18 INFO - 'NO_EM_RESTART': '1', 02:11:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:18 INFO - 'PWD': '/builds/slave/test', 02:11:18 INFO - 'SHELL': '/bin/bash', 02:11:18 INFO - 'SHLVL': '1', 02:11:18 INFO - 'TERM': 'linux', 02:11:18 INFO - 'TMOUT': '86400', 02:11:18 INFO - 'USER': 'cltbld', 02:11:18 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:18 INFO - '_': '/tools/buildbot/bin/python'} 02:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:19 INFO - Downloading/unpacking blobuploader==1.2.4 02:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:20 INFO - Downloading blobuploader-1.2.4.tar.gz 02:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:11:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:11:20 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 02:11:20 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 02:11:20 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 02:11:20 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 02:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:11:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:11:20 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 02:11:20 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 02:11:20 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 02:11:20 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 02:11:20 INFO - Downloading docopt-0.6.1.tar.gz 02:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:11:20 INFO - Installing collected packages: blobuploader, requests, docopt 02:11:20 INFO - Running setup.py install for blobuploader 02:11:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:11:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:11:20 INFO - Running setup.py install for requests 02:11:21 INFO - Running setup.py install for docopt 02:11:21 INFO - Successfully installed blobuploader requests docopt 02:11:21 INFO - Cleaning up... 02:11:21 INFO - Return code: 0 02:11:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:21 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 0x19d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d42a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d46090>, '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 0x1d46d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d40f90>, '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': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', '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 02:11:21 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 02:11:21 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 02:11:21 INFO - Using env: {'DISPLAY': ':0', 02:11:21 INFO - 'HOME': '/home/cltbld', 02:11:21 INFO - 'LANG': 'en_US.UTF-8', 02:11:21 INFO - 'LANGUAGE': 'en_US:en', 02:11:21 INFO - 'LOGNAME': 'cltbld', 02:11:21 INFO - 'MAIL': '/var/mail/cltbld', 02:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:21 INFO - 'MOZ_NO_REMOTE': '1', 02:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:21 INFO - 'NO_EM_RESTART': '1', 02:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:21 INFO - 'PWD': '/builds/slave/test', 02:11:21 INFO - 'SHELL': '/bin/bash', 02:11:21 INFO - 'SHLVL': '1', 02:11:21 INFO - 'TERM': 'linux', 02:11:21 INFO - 'TMOUT': '86400', 02:11:21 INFO - 'USER': 'cltbld', 02:11:21 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:21 INFO - '_': '/tools/buildbot/bin/python'} 02:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:11:21 INFO - Running setup.py (path:/tmp/pip-_ElLgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:11:21 INFO - Running setup.py (path:/tmp/pip-wPiOlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:11:21 INFO - Running setup.py (path:/tmp/pip-_td_lV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:11:21 INFO - Running setup.py (path:/tmp/pip-wK73ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:11:21 INFO - Running setup.py (path:/tmp/pip-j47cR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:11:21 INFO - Running setup.py (path:/tmp/pip-x7Gn30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:11:21 INFO - Running setup.py (path:/tmp/pip-uTIHY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:11:22 INFO - Running setup.py (path:/tmp/pip-M10WvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:11:22 INFO - Running setup.py (path:/tmp/pip-ks0bI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:11:22 INFO - Running setup.py (path:/tmp/pip-qB0wI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:11:22 INFO - Running setup.py (path:/tmp/pip-ugvff6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:11:22 INFO - Running setup.py (path:/tmp/pip-lCS30h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:11:22 INFO - Running setup.py (path:/tmp/pip-GGh6Ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:11:22 INFO - Running setup.py (path:/tmp/pip-jmXHYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:11:22 INFO - Running setup.py (path:/tmp/pip-79xkfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:11:22 INFO - Running setup.py (path:/tmp/pip-lTA4wN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:11:22 INFO - Running setup.py (path:/tmp/pip-j833CE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:11:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:11:22 INFO - Running setup.py install for manifestparser 02:11:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:11:22 INFO - Running setup.py install for mozcrash 02:11:23 INFO - Running setup.py install for mozdebug 02:11:23 INFO - Running setup.py install for mozdevice 02:11:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:11:23 INFO - Running setup.py install for mozfile 02:11:23 INFO - Running setup.py install for mozhttpd 02:11:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:11:23 INFO - Running setup.py install for mozinfo 02:11:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:11:23 INFO - Running setup.py install for mozInstall 02:11:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:11:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:11:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:11:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:11:23 INFO - Running setup.py install for mozleak 02:11:23 INFO - Running setup.py install for mozlog 02:11:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:11:23 INFO - Running setup.py install for moznetwork 02:11:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:11:23 INFO - Running setup.py install for mozprocess 02:11:23 INFO - Running setup.py install for mozprofile 02:11:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:11:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:11:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:11:24 INFO - Running setup.py install for mozrunner 02:11:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:11:24 INFO - Running setup.py install for mozscreenshot 02:11:24 INFO - Running setup.py install for moztest 02:11:24 INFO - Running setup.py install for mozversion 02:11:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:11:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:11:24 INFO - Cleaning up... 02:11:24 INFO - Return code: 0 02:11:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:11:24 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')]} 02:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:24 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')]} 02:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:24 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 0x19d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d42a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d46090>, '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 0x1d46d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d40f90>, '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': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', '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 02:11:24 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 02:11:24 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 02:11:24 INFO - Using env: {'DISPLAY': ':0', 02:11:24 INFO - 'HOME': '/home/cltbld', 02:11:24 INFO - 'LANG': 'en_US.UTF-8', 02:11:24 INFO - 'LANGUAGE': 'en_US:en', 02:11:24 INFO - 'LOGNAME': 'cltbld', 02:11:24 INFO - 'MAIL': '/var/mail/cltbld', 02:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:24 INFO - 'MOZ_NO_REMOTE': '1', 02:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:24 INFO - 'NO_EM_RESTART': '1', 02:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:24 INFO - 'PWD': '/builds/slave/test', 02:11:24 INFO - 'SHELL': '/bin/bash', 02:11:24 INFO - 'SHLVL': '1', 02:11:24 INFO - 'TERM': 'linux', 02:11:24 INFO - 'TMOUT': '86400', 02:11:24 INFO - 'USER': 'cltbld', 02:11:24 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:24 INFO - '_': '/tools/buildbot/bin/python'} 02:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:11:24 INFO - Running setup.py (path:/tmp/pip-qN7aao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:11:24 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)) 02:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:11:24 INFO - Running setup.py (path:/tmp/pip-iUj7gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:11:24 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)) 02:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:11:24 INFO - Running setup.py (path:/tmp/pip-tAla5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:11:24 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)) 02:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:11:24 INFO - Running setup.py (path:/tmp/pip-RgLltY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:11:25 INFO - Running setup.py (path:/tmp/pip-YQSdic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:11:25 INFO - Running setup.py (path:/tmp/pip-WsZFZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:11:25 INFO - Running setup.py (path:/tmp/pip-V6K_Iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:11:25 INFO - Running setup.py (path:/tmp/pip-4PT2RH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:11:25 INFO - Running setup.py (path:/tmp/pip-hrcEBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:11:25 INFO - Running setup.py (path:/tmp/pip-pEW92D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:11:25 INFO - Running setup.py (path:/tmp/pip-eOs0Qz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:11:25 INFO - Running setup.py (path:/tmp/pip-GuLr5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:11:25 INFO - Running setup.py (path:/tmp/pip-W21UFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:11:25 INFO - Running setup.py (path:/tmp/pip-eRjjjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:11:25 INFO - Running setup.py (path:/tmp/pip-vLMEHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:11:25 INFO - Running setup.py (path:/tmp/pip-rQ00I6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:11:25 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)) 02:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:11:25 INFO - Running setup.py (path:/tmp/pip-qDPWBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:11:25 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)) 02:11:25 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)) 02:11:25 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)) 02:11:26 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)) 02:11:26 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)) 02:11:26 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)) 02:11:26 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)) 02:11:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:27 INFO - Downloading blessings-1.6.tar.gz 02:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:11:27 INFO - Installing collected packages: blessings 02:11:27 INFO - Running setup.py install for blessings 02:11:27 INFO - Successfully installed blessings 02:11:27 INFO - Cleaning up... 02:11:27 INFO - Return code: 0 02:11:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:11:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:11:27 INFO - Reading from file tmpfile_stdout 02:11:27 INFO - Current package versions: 02:11:27 INFO - argparse == 1.2.1 02:11:27 INFO - blessings == 1.6 02:11:27 INFO - blobuploader == 1.2.4 02:11:27 INFO - docopt == 0.6.1 02:11:27 INFO - functools32 == 3.2.3-2 02:11:27 INFO - jsonschema == 2.5.1 02:11:27 INFO - manifestparser == 1.1 02:11:27 INFO - mozInstall == 1.13 02:11:27 INFO - mozcrash == 1.0 02:11:27 INFO - mozdebug == 0.1 02:11:27 INFO - mozdevice == 0.51 02:11:27 INFO - mozfile == 1.2 02:11:27 INFO - mozhttpd == 0.7 02:11:27 INFO - mozinfo == 0.10 02:11:27 INFO - mozleak == 0.1 02:11:27 INFO - mozlog == 3.5 02:11:27 INFO - moznetwork == 0.27 02:11:27 INFO - mozprocess == 0.25 02:11:27 INFO - mozprofile == 0.28 02:11:27 INFO - mozrunner == 6.13 02:11:27 INFO - mozscreenshot == 0.1 02:11:27 INFO - mozsystemmonitor == 0.3 02:11:27 INFO - moztest == 0.8 02:11:27 INFO - mozversion == 1.4 02:11:27 INFO - psutil == 3.1.1 02:11:27 INFO - requests == 1.2.3 02:11:27 INFO - wsgiref == 0.1.2 02:11:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:27 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 0x19d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d42a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d46090>, '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 0x1d46d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d40f90>, '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': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', '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 02:11:27 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 02:11:27 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 02:11:27 INFO - Using env: {'DISPLAY': ':0', 02:11:27 INFO - 'HOME': '/home/cltbld', 02:11:27 INFO - 'LANG': 'en_US.UTF-8', 02:11:27 INFO - 'LANGUAGE': 'en_US:en', 02:11:27 INFO - 'LOGNAME': 'cltbld', 02:11:27 INFO - 'MAIL': '/var/mail/cltbld', 02:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:27 INFO - 'MOZ_NO_REMOTE': '1', 02:11:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:27 INFO - 'NO_EM_RESTART': '1', 02:11:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:27 INFO - 'PWD': '/builds/slave/test', 02:11:27 INFO - 'SHELL': '/bin/bash', 02:11:27 INFO - 'SHLVL': '1', 02:11:27 INFO - 'TERM': 'linux', 02:11:27 INFO - 'TMOUT': '86400', 02:11:27 INFO - 'USER': 'cltbld', 02:11:27 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:27 INFO - '_': '/tools/buildbot/bin/python'} 02:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:27 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)) 02:11:27 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)) 02:11:27 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)) 02:11:27 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)) 02:11:27 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)) 02:11:27 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)) 02:11:27 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)) 02:11:27 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)) 02:11:27 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)) 02:11:27 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:11:28 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)) 02:11:28 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)) 02:11:28 INFO - Installing collected packages: simplejson 02:11:28 INFO - Running setup.py install for simplejson 02:11:29 INFO - building 'simplejson._speedups' extension 02:11:29 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 02:11:29 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 02:11:29 INFO - Successfully installed simplejson 02:11:29 INFO - Cleaning up... 02:11:29 INFO - Return code: 0 02:11:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:11:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d42a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d46090>, '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 0x1d46d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d40f90>, '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': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', '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 02:11:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:11:29 INFO - Using env: {'DISPLAY': ':0', 02:11:29 INFO - 'HOME': '/home/cltbld', 02:11:29 INFO - 'LANG': 'en_US.UTF-8', 02:11:29 INFO - 'LANGUAGE': 'en_US:en', 02:11:29 INFO - 'LOGNAME': 'cltbld', 02:11:29 INFO - 'MAIL': '/var/mail/cltbld', 02:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:29 INFO - 'MOZ_NO_REMOTE': '1', 02:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:29 INFO - 'NO_EM_RESTART': '1', 02:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:29 INFO - 'PWD': '/builds/slave/test', 02:11:29 INFO - 'SHELL': '/bin/bash', 02:11:29 INFO - 'SHLVL': '1', 02:11:29 INFO - 'TERM': 'linux', 02:11:29 INFO - 'TMOUT': '86400', 02:11:29 INFO - 'USER': 'cltbld', 02:11:29 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:29 INFO - '_': '/tools/buildbot/bin/python'} 02:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:11:30 INFO - Cleaning up... 02:11:30 INFO - Return code: 0 02:11:30 INFO - Running post-action listener: _resource_record_post_action 02:11:30 INFO - Running post-action listener: _start_resource_monitoring 02:11:30 INFO - Starting resource monitoring. 02:11:30 INFO - [mozharness: 2017-09-10 09:11:30.243369Z] Finished create-virtualenv step (success) 02:11:30 INFO - [mozharness: 2017-09-10 09:11:30.243931Z] Running install step. 02:11:30 INFO - Running pre-action listener: _resource_record_pre_action 02:11:30 INFO - Running main action method: install 02:11:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:11:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:11:30 INFO - Reading from file tmpfile_stdout 02:11:30 INFO - Detecting whether we're running mozinstall >=1.0... 02:11:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:11:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:11:30 INFO - Reading from file tmpfile_stdout 02:11:30 INFO - Output received: 02:11:30 INFO - Usage: mozinstall [options] installer 02:11:30 INFO - Options: 02:11:30 INFO - -h, --help show this help message and exit 02:11:30 INFO - -d DEST, --destination=DEST 02:11:30 INFO - Directory to install application into. [default: 02:11:30 INFO - "/builds/slave/test"] 02:11:30 INFO - --app=APP Application being installed. [default: firefox] 02:11:30 INFO - mkdir: /builds/slave/test/build/application 02:11:30 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'] 02:11:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:11:47 INFO - Reading from file tmpfile_stdout 02:11:47 INFO - Output received: 02:11:47 INFO - /builds/slave/test/build/application/firefox/firefox 02:11:47 INFO - Running post-action listener: _resource_record_post_action 02:11:47 INFO - [mozharness: 2017-09-10 09:11:47.307520Z] Finished install step (success) 02:11:47 INFO - [mozharness: 2017-09-10 09:11:47.307783Z] Running setup-mitmproxy step. 02:11:47 INFO - Running pre-action listener: _resource_record_pre_action 02:11:47 INFO - Running main action method: setup_mitmproxy 02:11:47 INFO - Skipping: mitmproxy is not required 02:11:47 INFO - Running post-action listener: _resource_record_post_action 02:11:47 INFO - [mozharness: 2017-09-10 09:11:47.308911Z] Finished setup-mitmproxy step (success) 02:11:47 INFO - [mozharness: 2017-09-10 09:11:47.309131Z] Running run-tests step. 02:11:47 INFO - Running pre-action listener: _resource_record_pre_action 02:11:47 INFO - Running pre-action listener: _set_gcov_prefix 02:11:47 INFO - Running main action method: run_tests 02:11:47 WARNING - Try message not found. 02:11:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:11:47 INFO - Python 2.7.3 02:11:47 INFO - Return code: 0 02:11:47 INFO - grabbing minidump binary from tooltool 02:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:47 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 0x1d46090>, '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 0x1d46d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d40f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:11:47 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 02:11:47 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 02:11:47 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 02:11:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:11:47 INFO - Return code: 0 02:11:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:11:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:11:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:11:47 INFO - ENV: RUST_BACKTRACE is now full 02:11:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:11:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:11:47 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/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 02:11:47 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/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 02:11:47 INFO - Using env: {'DISPLAY': ':0', 02:11:47 INFO - 'HOME': '/home/cltbld', 02:11:47 INFO - 'LANG': 'en_US.UTF-8', 02:11:47 INFO - 'LANGUAGE': 'en_US:en', 02:11:47 INFO - 'LOGNAME': 'cltbld', 02:11:47 INFO - 'MAIL': '/var/mail/cltbld', 02:11:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:11:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:11:47 INFO - 'MOZ_ACCELERATED': '1', 02:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:47 INFO - 'MOZ_NO_REMOTE': '1', 02:11:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:11:47 INFO - 'MOZ_WEBRENDER': '1', 02:11:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:47 INFO - 'NO_EM_RESTART': '1', 02:11:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:47 INFO - 'PWD': '/builds/slave/test', 02:11:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:11:47 INFO - 'RUST_BACKTRACE': 'full', 02:11:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:11:47 INFO - 'SHELL': '/bin/bash', 02:11:47 INFO - 'SHLVL': '1', 02:11:47 INFO - 'TERM': 'linux', 02:11:47 INFO - 'TMOUT': '86400', 02:11:47 INFO - 'USER': 'cltbld', 02:11:47 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:47 INFO - '_': '/tools/buildbot/bin/python'} 02:11:47 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/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 02:11:47 INFO - mozversion application_buildid: 20170910080726 02:11:47 INFO - mozversion application_changeset: 00baeed193d4f276694912f0298336880280837d 02:11:47 INFO - mozversion application_display_name: Nightly 02:11:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:11:47 INFO - mozversion application_name: Firefox 02:11:47 INFO - mozversion application_remotingname: firefox 02:11:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:11:47 INFO - mozversion application_vendor: Mozilla 02:11:47 INFO - mozversion application_version: 57.0a1 02:11:47 INFO - mozversion platform_buildid: 20170910080726 02:11:47 INFO - mozversion platform_changeset: 00baeed193d4f276694912f0298336880280837d 02:11:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:11:47 INFO - mozversion platform_version: 57.0a1 02:11:47 INFO - using testdate: 1505034707 02:11:47 INFO - actual date: 1505034707 02:11:47 INFO - starting webserver on 'localhost:55116' 02:11:47 INFO - SUITE-START | Running 2 tests 02:11:47 INFO - TEST-START | dromaeo_css 02:11:47 INFO - Initialising browser for dromaeo_css test... 02:11:47 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55116/getInfo.html -profile /tmp/tmpz23yXu/profile 02:11:47 INFO - TEST-INFO | started process 31266 (/builds/slave/test/build/application/firefox/firefox http://localhost:55116/getInfo.html) 02:11:54 INFO - TEST-INFO | 31266: exit 0 02:11:55 INFO - Browser initialized. 02:11:55 INFO - Running cycle 1/1 for dromaeo_css test... 02:11:55 INFO - Using env: {'DISPLAY': ':0', 02:11:55 INFO - 'HOME': '/home/cltbld', 02:11:55 INFO - 'JSGC_DISABLE_POISONING': '1', 02:11:55 INFO - 'LANG': 'en_US.UTF-8', 02:11:55 INFO - 'LANGUAGE': 'en_US:en', 02:11:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:11:55 INFO - 'LOGNAME': 'cltbld', 02:11:55 INFO - 'MAIL': '/var/mail/cltbld', 02:11:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:11:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:11:55 INFO - 'MOZ_ACCELERATED': '1', 02:11:55 INFO - 'MOZ_CRASHREPORTER': '1', 02:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:11:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:11:55 INFO - 'MOZ_NO_REMOTE': '1', 02:11:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:11:55 INFO - 'MOZ_WEBRENDER': '1', 02:11:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:11:55 INFO - 'NO_EM_RESTART': '1', 02:11:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:11:55 INFO - 'PWD': '/builds/slave/test', 02:11:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:11:55 INFO - 'RUST_BACKTRACE': 'full', 02:11:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:11:55 INFO - 'SHELL': '/bin/bash', 02:11:55 INFO - 'SHLVL': '1', 02:11:55 INFO - 'TERM': 'linux', 02:11:55 INFO - 'TMOUT': '86400', 02:11:55 INFO - 'USER': 'cltbld', 02:11:55 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:11:55 INFO - '_': '/tools/buildbot/bin/python'} 02:11:55 INFO - TEST-INFO | started process 31474 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpz23yXu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:11:55 INFO - PID 31474 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:11:56 INFO - PID 31474 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:11:56 INFO - PID 31474 | 02:11:56 INFO - PID 31474 | (/builds/slave/test/build/application/firefox/firefox:31535): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:11:56 INFO - PID 31474 | 02:12:06 INFO - PID 31474 | RSS: Main: 214802432 02:12:06 INFO - PID 31474 | 02:15:04 INFO - PID 31474 | Cycle 1(1): loaded http://localhost:55116/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55116/tests/dromaeo/cssquery-ext.html) 02:15:06 INFO - PID 31474 | RSS: Main: 224509952 02:15:06 INFO - PID 31474 | 02:18:08 INFO - PID 31474 | Cycle 1(1): loaded http://localhost:55116/tests/dromaeo/cssquery-ext.html (next: http://localhost:55116/tests/dromaeo/cssquery-jquery.html) 02:18:08 INFO - PID 31474 | RSS: Main: 227766272 02:18:08 INFO - PID 31474 | 02:21:11 INFO - PID 31474 | Cycle 1(1): loaded http://localhost:55116/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55116/tests/dromaeo/cssquery-mootools.html) 02:21:11 INFO - PID 31474 | RSS: Main: 227196928 02:21:11 INFO - PID 31474 | 02:24:13 INFO - PID 31474 | Cycle 1(1): loaded http://localhost:55116/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55116/tests/dromaeo/cssquery-prototype.html) 02:24:14 INFO - PID 31474 | RSS: Main: 223080448 02:24:14 INFO - PID 31474 | 02:27:18 INFO - PID 31474 | Cycle 1(1): loaded http://localhost:55116/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55116/tests/dromaeo/cssquery-yui.html) 02:27:18 INFO - PID 31474 | RSS: Main: 226422784 02:27:18 INFO - PID 31474 | 02:30:20 INFO - PID 31474 | Cycle 1(1): loaded http://localhost:55116/tests/dromaeo/cssquery-yui.html (next: http://localhost:55116/tests/dromaeo/cssquery-dojo.html) 02:30:20 INFO - PID 31474 | RSS: Main: 232505344 02:30:20 INFO - PID 31474 | 02:30:20 INFO - PID 31474 | __start_tp_report 02:30:20 INFO - PID 31474 | _x_x_mozilla_page_load 02:30:20 INFO - PID 31474 | _x_x_mozilla_page_load_details 02:30:20 INFO - PID 31474 | |i|pagename|runs| 02:30:20 INFO - PID 31474 | |0;dojo.html;3093.597832281803;3118.501039833627;3120.7035331643488;3126.968730312696;3173.682631736827;6093.116498107783;6137.846553836156;6184.165137706408;6260.99824028155;6315.179026726519;6170.413810687989;6197.349278325779;6222.782202622909;6298.779542716006;6306.527010474221;5695.658260504372;5723.2559767230205;5732.828015159531;5760.827175184731;5797.101449275369;5762.481376676119;5775.740091695884;5842.64944103353;5868.794592189274;5884.911726324109;5764.625299355528;5824.970875145618;5839.474447299742;5844.152597873306;5869.735861886204;5772.797952071699;5831.154482599999;5837.182794408786;5894.557908156884;5916.852078698024;5526.171074338841;5652.349979752299;5661.60368774186;5702.372738998707;5807;6179.969100154515;6287.056941458772;6317.589356691847;6353.237611486605;6357.809265722036;6491.93508064918;6705.4970877184405;6736.898946515757;6782.457403407716;6825.726970921157;6690;6716.429103526206;6758.25659177495;6763.425108865752;6789.456843452562;6273.62358258506;6280.87438251237;6288.937110628881;6295.024271237966;6295.811125666238;6847.863042739217;6889.896651550231;6925.445964322842;6939.097917270722;6986.091808065021;6627.3372662733345;6629.602223866584;6700.698468568926;6702.597844129368;6727.7981660549285;6743.123393958754;6749.392554670103;6833.180018397725;6838.418734407532;6841.042254084432;6489.383508566679;6632.005199220155;6639.468842492589;6673.532852700265;6698.5980841149685;6571.244306904671;6710.228323742735;6834.2482326944;6843.828904277333;6850.212225594021;5335.919961200585;5351.919721204185;5357.517823395835;5359.758810853521;5369.0872551665525;4593.081383723269;4610.262358022767;4646.465656449484;4653.325267836145;4659.906801863944;5588.552915766729;5684.6020778545935;5685.829425117253;5689.345725241567;5887.234659494238;6670.332966703388;6683.899741503881;6747.359000894907;6749.785038692984;6797.898031529531;5763.366029736726;5788.507976822018;5832.387599302012;5845.824625261249;5871.500922421642;5784.479396854304;5793.246877905845;5843.707814609253;5851.882962340815;5876.6180198286975;6258.280297765724;6395.488360931115;6513.478921686254;6546.476281897437;6610.173728283965;6630.138082049302;6765.526413151032;6832.419244364285;6842.213145488333;6851.725930962905;6411.967940160456;6429.97120460724;6468.256150542559;6475.546711730133;6488.870222595428;6569.868602628017;6575.24384695747;6664.966675166593;6666.366695163952;6686.765963191265;6624.900626490703;6668.333166683293;6678.23200331968;6764.526483146133;6779.661016949231;6488.772892948724;6539.574927629689;6573.835654108686;6577.375149360803;6590.868182636416;6511.706973186123;6559.7376104957175;6597.736090556324;6617.636030018395;6620.867582648224;6445.935540644534;6457.547971642128;6463.482921366373;6523.282438931618;6562;6294;6446.709898054505;6555.228445044546;6607.273199947906;6673.666316684244;6305.90541141874;6320.336364681642;6363.204599425072;6411.775587854403;6422.518311126778 02:30:20 INFO - PID 31474 | |1;ext.html;825.3273582030645;835.2148979958838;843.0263046181655;844.4679851693433;856.1780690537084;37323.44014839776;41000.58999410005;41022.17955640885;41294;41379;38168.618313816856;38233.80883095587;38268.23463530728;38329.23341533168;38873;6847.452203823707;6934.202566704843;6939.653017349137;6943.062686537303;6965.860682786341;6856.862862742742;6858.725650973955;6877.690503927335;6943.930560694379;6978.965105174467;6932.826679333007;6954.443644508452;6958.338957799001;6963.442924566022;7710.922890771105;7766.650500727453;7777.377809775205;7830.099538553082;7830.8433831323055;7842.725504607311;7640.5797681127515;7691.615419229016;7717.3054425101445;7726.613669316512;7731.45879788415;37852.43221351682;37972.43041354382;38954.61045389538;39096.80451597752;39395.60604393948;3441.9827900860337;3471.5660542432197;3475.2528206435486;3479.7216222702123;3482.1642805726697;32767.6723232767;32882.50676239858;33117.66882331194;33239.83380083108;33585.83207083974;5111.2333150027425;5140.280360749498;5210.6613070150315;5213.895722085575;5230.92153617696;185566;185618;185645;185835;186144.0692796541;41306.793466032475;41959.16081678349;47788.5221147791;47928;48446;50886.74556627193;50900.49099509032;50982.45087745672;50982.49017509777;51260.74369628128;15469.922650386676;15742.527724168292;15745.291461884242;15751.68496630061;15762.842371576367;12773.808392874114;12786.488540458464;12799.10406271552;12811.551595694105;12876.61370158878;13741.862581374258;13779.724405511834;13859.584212473643;13872.890168786473;13948.58154255374;5530.308711411074;5542.473465020817;5597.272354593958;5600.299962504687;5631.774729010794;994.9204063674889;1009.5961615353917;1021.070825548762;1026.9229807764448;1043.0247718383337;23144.801243881804;23204.071837126325;23309.650355244685;23538.64692029621;23571;1106.7067227184803;1108.634150730257;1114.509615769059;1122.5004872831512;1124.8706398764084;11960;12165.561627321946;12274.188075134389;12300.261984280973;12390.442430090667;201309;201846;211532;211998;213004;52524;52793.47206527885;52795.47204527982;53076.73461632667;53134;1417.6951955329653;1455.9053661511969;1462.1884853905972;1463.5024091808837;1465.9706805864037;22399.42406623194;22542.887285563465;22776.20283290077;22821.31536053921;22912.31263062111;37294;37341;37367.81316093402;37409.251814963005;37423.06442338963;195839;196046;196132;197070;197091;29993.550096749004;30257.24356891095;33940.49089263614;34107.82946085254;34417.82791086029;43636;44132;44708.77645611751;44797.776011119735;45392.31911521264;31624.051278461684;31672;32339.514907275938;32345.838270809436;32402.83798580992;31117.8444107778;31446.685533144377;31634.892778753554;31875.521867172476;32491.512627310138;36293.274134516636;36459.81770091239;36476;36522;36724.44913326249;31462.842685787342;31641.36717265688;31659.84170079135;31710.36579268448;31860.5220921691;22768.65847012263;23248.4187895304;23318.883405582863;23510.059597615902;23632.645510317016 02:30:20 INFO - PID 31474 | |2;jquery.html;562.1343131577371;562.524666656675;566.5326105962581;572.4504475703325;574.3768011707298;21120.260790872322;21953;22063.6690449643;22072.668909966364;22089.889550552245;23284.65073023906;24061.51876962464;24108.638370424458;24123.638145427834;24421.267361979124;6268;6346.619202847821;6370.9681451592905;6373.203349581302;6391.552591318609;6294.653794041325;6400.071989561534;6411.134496842937;6412.166418365606;6412.326705695905;6158.07628855673;6350.047492876082;6451.548391612589;6466.967665161691;6488;6447.742090316405;6499.3175716549795;6512.283648798629;6514;6517.511186660972;6024.698765061773;6050.45545900871;6075.9696201519155;6078.756849726005;6080.14877917092;27792.861035694896;28559.714402855912;29229.123126306244;29290.267743306376;29318.853405732836;1951.668216403215;1990.144237977669;1992.750906136733;1999.5900840327843;2001.979980200194;19343.709844352346;19673.114709837948;19752.308669196653;19781.505462363413;19782.901085494625;2373.196370758219;2417.891194896234;2460.2619214235838;2461.4461746107163;2476.2942561370105;116216;122123;122525.99999999911;130016.3499182498;138080.30959845238;20234.381249500006;20779;20938.581228375653;21678.24126155577;21805.781942180376;41978;42064.579354206675;42182.367264491055;42204.78897605492;42594.57405425968;10715;10803.351798892092;10884.61903833362;10963.342199468058;11023.118150547936;8846.911530884609;8859.11408859109;8970.461772293553;8981.910180898238;8991.685291014783;10853.945730271298;11211.439428028565;11223.55105795759;11314.151438642135;11497;3649.835757390924;3766.3408903441787;3841.4814000110186;3994.320965435883;4031.9395209071886;913.4747520175926;938.5776400619612;938.9577568898517;939.9013103624158;958.7698952251549;19190.520236993907;19588.11853466569;19744.901275493532;19848.305309314394;20205.59588808245;1001.2040427859723;1003.207466101786;1014.0417305646241;1016.3495362967707;1019.2864994503877;10498.265121441578;10727.195460340507;10789.784204315869;10924.836127458095;11060.72801627807;426672;434737;440304;442563;445169;109591;110991;111661.99999999837;111847;112942;1138.103526524996;1147.4033502578613;1209.1596340543415;1210.8607510136271;1226.1539537718945;20601.793982060586;20962;20998.47503812417;21192.894035529724;21209.939503024478;52803.32390971032;52993.7350313246;54727.452725472234;55562;57269.71365143148;68303.31696682969;68685;69645.9553106714;69926.30073699198;70060.64969675119;13772.586822395344;13987.370568324246;14011.649708757363;14013.579592612237;14030.579082627537;23104.884475577514;23109.19117830868;23204.419889502897;23286.883565582062;23422.297331080095;18050.1877415514;18210.544736381165;18253.178606962985;18337.449876503728;18434.26262949467;16139.75790363122;16460;16859.494215173563;16878.409255675993;17003.829961700223;17791.19939602695;17854.91072544629;17864.19611117529;17900.55248618795;18003.72994405059;12717.491300347883;13596.1842289459;13603.79594306106;13618.591442256748;13634;17190.398336058177;17267.82732172662;17403;17598.38405655796;17682.646347073245 02:30:20 INFO - PID 31474 | |3;mootools.html;1312.520929860601;1337.6522104252892;1342.6374878782722;1352.9594112176633;1354.2822304178785;14615.488457903963;15259.694806103871;15304.69390612187;15331.46339878103;15379.384824607003;14836.55490335289;14922.104673719585;14942.178180200111;14956.700865982673;14975.47585834496;2656.428867793419;2741.040635777472;2743.231895069374;2747.4917140329003;2750.422411293621;2692.609571612115;2711.3357227479296;2716.4295497945454;2718.4563087382503;2721.319670082479;2596.9220923372327;2610.125607921339;2789.0656630028966;2792.804503684743;2793.064323451646;2706.972930270692;2753.2428582139873;2771.1686494051905;2775.4449110177843;2781.707920668331;2594.1957993022033;2669.759721625063;2679.1962411276736;2687.771539419152;2690.8250963687296;14390;14545.709085818224;14633.780493292608;14634.707305853823;14701.632459188499;7817.218278172138;8072.515649061075;8084.1107478177355;8093.392995525359;8094.797630059236;4970.478099799541;5215.6349055566125;5298.125809241471;5299.072662284085;5326.387465441447;1905.8856468611789;1939.9806001939942;1944.7569053868267;1979.0500559731265;1980.7821139674625;25177.748222517723;25942.22173334803;25998.22005339843;26004.86997565019;26095.73904260952;6892.172939247323;6965.164180298295;7015.3686168244085;7141.143062832493;7226.458015648847;19509.122089506007;19731.309404171072;19741.901290493457;19753.40739777809;19792.50518737014;7893.13175550689;8014.398920080901;8079.757607271791;8115.918840811634;8125.715599953974;6992.370686638227;7051.647417629098;7091.361777440055;7299.817504562322;7350.485466017328;5387.515123638891;5557.77768889248;5650;5682.119271512922;5704.543636509153;1631.8776091793163;1677.1698009485383;1679.6892574873686;1697.5331783759566;1728.671552405039;846.9407141500159;867.800405906645;875.5840975536696;897.3808072430003;898.7977705016319;7521.7743467696055;7570.129435114922;7675.884861727079;8468;8630.352723545759;682.474494639125;702.4275215699189;709.0604948443333;712.4870093532649;718.1382341190592;4988.940037065845;5178;5244.947550524446;5259.552938000236;5264.710440925785;19497;20234.19063237454;20319.695204571944;20600.793992060186;21095.57808843845;19949.501262468555;20695.17219311241;20777.79222207789;20819.06314215864;20975.160993560086;10309.432981186106;10862.456877156268;10882.075023622923;10912.454377281105;10924.726881828017;8278.668853245803;8371.455855369382;8379.70671026511;8395.538245396318;8408.747737567883;17533.473995780147;17903.910480448034;18137.27450902002;18141.092945352942;18434.354797582022;17732.645347093243;18498.260069597603;18657.81342186561;18685.71971420457;19005.90497047561;11306.604268850551;11632.76734465289;11702.590409335633;11726.94136529312;11741.823872641746;13403.731925361633;13434;13540.526081587097;13592.524261650795;13622;10670;10904.945475272572;11014.229003969966;11084.16868734832;11380.601678941197;9850.16273616722;10010.799784004425;10127.898721012696;10177.847332289874;10259.897401026194;8982.505962172143;9258.72223833286;9354;9386.202172815387;9426.62293508252;6569.540132190701;6759.391654751096;6795.25252222265;6821.3519715626935;6825.60075184576;6910.93089069123;7036.2260151384335;7057.964710176417;7084.397826184832;7090.822729431806 02:30:20 INFO - PID 31474 | |4;prototype.html;497.23425924076923;504.07502233401704;524.2372348233316;525.7539631074352;533.663791811159;6151.969240153799;6166.352532984034;6215.192025036739;6284.340144284847;6289.622622642644;6033.607815492001;6059.3637668044885;6111.266648002234;6112.602680825743;6182.443580077792;5063.189889617662;5101.245327523152;5114.43741188469;5122.180451127821;5126.897462050757;4788.952110478903;5016.673916195454;5025.748712564376;5072.898542029176;5105.948940510603;4814.277858321262;4822.7588620569;4853.417589889218;4885.825807515657;4902.362692849924;4838.177509823338;4889.144399730033;4891.51084891508;4903.621743454421;4923.138450771136;4344.565543445662;4354.934675979863;4380.12397520494;4430.113977204572;4433;6028.487578555828;6029.758809647609;6031.819045428644;6043.516518678495;6094.573379863411;5592.944070559283;5603;5608.943910560883;5615.719214039323;5647.774089036434;6415.871682566322;6415.871682566322;6439.967800161016;6487.318831522716;6489.415952564244;4548.954510454886;4760.857174284777;4787.8563643090765;4808.471068182497;4813.494583068762;6581.243157036906;6597.60414375139;6607.504437167184;6639.236487803868;6761.695723692445;4891.266310053449;4897.412310522689;4919.891890271591;4939.851804445872;4945.505449455098;4972.229304457815;4995.400551933792;5013.273075404046;5016.0971025215085;5017.197248440305;5519.227308176858;5526.447355264522;5547.889042219133;5573.944260557424;5598.664080155122;6525.3800888915475;6543.640099794462;6548.148740663683;6563.278039415661;6591.209054913441;1732.2205007746566;1733.9653206936043;1739.3912130754388;1748.7551742756025;1750.3961133408955;1125.7410795516914;1186.7448498572849;1191.267370567095;1207.2032458577296;1219.646302572262;5643.689597072201;5673.829785106371;5689.288838895138;5706.457886500776;5709.25779648643;6562.5890433557015;6576.210854697467;6600.834979125464;6624.304448032984;6625.86748265032;5605.271314729141;5615.213870058114;5617.887642247214;5680.914786278209;5694.775623241023;3332.5006245316013;3501.492283618861;3519.07916429363;3551.3252482028847;3555.8933232003083;6416;6417.807465776034;6421.646809425433;6442.903356449657;6447.709853056624;6745.763898263537;6752.426043786333;6814.420774234147;6890.276520965327;6922.619255940972;6090.33006369299;6182.845428864314;6319.3364696706185;6379.138816259746;6440.549161558646;6660.766873159416;6662.267150613526;6665.633390163587;6707.329267073449;6738.764143254963;6593.439557637655;6594.175728033996;6610.768623098168;6623.602583844985;6650.235222949229;6374.553781235269;6413.775517856852;6430.7749228776765;6436.195475565554;6451.258105317761;4872.634552408513;4913.434954980223;4931.630127740362;4982.6512144149565;4992.450830408579;6247.562670613013;6523.608583484816;6526.021096835512;6528.183977002875;6625.867482650224;6268.686565671789;6336;6365.840853978688;6456.257530384066;6471.935280647133;6333.683315834282;6358.395952384516;6416.647084410402;6419.358064193543;6437.967810160919;6289.088082228142;6315.431611155018;6369.585976911486;6391.201099862517;6391.776287829904;6446.8388290293115;6479.870402592016;6502.081885843782;6525.292003818362;6539.836504087436;6217.937820621917;6226.346233645402;6290.559660823881;6303.6532990685955;6333 02:30:20 INFO - PID 31474 | |5;yui.html;890.1454603580559;914.721010091922;917.8210249001439;920.2913756407562;946.4605175050222;18288;19284.132214050347;19289.517762055955;19301.32445364409;19345.806541934577;14591.78112328316;14600.123992560453;14619.78070328946;14674.63313417165;14697.55907322782;957.3633533700098;969.8787651543557;972.8735264415616;972.8735264415651;972.9902700972971;921.6866265469739;922.3543519536317;922.6493932305715;922.9861552076691;983.7393090830935;966.5408930757904;989.3816364772017;989.8168838764851;1003.9498025098752;1018.3533456255294;966.9613215471372;996.5864166370948;1011.7470632341915;1012.9240306976969;1013.1489548778987;733.2008111158809;741.7478057460489;745.407401116114;746.559529877375;748.9662965166581;19182.328618498286;19841.00794960261;20009;20013.699794503093;20056.899715501328;20573.07421166051;20768.376948691563;20999.055042523123;21236.044378002873;21298.36104916855;15907.522774316789;16328.918355408267;16350.509484715478;16443.424480143138;16506.669866602722;3597.0827439002887;3605.3330133925306;3634.491171236029;3634.582023067355;3657.1771351445977;116083;145558;145698;147379.26310368488;147412.2629386846;17541.473755787345;17867;17979.550511237063;18055.09724513769;18107.18517666708;31566.526502102486;32532.34935301328;32689.50965735516;32689.67310326914;32693.50959735606;7797.493162944391;8710.216080552655;8755.518446485377;8797.208251257418;8805.69180078707;3976.8210430530694;4045.02919299366;4077.225327187884;4086.0602061526015;4094.8362065517044;5486.561075113981;5515.586331025189;5549.417311182348;5552.944470555242;5592.944070559242;998.8351921932945;1012.6759436980243;1037.7301901505512;1038.714353552764;1045.6549338718205;643.3470027921663;674.5682763031664;684.1379861374604;684.5755631508497;692.5429216716942;12228.633141005785;12359.764023597569;12592.87407125917;12791.552295669793;13000.219986800823;612.3784358875745;617.6664601115348;619.2476141488114;637.677972623822;639.8528338482082;5277.393099793496;5311.256424100552;5414.10667239901;5436.320459942507;5477.808276710376;117235.35169906313;117834;119666;121918;123735;105529.47235263775;105752;107344;107567.46216268868;108721.45639271753;91291;91984;92315.53842230747;93937;96138.03861961431;9996.60013599477;10028.348157369748;10065.547050382896;10324.174066074545;10344.172466202835;42898;43496.782516087216;43872;44965.550344497446;45874.54125458703;57152.42847571471;57338;57945.420545794004;57986.42013579979;57989;14490;14528.055676381002;14840.777388338967;14852.40590376416;14855;16922.576935576708;16974.74537881908;17052.14739263007;17145.742813857552;17266.395676151777;15075;15520.14639194855;15574.299156537756;15652.92173539125;15821.604459888595;14562.417503300174;14612.634684132556;14672.633184170481;14975.550733477578;14986;15879.2060396982;15911.443099491926;16004.359825606845;16254.106024168785;16368.427105051265;11020.559177632806;11048.613298534512;11208.383538905113;11377.658670239905;11394.715132121763;11239.157063220455;11275.830862537232;11281.323120612462;11396;11438.485268162785 02:30:20 INFO - PID 31474 | __end_tp_report 02:30:20 INFO - PID 31474 | __start_cc_report 02:30:20 INFO - PID 31474 | _x_x_mozilla_cycle_collect,357 02:30:20 INFO - PID 31474 | __end_cc_report 02:30:20 INFO - PID 31474 | __startTimestamp1505035820981__endTimestamp 02:30:20 INFO - PID 31474 | 02:30:20 INFO - PID 31474 | ------- Summary: start ------- 02:30:20 INFO - PID 31474 | Number of tests: 6 02:30:20 INFO - PID 31474 | 02:30:20 INFO - PID 31474 | [#0] dojo.html Cycles:165 Average:6179.56 Median:6411.97 stddev:740.97 (11.6%) stddev-sans-first:702.58 02:30:20 INFO - PID 31474 | Values: 3093.6 3118.5 3120.7 3127.0 3173.7 6093.1 6137.8 6184.2 6261.0 6315.2 6170.4 6197.3 6222.8 6298.8 6306.5 5695.7 5723.3 5732.8 5760.8 5797.1 5762.5 5775.7 5842.6 5868.8 5884.9 5764.6 5825.0 5839.5 5844.2 5869.7 5772.8 5831.2 5837.2 5894.6 5916.9 5526.2 5652.3 5661.6 5702.4 5807.0 6180.0 6287.1 6317.6 6353.2 6357.8 6491.9 6705.5 6736.9 6782.5 6825.7 6690.0 6716.4 6758.3 6763.4 6789.5 6273.6 6280.9 6288.9 6295.0 6295.8 6847.9 6889.9 6925.4 6939.1 6986.1 6627.3 6629.6 6700.7 6702.6 6727.8 6743.1 6749.4 6833.2 6838.4 6841.0 6489.4 6632.0 6639.5 6673.5 6698.6 6571.2 6710.2 6834.2 6843.8 6850.2 5335.9 5351.9 5357.5 5359.8 5369.1 4593.1 4610.3 4646.5 4653.3 4659.9 5588.6 5684.6 5685.8 5689.3 5887.2 6670.3 6683.9 6747.4 6749.8 6797.9 5763.4 5788.5 5832.4 5845.8 5871.5 5784.5 5793.2 5843.7 5851.9 5876.6 6258.3 6395.5 6513.5 6546.5 6610.2 6630.1 6765.5 6832.4 6842.2 6851.7 6412.0 6430.0 6468.3 6475.5 6488.9 6569.9 6575.2 6665.0 6666.4 6686.8 6624.9 6668.3 6678.2 6764.5 6779.7 6488.8 6539.6 6573.8 6577.4 6590.9 6511.7 6559.7 6597.7 6617.6 6620.9 6445.9 6457.5 6463.5 6523.3 6562.0 6294.0 6446.7 6555.2 6607.3 6673.7 6305.9 6320.3 6363.2 6411.8 6422.5 02:30:20 INFO - PID 31474 | 02:30:20 INFO - PID 31474 | [#1] ext.html Cycles:180 Average:36540.07 Median:3473.41 stddev:50961.76 (1467.2%) stddev-sans-first:51034.16 02:30:21 INFO - PID 31474 | Values: 825.3 835.2 843.0 844.5 856.2 37323.4 41000.6 41022.2 41294.0 41379.0 38168.6 38233.8 38268.2 38329.2 38873.0 6847.5 6934.2 6939.7 6943.1 6965.9 6856.9 6858.7 6877.7 6943.9 6979.0 6932.8 6954.4 6958.3 6963.4 7710.9 7766.7 7777.4 7830.1 7830.8 7842.7 7640.6 7691.6 7717.3 7726.6 7731.5 37852.4 37972.4 38954.6 39096.8 39395.6 3442.0 3471.6 3475.3 3479.7 3482.2 32767.7 32882.5 33117.7 33239.8 33585.8 5111.2 5140.3 5210.7 5213.9 5230.9 185566.0 185618.0 185645.0 185835.0 186144.1 41306.8 41959.2 47788.5 47928.0 48446.0 50886.7 50900.5 50982.5 50982.5 51260.7 15469.9 15742.5 15745.3 15751.7 15762.8 12773.8 12786.5 12799.1 12811.6 12876.6 13741.9 13779.7 13859.6 13872.9 13948.6 5530.3 5542.5 5597.3 5600.3 5631.8 994.9 1009.6 1021.1 1026.9 1043.0 23144.8 23204.1 23309.7 23538.6 23571.0 1106.7 1108.6 1114.5 1122.5 1124.9 11960.0 12165.6 12274.2 12300.3 12390.4 201309.0 201846.0 211532.0 211998.0 213004.0 52524.0 52793.5 52795.5 53076.7 53134.0 1417.7 1455.9 1462.2 1463.5 1466.0 22399.4 22542.9 22776.2 22821.3 22912.3 37294.0 37341.0 37367.8 37409.3 37423.1 195839.0 196046.0 196132.0 197070.0 197091.0 29993.6 30257.2 33940.5 34107.8 34417.8 43636.0 44132.0 44708.8 44797.8 45392.3 31624.1 31672.0 32339.5 32345.8 32402.8 31117.8 31446.7 31634.9 31875.5 32491.5 36293.3 36459.8 36476.0 36522.0 36724.4 31462.8 31641.4 31659.8 31710.4 31860.5 22768.7 23248.4 23318.9 23510.1 23632.6 02:30:21 INFO - PID 31474 | 02:30:21 INFO - PID 31474 | [#2] jquery.html Cycles:180 Average:33438.89 Median:21879.39 stddev:73950.07 (338.0%) stddev-sans-first:74116.32 02:30:21 INFO - PID 31474 | Values: 562.1 562.5 566.5 572.5 574.4 21120.3 21953.0 22063.7 22072.7 22089.9 23284.7 24061.5 24108.6 24123.6 24421.3 6268.0 6346.6 6371.0 6373.2 6391.6 6294.7 6400.1 6411.1 6412.2 6412.3 6158.1 6350.0 6451.5 6467.0 6488.0 6447.7 6499.3 6512.3 6514.0 6517.5 6024.7 6050.5 6076.0 6078.8 6080.1 27792.9 28559.7 29229.1 29290.3 29318.9 1951.7 1990.1 1992.8 1999.6 2002.0 19343.7 19673.1 19752.3 19781.5 19782.9 2373.2 2417.9 2460.3 2461.4 2476.3 116216.0 122123.0 122526.0 130016.3 138080.3 20234.4 20779.0 20938.6 21678.2 21805.8 41978.0 42064.6 42182.4 42204.8 42594.6 10715.0 10803.4 10884.6 10963.3 11023.1 8846.9 8859.1 8970.5 8981.9 8991.7 10853.9 11211.4 11223.6 11314.2 11497.0 3649.8 3766.3 3841.5 3994.3 4031.9 913.5 938.6 939.0 939.9 958.8 19190.5 19588.1 19744.9 19848.3 20205.6 1001.2 1003.2 1014.0 1016.3 1019.3 10498.3 10727.2 10789.8 10924.8 11060.7 426672.0 434737.0 440304.0 442563.0 445169.0 109591.0 110991.0 111662.0 111847.0 112942.0 1138.1 1147.4 1209.2 1210.9 1226.2 20601.8 20962.0 20998.5 21192.9 21209.9 52803.3 52993.7 54727.5 55562.0 57269.7 68303.3 68685.0 69646.0 69926.3 70060.6 13772.6 13987.4 14011.6 14013.6 14030.6 23104.9 23109.2 23204.4 23286.9 23422.3 18050.2 18210.5 18253.2 18337.4 18434.3 16139.8 16460.0 16859.5 16878.4 17003.8 17791.2 17854.9 17864.2 17900.6 18003.7 12717.5 13596.2 13603.8 13618.6 13634.0 17190.4 17267.8 17403.0 17598.4 17682.6 02:30:21 INFO - PID 31474 | 02:30:21 INFO - PID 31474 | [#3] mootools.html Cycles:180 Average:9183.99 Median:2689.30 stddev:6535.64 (243.0%) stddev-sans-first:6527.22 02:30:21 INFO - PID 31474 | Values: 1312.5 1337.7 1342.6 1353.0 1354.3 14615.5 15259.7 15304.7 15331.5 15379.4 14836.6 14922.1 14942.2 14956.7 14975.5 2656.4 2741.0 2743.2 2747.5 2750.4 2692.6 2711.3 2716.4 2718.5 2721.3 2596.9 2610.1 2789.1 2792.8 2793.1 2707.0 2753.2 2771.2 2775.4 2781.7 2594.2 2669.8 2679.2 2687.8 2690.8 14390.0 14545.7 14633.8 14634.7 14701.6 7817.2 8072.5 8084.1 8093.4 8094.8 4970.5 5215.6 5298.1 5299.1 5326.4 1905.9 1940.0 1944.8 1979.1 1980.8 25177.7 25942.2 25998.2 26004.9 26095.7 6892.2 6965.2 7015.4 7141.1 7226.5 19509.1 19731.3 19741.9 19753.4 19792.5 7893.1 8014.4 8079.8 8115.9 8125.7 6992.4 7051.6 7091.4 7299.8 7350.5 5387.5 5557.8 5650.0 5682.1 5704.5 1631.9 1677.2 1679.7 1697.5 1728.7 846.9 867.8 875.6 897.4 898.8 7521.8 7570.1 7675.9 8468.0 8630.4 682.5 702.4 709.1 712.5 718.1 4988.9 5178.0 5244.9 5259.6 5264.7 19497.0 20234.2 20319.7 20600.8 21095.6 19949.5 20695.2 20777.8 20819.1 20975.2 10309.4 10862.5 10882.1 10912.5 10924.7 8278.7 8371.5 8379.7 8395.5 8408.7 17533.5 17903.9 18137.3 18141.1 18434.4 17732.6 18498.3 18657.8 18685.7 19005.9 11306.6 11632.8 11702.6 11726.9 11741.8 13403.7 13434.0 13540.5 13592.5 13622.0 10670.0 10904.9 11014.2 11084.2 11380.6 9850.2 10010.8 10127.9 10177.8 10259.9 8982.5 9258.7 9354.0 9386.2 9426.6 6569.5 6759.4 6795.3 6821.4 6825.6 6910.9 7036.2 7058.0 7084.4 7090.8 02:30:21 INFO - PID 31474 | 02:30:21 INFO - PID 31474 | [#4] prototype.html Cycles:180 Average:5431.36 Median:6074.85 stddev:1520.84 (25.0%) stddev-sans-first:1479.33 02:30:21 INFO - PID 31474 | Values: 497.2 504.1 524.2 525.8 533.7 6152.0 6166.4 6215.2 6284.3 6289.6 6033.6 6059.4 6111.3 6112.6 6182.4 5063.2 5101.2 5114.4 5122.2 5126.9 4789.0 5016.7 5025.7 5072.9 5105.9 4814.3 4822.8 4853.4 4885.8 4902.4 4838.2 4889.1 4891.5 4903.6 4923.1 4344.6 4354.9 4380.1 4430.1 4433.0 6028.5 6029.8 6031.8 6043.5 6094.6 5592.9 5603.0 5608.9 5615.7 5647.8 6415.9 6415.9 6440.0 6487.3 6489.4 4549.0 4760.9 4787.9 4808.5 4813.5 6581.2 6597.6 6607.5 6639.2 6761.7 4891.3 4897.4 4919.9 4939.9 4945.5 4972.2 4995.4 5013.3 5016.1 5017.2 5519.2 5526.4 5547.9 5573.9 5598.7 6525.4 6543.6 6548.1 6563.3 6591.2 1732.2 1734.0 1739.4 1748.8 1750.4 1125.7 1186.7 1191.3 1207.2 1219.6 5643.7 5673.8 5689.3 5706.5 5709.3 6562.6 6576.2 6600.8 6624.3 6625.9 5605.3 5615.2 5617.9 5680.9 5694.8 3332.5 3501.5 3519.1 3551.3 3555.9 6416.0 6417.8 6421.6 6442.9 6447.7 6745.8 6752.4 6814.4 6890.3 6922.6 6090.3 6182.8 6319.3 6379.1 6440.5 6660.8 6662.3 6665.6 6707.3 6738.8 6593.4 6594.2 6610.8 6623.6 6650.2 6374.6 6413.8 6430.8 6436.2 6451.3 4872.6 4913.4 4931.6 4982.7 4992.5 6247.6 6523.6 6526.0 6528.2 6625.9 6268.7 6336.0 6365.8 6456.3 6471.9 6333.7 6358.4 6416.6 6419.4 6438.0 6289.1 6315.4 6369.6 6391.2 6391.8 6446.8 6479.9 6502.1 6525.3 6539.8 6217.9 6226.3 6290.6 6303.7 6333.0 02:30:21 INFO - PID 31474 | 02:30:21 INFO - PID 31474 | [#5] yui.html Cycles:180 Average:23950.45 Median:20011.35 stddev:35062.96 (175.2%) stddev-sans-first:35118.57 02:30:21 INFO - PID 31474 | Values: 890.1 914.7 917.8 920.3 946.5 18288.0 19284.1 19289.5 19301.3 19345.8 14591.8 14600.1 14619.8 14674.6 14697.6 957.4 969.9 972.9 972.9 973.0 921.7 922.4 922.6 923.0 983.7 966.5 989.4 989.8 1003.9 1018.4 967.0 996.6 1011.7 1012.9 1013.1 733.2 741.7 745.4 746.6 749.0 19182.3 19841.0 20009.0 20013.7 20056.9 20573.1 20768.4 20999.1 21236.0 21298.4 15907.5 16328.9 16350.5 16443.4 16506.7 3597.1 3605.3 3634.5 3634.6 3657.2 116083.0 145558.0 145698.0 147379.3 147412.3 17541.5 17867.0 17979.6 18055.1 18107.2 31566.5 32532.3 32689.5 32689.7 32693.5 7797.5 8710.2 8755.5 8797.2 8805.7 3976.8 4045.0 4077.2 4086.1 4094.8 5486.6 5515.6 5549.4 5552.9 5592.9 998.8 1012.7 1037.7 1038.7 1045.7 643.3 674.6 684.1 684.6 692.5 12228.6 12359.8 12592.9 12791.6 13000.2 612.4 617.7 619.2 637.7 639.9 5277.4 5311.3 5414.1 5436.3 5477.8 117235.4 117834.0 119666.0 121918.0 123735.0 105529.5 105752.0 107344.0 107567.5 108721.5 91291.0 91984.0 92315.5 93937.0 96138.0 9996.6 10028.3 10065.5 10324.2 10344.2 42898.0 43496.8 43872.0 44965.6 45874.5 57152.4 57338.0 57945.4 57986.4 57989.0 14490.0 14528.1 14840.8 14852.4 14855.0 16922.6 16974.7 17052.1 17145.7 17266.4 15075.0 15520.1 15574.3 15652.9 15821.6 14562.4 14612.6 14672.6 14975.6 14986.0 15879.2 15911.4 16004.4 16254.1 16368.4 11020.6 11048.6 11208.4 11377.7 11394.7 11239.2 11275.8 11281.3 11396.0 11438.5 02:30:21 INFO - PID 31474 | -------- Summary: end -------- 02:30:21 INFO - PID 31474 | 02:30:22 INFO - TEST-INFO | 31474: exit 0 02:30:22 INFO - TEST-OK | dromaeo_css | took 1114341ms 02:30:22 INFO - TEST-START | kraken 02:30:22 INFO - Initialising browser for kraken test... 02:30:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55116/getInfo.html -profile /tmp/tmpKMs6SA/profile 02:30:22 INFO - TEST-INFO | started process 31864 (/builds/slave/test/build/application/firefox/firefox http://localhost:55116/getInfo.html) 02:30:28 INFO - TEST-INFO | 31864: exit 0 02:30:28 INFO - Browser initialized. 02:30:28 INFO - Running cycle 1/1 for kraken test... 02:30:28 INFO - Using env: {'DISPLAY': ':0', 02:30:28 INFO - 'HOME': '/home/cltbld', 02:30:28 INFO - 'JSGC_DISABLE_POISONING': '1', 02:30:28 INFO - 'LANG': 'en_US.UTF-8', 02:30:28 INFO - 'LANGUAGE': 'en_US:en', 02:30:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:30:28 INFO - 'LOGNAME': 'cltbld', 02:30:28 INFO - 'MAIL': '/var/mail/cltbld', 02:30:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:30:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:30:28 INFO - 'MOZ_ACCELERATED': '1', 02:30:28 INFO - 'MOZ_CRASHREPORTER': '1', 02:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:30:28 INFO - 'MOZ_NO_REMOTE': '1', 02:30:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:30:28 INFO - 'MOZ_WEBRENDER': '1', 02:30:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:28 INFO - 'NO_EM_RESTART': '1', 02:30:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:28 INFO - 'PWD': '/builds/slave/test', 02:30:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:30:28 INFO - 'RUST_BACKTRACE': 'full', 02:30:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:30:28 INFO - 'SHELL': '/bin/bash', 02:30:28 INFO - 'SHLVL': '1', 02:30:28 INFO - 'TERM': 'linux', 02:30:28 INFO - 'TMOUT': '86400', 02:30:28 INFO - 'USER': 'cltbld', 02:30:28 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612', 02:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:28 INFO - '_': '/tools/buildbot/bin/python'} 02:30:28 INFO - TEST-INFO | started process 32077 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKMs6SA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:30:29 INFO - PID 32077 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:30:29 INFO - PID 32077 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:30:29 INFO - PID 32077 | 02:30:29 INFO - PID 32077 | (/builds/slave/test/build/application/firefox/firefox:32138): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:30:29 INFO - PID 32077 | 02:30:40 INFO - PID 32077 | RSS: Main: 215035904 02:30:40 INFO - PID 32077 | 02:30:42 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55116/tests/kraken/driver.html?testName=audio-beat-detection) 02:30:43 INFO - PID 32077 | RSS: Main: 222433280 02:30:43 INFO - PID 32077 | 02:30:46 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55116/tests/kraken/driver.html?testName=audio-dft) 02:30:47 INFO - PID 32077 | RSS: Main: 222040064 02:30:47 INFO - PID 32077 | 02:30:50 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55116/tests/kraken/driver.html?testName=audio-fft) 02:30:50 INFO - PID 32077 | RSS: Main: 218152960 02:30:50 INFO - PID 32077 | 02:30:52 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55116/tests/kraken/driver.html?testName=audio-oscillator) 02:30:53 INFO - PID 32077 | RSS: Main: 217321472 02:30:53 INFO - PID 32077 | 02:30:55 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55116/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:30:56 INFO - PID 32077 | RSS: Main: 219140096 02:30:56 INFO - PID 32077 | 02:30:59 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55116/tests/kraken/driver.html?testName=imaging-darkroom) 02:31:00 INFO - PID 32077 | RSS: Main: 220942336 02:31:00 INFO - PID 32077 | 02:31:04 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55116/tests/kraken/driver.html?testName=imaging-desaturate) 02:31:04 INFO - PID 32077 | RSS: Main: 219336704 02:31:04 INFO - PID 32077 | 02:31:07 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55116/tests/kraken/driver.html?testName=json-parse-financial) 02:31:08 INFO - PID 32077 | RSS: Main: 220545024 02:31:08 INFO - PID 32077 | 02:31:10 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55116/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:31:10 INFO - PID 32077 | RSS: Main: 221204480 02:31:10 INFO - PID 32077 | 02:31:12 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55116/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:31:13 INFO - PID 32077 | RSS: Main: 221319168 02:31:13 INFO - PID 32077 | 02:31:15 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55116/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:31:15 INFO - PID 32077 | RSS: Main: 221663232 02:31:15 INFO - PID 32077 | 02:31:18 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55116/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:31:19 INFO - PID 32077 | RSS: Main: 222269440 02:31:19 INFO - PID 32077 | 02:31:22 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55116/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:31:22 INFO - PID 32077 | RSS: Main: 222679040 02:31:22 INFO - PID 32077 | 02:31:24 INFO - PID 32077 | Cycle 1(1): loaded http://localhost:55116/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55116/tests/kraken/driver.html?testName=ai-astar) 02:31:25 INFO - PID 32077 | RSS: Main: 224169984 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | __start_tp_report 02:31:25 INFO - PID 32077 | _x_x_mozilla_page_load 02:31:25 INFO - PID 32077 | _x_x_mozilla_page_load_details 02:31:25 INFO - PID 32077 | |i|pagename|runs| 02:31:25 INFO - PID 32077 | |0;ai-astar;110;109;114;113;110;111;122;114;108;114 02:31:25 INFO - PID 32077 | |1;audio-beat-detection;118;124;117;116;118;116;119;118;118;123 02:31:25 INFO - PID 32077 | |2;audio-dft;169;165;165;165;176;172;169;165;171;164 02:31:25 INFO - PID 32077 | |3;audio-fft;76;74;74;79;80;87;76;74;74;85 02:31:25 INFO - PID 32077 | |4;audio-oscillator;84;84;85;84;89;79;78;91;88;81 02:31:25 INFO - PID 32077 | |5;imaging-gaussian-blur;113;115;115;114;114;118;125;111;118;116 02:31:25 INFO - PID 32077 | |6;imaging-darkroom;154;149;157;158;149;148;158;151;153;150 02:31:25 INFO - PID 32077 | |7;imaging-desaturate;94;100;97;96;96;96;95;96;99;100 02:31:25 INFO - PID 32077 | |8;json-parse-financial;52;58;52;63;54;54;53;55;54;55 02:31:25 INFO - PID 32077 | |9;json-stringify-tinderbox;43;41;38;38;39;41;45;38;44;39 02:31:25 INFO - PID 32077 | |10;stanford-crypto-aes;87;79;80;83;75;82;77;75;75;84 02:31:25 INFO - PID 32077 | |11;stanford-crypto-ccm;131;116;125;123;127;124;118;124;133;124 02:31:25 INFO - PID 32077 | |12;stanford-crypto-pbkdf2;177;179;189;174;168;183;173;179;176;175 02:31:25 INFO - PID 32077 | |13;stanford-crypto-sha256-iterative;68;65;64;64;67;67;64;66;68;64 02:31:25 INFO - PID 32077 | __end_tp_report 02:31:25 INFO - PID 32077 | __start_cc_report 02:31:25 INFO - PID 32077 | _x_x_mozilla_cycle_collect,968 02:31:25 INFO - PID 32077 | __end_cc_report 02:31:25 INFO - PID 32077 | __startTimestamp1505035885047__endTimestamp 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | ------- Summary: start ------- 02:31:25 INFO - PID 32077 | Number of tests: 14 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.50 stddev:4.01 (3.5%) stddev-sans-first:4.15 02:31:25 INFO - PID 32077 | Values: 110.0 109.0 114.0 113.0 110.0 111.0 122.0 114.0 108.0 114.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#1] audio-beat-detection Cycles:10 Average:118.70 Median:118.00 stddev:2.71 (2.3%) stddev-sans-first:2.86 02:31:25 INFO - PID 32077 | Values: 118.0 124.0 117.0 116.0 118.0 116.0 119.0 118.0 118.0 123.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#2] audio-dft Cycles:10 Average:168.10 Median:169.00 stddev:3.98 (2.4%) stddev-sans-first:4.21 02:31:25 INFO - PID 32077 | Values: 169.0 165.0 165.0 165.0 176.0 172.0 169.0 165.0 171.0 164.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#3] audio-fft Cycles:10 Average:77.90 Median:77.50 stddev:4.79 (6.2%) stddev-sans-first:5.04 02:31:25 INFO - PID 32077 | Values: 76.0 74.0 74.0 79.0 80.0 87.0 76.0 74.0 74.0 85.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:84.50 stddev:4.22 (5.0%) stddev-sans-first:4.47 02:31:25 INFO - PID 32077 | Values: 84.0 84.0 85.0 84.0 89.0 79.0 78.0 91.0 88.0 81.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#5] imaging-gaussian-blur Cycles:10 Average:115.90 Median:115.50 stddev:3.84 (3.3%) stddev-sans-first:3.93 02:31:25 INFO - PID 32077 | Values: 113.0 115.0 115.0 114.0 114.0 118.0 125.0 111.0 118.0 116.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#6] imaging-darkroom Cycles:10 Average:152.70 Median:153.50 stddev:3.89 (2.5%) stddev-sans-first:4.10 02:31:25 INFO - PID 32077 | Values: 154.0 149.0 157.0 158.0 149.0 148.0 158.0 151.0 153.0 150.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:96.00 stddev:2.08 (2.2%) stddev-sans-first:1.92 02:31:25 INFO - PID 32077 | Values: 94.0 100.0 97.0 96.0 96.0 96.0 95.0 96.0 99.0 100.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:54.50 stddev:3.30 (6.1%) stddev-sans-first:3.32 02:31:25 INFO - PID 32077 | Values: 52.0 58.0 52.0 63.0 54.0 54.0 53.0 55.0 54.0 55.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#9] json-stringify-tinderbox Cycles:10 Average:40.60 Median:41.00 stddev:2.63 (6.4%) stddev-sans-first:2.65 02:31:25 INFO - PID 32077 | Values: 43.0 41.0 38.0 38.0 39.0 41.0 45.0 38.0 44.0 39.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:81.00 stddev:4.24 (5.2%) stddev-sans-first:3.59 02:31:25 INFO - PID 32077 | Values: 87.0 79.0 80.0 83.0 75.0 82.0 77.0 75.0 75.0 84.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#11] stanford-crypto-ccm Cycles:10 Average:124.50 Median:124.50 stddev:5.15 (4.1%) stddev-sans-first:4.89 02:31:25 INFO - PID 32077 | Values: 131.0 116.0 125.0 123.0 127.0 124.0 118.0 124.0 133.0 124.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:178.00 stddev:5.76 (3.2%) stddev-sans-first:6.10 02:31:25 INFO - PID 32077 | Values: 177.0 179.0 189.0 174.0 168.0 183.0 173.0 179.0 176.0 175.0 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.50 stddev:1.70 (2.6%) stddev-sans-first:1.59 02:31:25 INFO - PID 32077 | Values: 68.0 65.0 64.0 64.0 67.0 67.0 64.0 66.0 68.0 64.0 02:31:25 INFO - PID 32077 | -------- Summary: end -------- 02:31:25 INFO - PID 32077 | 02:31:25 INFO - PID 32077 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 02:31:25 INFO - TEST-INFO | 32077: exit 0 02:31:26 INFO - TEST-OK | kraken | took 64021ms 02:31:26 INFO - SUITE-END | took 1178s 02:31:26 INFO - Completed test suite (00:19:38) 02:31:26 INFO - javascript benchmark 02:31:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8436.187155184554, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3093.597832281803, 3118.501039833627, 3120.7035331643488, 3126.968730312696, 3173.682631736827, 6093.116498107783, 6137.846553836156, 6184.165137706408, 6260.99824028155, 6315.179026726519, 6170.413810687989, 6197.349278325779, 6222.782202622909, 6298.779542716006, 6306.527010474221, 5695.658260504372, 5723.2559767230205, 5732.828015159531, 5760.827175184731, 5797.101449275369, 5762.481376676119, 5775.740091695884, 5842.64944103353, 5868.794592189274, 5884.911726324109, 5764.625299355528, 5824.970875145618, 5839.474447299742, 5844.152597873306, 5869.735861886204, 5772.797952071699, 5831.154482599999, 5837.182794408786, 5894.557908156884, 5916.852078698024, 5526.171074338841, 5652.349979752299, 5661.60368774186, 5702.372738998707, 5807.0, 6179.969100154515, 6287.056941458772, 6317.589356691847, 6353.237611486605, 6357.809265722036, 6491.93508064918, 6705.4970877184405, 6736.898946515757, 6782.457403407716, 6825.726970921157, 6690.0, 6716.429103526206, 6758.25659177495, 6763.425108865752, 6789.456843452562, 6273.62358258506, 6280.87438251237, 6288.937110628881, 6295.024271237966, 6295.811125666238, 6847.863042739217, 6889.896651550231, 6925.445964322842, 6939.097917270722, 6986.091808065021, 6627.3372662733345, 6629.602223866584, 6700.698468568926, 6702.597844129368, 6727.7981660549285, 6743.123393958754, 6749.392554670103, 6833.180018397725, 6838.418734407532, 6841.042254084432, 6489.383508566679, 6632.005199220155, 6639.468842492589, 6673.532852700265, 6698.5980841149685, 6571.244306904671, 6710.228323742735, 6834.2482326944, 6843.828904277333, 6850.212225594021, 5335.919961200585, 5351.919721204185, 5357.517823395835, 5359.758810853521, 5369.0872551665525, 4593.081383723269, 4610.262358022767, 4646.465656449484, 4653.325267836145, 4659.906801863944, 5588.552915766729, 5684.6020778545935, 5685.829425117253, 5689.345725241567, 5887.234659494238, 6670.332966703388, 6683.899741503881, 6747.359000894907, 6749.785038692984, 6797.898031529531, 5763.366029736726, 5788.507976822018, 5832.387599302012, 5845.824625261249, 5871.500922421642, 5784.479396854304, 5793.246877905845, 5843.707814609253, 5851.882962340815, 5876.6180198286975, 6258.280297765724, 6395.488360931115, 6513.478921686254, 6546.476281897437, 6610.173728283965, 6630.138082049302, 6765.526413151032, 6832.419244364285, 6842.213145488333, 6851.725930962905, 6411.967940160456, 6429.97120460724, 6468.256150542559, 6475.546711730133, 6488.870222595428, 6569.868602628017, 6575.24384695747, 6664.966675166593, 6666.366695163952, 6686.765963191265, 6624.900626490703, 6668.333166683293, 6678.23200331968, 6764.526483146133, 6779.661016949231, 6488.772892948724, 6539.574927629689, 6573.835654108686, 6577.375149360803, 6590.868182636416, 6511.706973186123, 6559.7376104957175, 6597.736090556324, 6617.636030018395, 6620.867582648224, 6445.935540644534, 6457.547971642128, 6463.482921366373, 6523.282438931618, 6562.0, 6294.0, 6446.709898054505, 6555.228445044546, 6607.273199947906, 6673.666316684244, 6305.90541141874, 6320.336364681642, 6363.204599425072, 6411.775587854403, 6422.518311126778], "value": 6122.841474815115, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.3273582030645, 835.2148979958838, 843.0263046181655, 844.4679851693433, 856.1780690537084, 37323.44014839776, 41000.58999410005, 41022.17955640885, 41294.0, 41379.0, 38168.618313816856, 38233.80883095587, 38268.23463530728, 38329.23341533168, 38873.0, 6847.452203823707, 6934.202566704843, 6939.653017349137, 6943.062686537303, 6965.860682786341, 6856.862862742742, 6858.725650973955, 6877.690503927335, 6943.930560694379, 6978.965105174467, 6932.826679333007, 6954.443644508452, 6958.338957799001, 6963.442924566022, 7710.922890771105, 7766.650500727453, 7777.377809775205, 7830.099538553082, 7830.8433831323055, 7842.725504607311, 7640.5797681127515, 7691.615419229016, 7717.3054425101445, 7726.613669316512, 7731.45879788415, 37852.43221351682, 37972.43041354382, 38954.61045389538, 39096.80451597752, 39395.60604393948, 3441.9827900860337, 3471.5660542432197, 3475.2528206435486, 3479.7216222702123, 3482.1642805726697, 32767.6723232767, 32882.50676239858, 33117.66882331194, 33239.83380083108, 33585.83207083974, 5111.2333150027425, 5140.280360749498, 5210.6613070150315, 5213.895722085575, 5230.92153617696, 185566.0, 185618.0, 185645.0, 185835.0, 186144.0692796541, 41306.793466032475, 41959.16081678349, 47788.5221147791, 47928.0, 48446.0, 50886.74556627193, 50900.49099509032, 50982.45087745672, 50982.49017509777, 51260.74369628128, 15469.922650386676, 15742.527724168292, 15745.291461884242, 15751.68496630061, 15762.842371576367, 12773.808392874114, 12786.488540458464, 12799.10406271552, 12811.551595694105, 12876.61370158878, 13741.862581374258, 13779.724405511834, 13859.584212473643, 13872.890168786473, 13948.58154255374, 5530.308711411074, 5542.473465020817, 5597.272354593958, 5600.299962504687, 5631.774729010794, 994.9204063674889, 1009.5961615353917, 1021.070825548762, 1026.9229807764448, 1043.0247718383337, 23144.801243881804, 23204.071837126325, 23309.650355244685, 23538.64692029621, 23571.0, 1106.7067227184803, 1108.634150730257, 1114.509615769059, 1122.5004872831512, 1124.8706398764084, 11960.0, 12165.561627321946, 12274.188075134389, 12300.261984280973, 12390.442430090667, 201309.0, 201846.0, 211532.0, 211998.0, 213004.0, 52524.0, 52793.47206527885, 52795.47204527982, 53076.73461632667, 53134.0, 1417.6951955329653, 1455.9053661511969, 1462.1884853905972, 1463.5024091808837, 1465.9706805864037, 22399.42406623194, 22542.887285563465, 22776.20283290077, 22821.31536053921, 22912.31263062111, 37294.0, 37341.0, 37367.81316093402, 37409.251814963005, 37423.06442338963, 195839.0, 196046.0, 196132.0, 197070.0, 197091.0, 29993.550096749004, 30257.24356891095, 33940.49089263614, 34107.82946085254, 34417.82791086029, 43636.0, 44132.0, 44708.77645611751, 44797.776011119735, 45392.31911521264, 31624.051278461684, 31672.0, 32339.514907275938, 32345.838270809436, 32402.83798580992, 31117.8444107778, 31446.685533144377, 31634.892778753554, 31875.521867172476, 32491.512627310138, 36293.274134516636, 36459.81770091239, 36476.0, 36522.0, 36724.44913326249, 31462.842685787342, 31641.36717265688, 31659.84170079135, 31710.36579268448, 31860.5220921691, 22768.65847012263, 23248.4187895304, 23318.883405582863, 23510.059597615902, 23632.645510317016], "value": 16841.06821271675, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.1343131577371, 562.524666656675, 566.5326105962581, 572.4504475703325, 574.3768011707298, 21120.260790872322, 21953.0, 22063.6690449643, 22072.668909966364, 22089.889550552245, 23284.65073023906, 24061.51876962464, 24108.638370424458, 24123.638145427834, 24421.267361979124, 6268.0, 6346.619202847821, 6370.9681451592905, 6373.203349581302, 6391.552591318609, 6294.653794041325, 6400.071989561534, 6411.134496842937, 6412.166418365606, 6412.326705695905, 6158.07628855673, 6350.047492876082, 6451.548391612589, 6466.967665161691, 6488.0, 6447.742090316405, 6499.3175716549795, 6512.283648798629, 6514.0, 6517.511186660972, 6024.698765061773, 6050.45545900871, 6075.9696201519155, 6078.756849726005, 6080.14877917092, 27792.861035694896, 28559.714402855912, 29229.123126306244, 29290.267743306376, 29318.853405732836, 1951.668216403215, 1990.144237977669, 1992.750906136733, 1999.5900840327843, 2001.979980200194, 19343.709844352346, 19673.114709837948, 19752.308669196653, 19781.505462363413, 19782.901085494625, 2373.196370758219, 2417.891194896234, 2460.2619214235838, 2461.4461746107163, 2476.2942561370105, 116216.0, 122123.0, 122525.99999999911, 130016.3499182498, 138080.30959845238, 20234.381249500006, 20779.0, 20938.581228375653, 21678.24126155577, 21805.781942180376, 41978.0, 42064.579354206675, 42182.367264491055, 42204.78897605492, 42594.57405425968, 10715.0, 10803.351798892092, 10884.61903833362, 10963.342199468058, 11023.118150547936, 8846.911530884609, 8859.11408859109, 8970.461772293553, 8981.910180898238, 8991.685291014783, 10853.945730271298, 11211.439428028565, 11223.55105795759, 11314.151438642135, 11497.0, 3649.835757390924, 3766.3408903441787, 3841.4814000110186, 3994.320965435883, 4031.9395209071886, 913.4747520175926, 938.5776400619612, 938.9577568898517, 939.9013103624158, 958.7698952251549, 19190.520236993907, 19588.11853466569, 19744.901275493532, 19848.305309314394, 20205.59588808245, 1001.2040427859723, 1003.207466101786, 1014.0417305646241, 1016.3495362967707, 1019.2864994503877, 10498.265121441578, 10727.195460340507, 10789.784204315869, 10924.836127458095, 11060.72801627807, 426672.0, 434737.0, 440304.0, 442563.0, 445169.0, 109591.0, 110991.0, 111661.99999999837, 111847.0, 112942.0, 1138.103526524996, 1147.4033502578613, 1209.1596340543415, 1210.8607510136271, 1226.1539537718945, 20601.793982060586, 20962.0, 20998.47503812417, 21192.894035529724, 21209.939503024478, 52803.32390971032, 52993.7350313246, 54727.452725472234, 55562.0, 57269.71365143148, 68303.31696682969, 68685.0, 69645.9553106714, 69926.30073699198, 70060.64969675119, 13772.586822395344, 13987.370568324246, 14011.649708757363, 14013.579592612237, 14030.579082627537, 23104.884475577514, 23109.19117830868, 23204.419889502897, 23286.883565582062, 23422.297331080095, 18050.1877415514, 18210.544736381165, 18253.178606962985, 18337.449876503728, 18434.26262949467, 16139.75790363122, 16460.0, 16859.494215173563, 16878.409255675993, 17003.829961700223, 17791.19939602695, 17854.91072544629, 17864.19611117529, 17900.55248618795, 18003.72994405059, 12717.491300347883, 13596.1842289459, 13603.79594306106, 13618.591442256748, 13634.0, 17190.398336058177, 17267.82732172662, 17403.0, 17598.38405655796, 17682.646347073245], "value": 12255.974716127745, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1312.520929860601, 1337.6522104252892, 1342.6374878782722, 1352.9594112176633, 1354.2822304178785, 14615.488457903963, 15259.694806103871, 15304.69390612187, 15331.46339878103, 15379.384824607003, 14836.55490335289, 14922.104673719585, 14942.178180200111, 14956.700865982673, 14975.47585834496, 2656.428867793419, 2741.040635777472, 2743.231895069374, 2747.4917140329003, 2750.422411293621, 2692.609571612115, 2711.3357227479296, 2716.4295497945454, 2718.4563087382503, 2721.319670082479, 2596.9220923372327, 2610.125607921339, 2789.0656630028966, 2792.804503684743, 2793.064323451646, 2706.972930270692, 2753.2428582139873, 2771.1686494051905, 2775.4449110177843, 2781.707920668331, 2594.1957993022033, 2669.759721625063, 2679.1962411276736, 2687.771539419152, 2690.8250963687296, 14390.0, 14545.709085818224, 14633.780493292608, 14634.707305853823, 14701.632459188499, 7817.218278172138, 8072.515649061075, 8084.1107478177355, 8093.392995525359, 8094.797630059236, 4970.478099799541, 5215.6349055566125, 5298.125809241471, 5299.072662284085, 5326.387465441447, 1905.8856468611789, 1939.9806001939942, 1944.7569053868267, 1979.0500559731265, 1980.7821139674625, 25177.748222517723, 25942.22173334803, 25998.22005339843, 26004.86997565019, 26095.73904260952, 6892.172939247323, 6965.164180298295, 7015.3686168244085, 7141.143062832493, 7226.458015648847, 19509.122089506007, 19731.309404171072, 19741.901290493457, 19753.40739777809, 19792.50518737014, 7893.13175550689, 8014.398920080901, 8079.757607271791, 8115.918840811634, 8125.715599953974, 6992.370686638227, 7051.647417629098, 7091.361777440055, 7299.817504562322, 7350.485466017328, 5387.515123638891, 5557.77768889248, 5650.0, 5682.119271512922, 5704.543636509153, 1631.8776091793163, 1677.1698009485383, 1679.6892574873686, 1697.5331783759566, 1728.671552405039, 846.9407141500159, 867.800405906645, 875.5840975536696, 897.3808072430003, 898.7977705016319, 7521.7743467696055, 7570.129435114922, 7675.884861727079, 8468.0, 8630.352723545759, 682.474494639125, 702.4275215699189, 709.0604948443333, 712.4870093532649, 718.1382341190592, 4988.940037065845, 5178.0, 5244.947550524446, 5259.552938000236, 5264.710440925785, 19497.0, 20234.19063237454, 20319.695204571944, 20600.793992060186, 21095.57808843845, 19949.501262468555, 20695.17219311241, 20777.79222207789, 20819.06314215864, 20975.160993560086, 10309.432981186106, 10862.456877156268, 10882.075023622923, 10912.454377281105, 10924.726881828017, 8278.668853245803, 8371.455855369382, 8379.70671026511, 8395.538245396318, 8408.747737567883, 17533.473995780147, 17903.910480448034, 18137.27450902002, 18141.092945352942, 18434.354797582022, 17732.645347093243, 18498.260069597603, 18657.81342186561, 18685.71971420457, 19005.90497047561, 11306.604268850551, 11632.76734465289, 11702.590409335633, 11726.94136529312, 11741.823872641746, 13403.731925361633, 13434.0, 13540.526081587097, 13592.524261650795, 13622.0, 10670.0, 10904.945475272572, 11014.229003969966, 11084.16868734832, 11380.601678941197, 9850.16273616722, 10010.799784004425, 10127.898721012696, 10177.847332289874, 10259.897401026194, 8982.505962172143, 9258.72223833286, 9354.0, 9386.202172815387, 9426.62293508252, 6569.540132190701, 6759.391654751096, 6795.25252222265, 6821.3519715626935, 6825.60075184576, 6910.93089069123, 7036.2260151384335, 7057.964710176417, 7084.397826184832, 7090.822729431806], "value": 6562.688277405426, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.23425924076923, 504.07502233401704, 524.2372348233316, 525.7539631074352, 533.663791811159, 6151.969240153799, 6166.352532984034, 6215.192025036739, 6284.340144284847, 6289.622622642644, 6033.607815492001, 6059.3637668044885, 6111.266648002234, 6112.602680825743, 6182.443580077792, 5063.189889617662, 5101.245327523152, 5114.43741188469, 5122.180451127821, 5126.897462050757, 4788.952110478903, 5016.673916195454, 5025.748712564376, 5072.898542029176, 5105.948940510603, 4814.277858321262, 4822.7588620569, 4853.417589889218, 4885.825807515657, 4902.362692849924, 4838.177509823338, 4889.144399730033, 4891.51084891508, 4903.621743454421, 4923.138450771136, 4344.565543445662, 4354.934675979863, 4380.12397520494, 4430.113977204572, 4433.0, 6028.487578555828, 6029.758809647609, 6031.819045428644, 6043.516518678495, 6094.573379863411, 5592.944070559283, 5603.0, 5608.943910560883, 5615.719214039323, 5647.774089036434, 6415.871682566322, 6415.871682566322, 6439.967800161016, 6487.318831522716, 6489.415952564244, 4548.954510454886, 4760.857174284777, 4787.8563643090765, 4808.471068182497, 4813.494583068762, 6581.243157036906, 6597.60414375139, 6607.504437167184, 6639.236487803868, 6761.695723692445, 4891.266310053449, 4897.412310522689, 4919.891890271591, 4939.851804445872, 4945.505449455098, 4972.229304457815, 4995.400551933792, 5013.273075404046, 5016.0971025215085, 5017.197248440305, 5519.227308176858, 5526.447355264522, 5547.889042219133, 5573.944260557424, 5598.664080155122, 6525.3800888915475, 6543.640099794462, 6548.148740663683, 6563.278039415661, 6591.209054913441, 1732.2205007746566, 1733.9653206936043, 1739.3912130754388, 1748.7551742756025, 1750.3961133408955, 1125.7410795516914, 1186.7448498572849, 1191.267370567095, 1207.2032458577296, 1219.646302572262, 5643.689597072201, 5673.829785106371, 5689.288838895138, 5706.457886500776, 5709.25779648643, 6562.5890433557015, 6576.210854697467, 6600.834979125464, 6624.304448032984, 6625.86748265032, 5605.271314729141, 5615.213870058114, 5617.887642247214, 5680.914786278209, 5694.775623241023, 3332.5006245316013, 3501.492283618861, 3519.07916429363, 3551.3252482028847, 3555.8933232003083, 6416.0, 6417.807465776034, 6421.646809425433, 6442.903356449657, 6447.709853056624, 6745.763898263537, 6752.426043786333, 6814.420774234147, 6890.276520965327, 6922.619255940972, 6090.33006369299, 6182.845428864314, 6319.3364696706185, 6379.138816259746, 6440.549161558646, 6660.766873159416, 6662.267150613526, 6665.633390163587, 6707.329267073449, 6738.764143254963, 6593.439557637655, 6594.175728033996, 6610.768623098168, 6623.602583844985, 6650.235222949229, 6374.553781235269, 6413.775517856852, 6430.7749228776765, 6436.195475565554, 6451.258105317761, 4872.634552408513, 4913.434954980223, 4931.630127740362, 4982.6512144149565, 4992.450830408579, 6247.562670613013, 6523.608583484816, 6526.021096835512, 6528.183977002875, 6625.867482650224, 6268.686565671789, 6336.0, 6365.840853978688, 6456.257530384066, 6471.935280647133, 6333.683315834282, 6358.395952384516, 6416.647084410402, 6419.358064193543, 6437.967810160919, 6289.088082228142, 6315.431611155018, 6369.585976911486, 6391.201099862517, 6391.776287829904, 6446.8388290293115, 6479.870402592016, 6502.081885843782, 6525.292003818362, 6539.836504087436, 6217.937820621917, 6226.346233645402, 6290.559660823881, 6303.6532990685955, 6333.0], "value": 4985.029892489695, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [890.1454603580559, 914.721010091922, 917.8210249001439, 920.2913756407562, 946.4605175050222, 18288.0, 19284.132214050347, 19289.517762055955, 19301.32445364409, 19345.806541934577, 14591.78112328316, 14600.123992560453, 14619.78070328946, 14674.63313417165, 14697.55907322782, 957.3633533700098, 969.8787651543557, 972.8735264415616, 972.8735264415651, 972.9902700972971, 921.6866265469739, 922.3543519536317, 922.6493932305715, 922.9861552076691, 983.7393090830935, 966.5408930757904, 989.3816364772017, 989.8168838764851, 1003.9498025098752, 1018.3533456255294, 966.9613215471372, 996.5864166370948, 1011.7470632341915, 1012.9240306976969, 1013.1489548778987, 733.2008111158809, 741.7478057460489, 745.407401116114, 746.559529877375, 748.9662965166581, 19182.328618498286, 19841.00794960261, 20009.0, 20013.699794503093, 20056.899715501328, 20573.07421166051, 20768.376948691563, 20999.055042523123, 21236.044378002873, 21298.36104916855, 15907.522774316789, 16328.918355408267, 16350.509484715478, 16443.424480143138, 16506.669866602722, 3597.0827439002887, 3605.3330133925306, 3634.491171236029, 3634.582023067355, 3657.1771351445977, 116083.0, 145558.0, 145698.0, 147379.26310368488, 147412.2629386846, 17541.473755787345, 17867.0, 17979.550511237063, 18055.09724513769, 18107.18517666708, 31566.526502102486, 32532.34935301328, 32689.50965735516, 32689.67310326914, 32693.50959735606, 7797.493162944391, 8710.216080552655, 8755.518446485377, 8797.208251257418, 8805.69180078707, 3976.8210430530694, 4045.02919299366, 4077.225327187884, 4086.0602061526015, 4094.8362065517044, 5486.561075113981, 5515.586331025189, 5549.417311182348, 5552.944470555242, 5592.944070559242, 998.8351921932945, 1012.6759436980243, 1037.7301901505512, 1038.714353552764, 1045.6549338718205, 643.3470027921663, 674.5682763031664, 684.1379861374604, 684.5755631508497, 692.5429216716942, 12228.633141005785, 12359.764023597569, 12592.87407125917, 12791.552295669793, 13000.219986800823, 612.3784358875745, 617.6664601115348, 619.2476141488114, 637.677972623822, 639.8528338482082, 5277.393099793496, 5311.256424100552, 5414.10667239901, 5436.320459942507, 5477.808276710376, 117235.35169906313, 117834.0, 119666.0, 121918.0, 123735.0, 105529.47235263775, 105752.0, 107344.0, 107567.46216268868, 108721.45639271753, 91291.0, 91984.0, 92315.53842230747, 93937.0, 96138.03861961431, 9996.60013599477, 10028.348157369748, 10065.547050382896, 10324.174066074545, 10344.172466202835, 42898.0, 43496.782516087216, 43872.0, 44965.550344497446, 45874.54125458703, 57152.42847571471, 57338.0, 57945.420545794004, 57986.42013579979, 57989.0, 14490.0, 14528.055676381002, 14840.777388338967, 14852.40590376416, 14855.0, 16922.576935576708, 16974.74537881908, 17052.14739263007, 17145.742813857552, 17266.395676151777, 15075.0, 15520.14639194855, 15574.299156537756, 15652.92173539125, 15821.604459888595, 14562.417503300174, 14612.634684132556, 14672.633184170481, 14975.550733477578, 14986.0, 15879.2060396982, 15911.443099491926, 16004.359825606845, 16254.106024168785, 16368.427105051265, 11020.559177632806, 11048.613298534512, 11208.383538905113, 11377.658670239905, 11394.715132121763, 11239.157063220455, 11275.830862537232, 11281.323120612462, 11396.0, 11438.485268162785], "value": 8718.279906956657, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1469.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 114.0, 113.0, 110.0, 111.0, 122.0, 114.0, 108.0, 114.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 124.0, 117.0, 116.0, 118.0, 116.0, 119.0, 118.0, 118.0, 123.0], "value": 118.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 165.0, 165.0, 165.0, 176.0, 172.0, 169.0, 165.0, 171.0, 164.0], "value": 168.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 74.0, 79.0, 80.0, 87.0, 76.0, 74.0, 74.0, 85.0], "value": 77.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 84.0, 89.0, 79.0, 78.0, 91.0, 88.0, 81.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 115.0, 114.0, 114.0, 118.0, 125.0, 111.0, 118.0, 116.0], "value": 115.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 149.0, 157.0, 158.0, 149.0, 148.0, 158.0, 151.0, 153.0, 150.0], "value": 152.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 100.0, 97.0, 96.0, 96.0, 96.0, 95.0, 96.0, 99.0, 100.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 58.0, 52.0, 63.0, 54.0, 54.0, 53.0, 55.0, 54.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 41.0, 38.0, 38.0, 39.0, 41.0, 45.0, 38.0, 44.0, 39.0], "value": 40.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 79.0, 80.0, 83.0, 75.0, 82.0, 77.0, 75.0, 75.0, 84.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 116.0, 125.0, 123.0, 127.0, 124.0, 118.0, 124.0, 133.0, 124.0], "value": 124.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 179.0, 189.0, 174.0, 168.0, 183.0, 173.0, 179.0, 176.0, 175.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 64.0, 64.0, 67.0, 67.0, 64.0, 66.0, 68.0, 64.0], "value": 65.7, "unit": "score"}]}]} 02:31:26 INFO - Return code: 0 02:31:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:31:26 INFO - # TBPL SUCCESS # 02:31:26 INFO - Running post-action listener: _package_coverage_data 02:31:26 INFO - Running post-action listener: _resource_record_post_action 02:31:26 INFO - [mozharness: 2017-09-10 09:31:26.356123Z] Finished run-tests step (success) 02:31:26 INFO - Running post-run listener: _resource_record_post_run 02:31:26 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 7671808; Write bytes: 309002240; Read time: 3624; Write time: 172736 02:31:26 INFO - TinderboxPrint: CPU usage
13.3% 02:31:26 INFO - TinderboxPrint: I/O read bytes / time
7,671,808 / 3,624 02:31:26 INFO - TinderboxPrint: I/O write bytes / time
309,002,240 / 172,736 02:31:26 INFO - TinderboxPrint: CPU idle
8,265.0 (86.7%) 02:31:26 INFO - TinderboxPrint: CPU user
1,201.9 (12.6%) 02:31:26 INFO - TinderboxPrint: Swap in / out
0 / 0 02:31:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 13144064; Read time: 0; Write time: 2660 02:31:26 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 02:31:26 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 7667712; Write bytes: 295854080; Read time: 3608; Write time: 169724 02:31:27 INFO - Running post-run listener: _upload_blobber_files 02:31:27 INFO - Blob upload gear active. 02:31:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:31:27 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 02:31:27 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'] 02:31:27 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 02:31:27 INFO - (blobuploader) - INFO - Open directory for files ... 02:31:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:31:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:31:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:31:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:31:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:31:28 INFO - (blobuploader) - INFO - Done attempting. 02:31:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:31:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:31:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:31:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:31:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:31:35 INFO - (blobuploader) - INFO - Done attempting. 02:31:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:31:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:31:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:31:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:31:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:31:36 INFO - (blobuploader) - INFO - Done attempting. 02:31:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:31:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:31:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:31:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:31:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:31:37 INFO - (blobuploader) - INFO - Done attempting. 02:31:37 INFO - (blobuploader) - INFO - Iteration through files over. 02:31:37 INFO - Return code: 0 02:31:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:31:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:31:37 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c6a7c287d86b10664354b772db0201d975ff7f0ea87ba11718dbad19345f5784ec9214e2b16a5e1e8e5659df85be690414d7d881b651c7b089e9e3139947632", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66eb3c8e1a0088ae5b4d4356ddd4493550cee7371dc6b35efea9d5464e8260bd939bda413b0afc8cdd5d244b9275d87c0ebe25e398bd6ad06bc859977e2ea6e9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/072c6d22dd26c7ab80fe56a51b559209b29b4118da0451d10067af89426bc62795080cd8afa700b311e415c5f05b096a9f0e1d33bfd20da0645e60affb549da3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5582b724baf080d52938f5214d48054f0796fafe55abe3459950226c0016b5a2c8dd35464e94761b6d7c24a53b695d77ecef5d0ecb6e0004080acbd7092ff54"} 02:31:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:31:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:31:37 INFO - Contents: 02:31:37 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c6a7c287d86b10664354b772db0201d975ff7f0ea87ba11718dbad19345f5784ec9214e2b16a5e1e8e5659df85be690414d7d881b651c7b089e9e3139947632", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66eb3c8e1a0088ae5b4d4356ddd4493550cee7371dc6b35efea9d5464e8260bd939bda413b0afc8cdd5d244b9275d87c0ebe25e398bd6ad06bc859977e2ea6e9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/072c6d22dd26c7ab80fe56a51b559209b29b4118da0451d10067af89426bc62795080cd8afa700b311e415c5f05b096a9f0e1d33bfd20da0645e60affb549da3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5582b724baf080d52938f5214d48054f0796fafe55abe3459950226c0016b5a2c8dd35464e94761b6d7c24a53b695d77ecef5d0ecb6e0004080acbd7092ff54"} 02:31:37 INFO - Running post-run listener: copy_logs_to_upload_dir 02:31:37 INFO - Copying logs to upload dir... 02:31:37 INFO - mkdir: /builds/slave/test/build/upload/logs 02:31:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1322.420289 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 2 secs) (at 2017-09-10 02:31:37.227164) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-10 02:31:37.230979) ========= 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=0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612 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/1c6a7c287d86b10664354b772db0201d975ff7f0ea87ba11718dbad19345f5784ec9214e2b16a5e1e8e5659df85be690414d7d881b651c7b089e9e3139947632", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66eb3c8e1a0088ae5b4d4356ddd4493550cee7371dc6b35efea9d5464e8260bd939bda413b0afc8cdd5d244b9275d87c0ebe25e398bd6ad06bc859977e2ea6e9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/072c6d22dd26c7ab80fe56a51b559209b29b4118da0451d10067af89426bc62795080cd8afa700b311e415c5f05b096a9f0e1d33bfd20da0645e60affb549da3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5582b724baf080d52938f5214d48054f0796fafe55abe3459950226c0016b5a2c8dd35464e94761b6d7c24a53b695d77ecef5d0ecb6e0004080acbd7092ff54"} build_url:https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011328 build_url: 'https://queue.taskcluster.net/v1/task/DRhsJxRzQc2SbL7C1Z9-Ww/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c6a7c287d86b10664354b772db0201d975ff7f0ea87ba11718dbad19345f5784ec9214e2b16a5e1e8e5659df85be690414d7d881b651c7b089e9e3139947632", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66eb3c8e1a0088ae5b4d4356ddd4493550cee7371dc6b35efea9d5464e8260bd939bda413b0afc8cdd5d244b9275d87c0ebe25e398bd6ad06bc859977e2ea6e9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/072c6d22dd26c7ab80fe56a51b559209b29b4118da0451d10067af89426bc62795080cd8afa700b311e415c5f05b096a9f0e1d33bfd20da0645e60affb549da3", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5582b724baf080d52938f5214d48054f0796fafe55abe3459950226c0016b5a2c8dd35464e94761b6d7c24a53b695d77ecef5d0ecb6e0004080acbd7092ff54"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-10 02:31:37.259399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:31:37.259749) ========= 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=0e2a256762fed810e2f0f810000001b3-1505034572.374462-308159612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004581 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:31:37.343948) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-10 02:31:37.344332) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-10 02:31:37.344715) ========= ========= Total master_lag: 0.24 =========