builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-058 starttime: 1506990617.47 results: success (0) revision: 8c6b4fd1d76927cf8b07eb045c5aadd47b77d910 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:17.470316) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:17.471011) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:17.471277) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:17.628601) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:17.628860) ========= 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-1506979515.532875-1647473711 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-02 17:30:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 93.9M=0s 2017-10-02 17:30:17 (93.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131124 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:17.781587) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:17.781871) ========= 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-1506979515.532875-1647473711 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020671 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:17.831608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:17.831907) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c6b4fd1d76927cf8b07eb045c5aadd47b77d910 --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 8c6b4fd1d76927cf8b07eb045c5aadd47b77d910 --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-1506979515.532875-1647473711 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-02 17:30:17,905 truncating revision to first 12 chars 2017-10-02 17:30:17,905 Setting DEBUG logging. 2017-10-02 17:30:17,905 attempt 1/10 2017-10-02 17:30:17,905 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c6b4fd1d769?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-02 17:30:18,272 unpacking tar archive at: mozilla-central-8c6b4fd1d769/testing/mozharness/ program finished with exit code 0 elapsedTime=0.624407 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:18.472813) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:18.473113) ========= script_repo_revision: 8c6b4fd1d76927cf8b07eb045c5aadd47b77d910 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:18.473475) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:18.473752) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 17:30:18.487424) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-10-02 17:30:18.487728) ========= /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-1506979515.532875-1647473711 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:30:18 INFO - MultiFileLogger online at 20171002 17:30:18 in /builds/slave/test 17:30:18 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 17:30:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:30:18 INFO - {'append_to_log': False, 17:30:18 INFO - 'base_work_dir': '/builds/slave/test', 17:30:18 INFO - 'blob_upload_branch': 'Firefox', 17:30:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:30:18 INFO - 'branch': 'Firefox', 17:30:18 INFO - 'buildbot_json_path': 'buildprops.json', 17:30:18 INFO - 'code_coverage': False, 17:30:18 INFO - 'config_files': ('talos/linux_config.py',), 17:30:18 INFO - 'default_actions': ('clobber', 17:30:18 INFO - 'read-buildbot-config', 17:30:18 INFO - 'download-and-extract', 17:30:18 INFO - 'populate-webroot', 17:30:18 INFO - 'create-virtualenv', 17:30:18 INFO - 'install', 17:30:18 INFO - 'setup-mitmproxy', 17:30:18 INFO - 'run-tests'), 17:30:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:30:18 INFO - 'disable_ccov_upload': False, 17:30:18 INFO - 'disable_stylo': False, 17:30:18 INFO - 'download_minidump_stackwalk': True, 17:30:18 INFO - 'download_symbols': 'ondemand', 17:30:18 INFO - 'enable_stylo': False, 17:30:18 INFO - 'enable_webrender': False, 17:30:18 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:30:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:30:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:30:18 INFO - 'gecko_profile': False, 17:30:18 INFO - 'gecko_profile_interval': 0, 17:30:18 INFO - 'installer_path': 'installer.exe', 17:30:18 INFO - 'log_level': 'info', 17:30:18 INFO - 'log_name': 'talos', 17:30:18 INFO - 'log_to_console': True, 17:30:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:30:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:30:18 INFO - 'opt_config_files': (), 17:30:18 INFO - 'pip_index': False, 17:30:18 INFO - 'suite': 'dromaeojs-e10s', 17:30:18 INFO - 'system_bits': '32', 17:30:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:30:18 INFO - 'title': 'talos-linux64-ix-058', 17:30:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:30:18 INFO - 'use_talos_json': True, 17:30:18 INFO - 'verify': 'False', 17:30:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:30:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:30:18 INFO - 'work_dir': 'build'} 17:30:18 INFO - [mozharness: 2017-10-03 00:30:18.704431Z] Running clobber step. 17:30:18 INFO - Running pre-action listener: _resource_record_pre_action 17:30:18 INFO - Running main action method: clobber 17:30:18 INFO - rmtree: /builds/slave/test/build 17:30:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:30:20 INFO - Running post-action listener: _resource_record_post_action 17:30:20 INFO - [mozharness: 2017-10-03 00:30:20.204994Z] Finished clobber step (success) 17:30:20 INFO - [mozharness: 2017-10-03 00:30:20.205092Z] Running read-buildbot-config step. 17:30:20 INFO - Running pre-action listener: _resource_record_pre_action 17:30:20 INFO - Running main action method: read_buildbot_config 17:30:20 INFO - Using buildbot properties: 17:30:20 INFO - { 17:30:20 INFO - "project": "", 17:30:20 INFO - "product": "firefox", 17:30:20 INFO - "who": "kwierso@gmail.com", 17:30:20 INFO - "installer_path": "public/build/target.tar.bz2", 17:30:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:30:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:30:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 17:30:20 INFO - "stage_platform": "linux64-pgo", 17:30:20 INFO - "basedir": "/builds/slave/test", 17:30:20 INFO - "buildnumber": 215, 17:30:20 INFO - "platform": "ubuntu64_hw", 17:30:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:30:20 INFO - "slavebuilddir": "test", 17:30:20 INFO - "taskId": "UPXJYbYWQDqDwN5IGO3amw", 17:30:20 INFO - "branch": "mozilla-central", 17:30:20 INFO - "script_repo_revision": "production", 17:30:20 INFO - "revision": "8c6b4fd1d76927cf8b07eb045c5aadd47b77d910", 17:30:20 INFO - "slavename": "talos-linux64-ix-058", 17:30:20 INFO - "repo_path": "mozilla-central" 17:30:20 INFO - } 17:30:20 INFO - Finding installer, test and symbols from parent task. 17:30:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UPXJYbYWQDqDwN5IGO3amw'}, attempt #1 17:30:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UPXJYbYWQDqDwN5IGO3amw'}, attempt #1 17:30:21 INFO - Task dependencies: O8PaNEsoTpCKDFDDGb4FLA 17:30:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA'}, attempt #1 17:30:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.tar.bz2 17:30:21 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.test_packages.json 17:30:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.crashreporter-symbols.zip 17:30:21 INFO - Running post-action listener: _resource_record_post_action 17:30:21 INFO - [mozharness: 2017-10-03 00:30:21.793018Z] Finished read-buildbot-config step (success) 17:30:21 INFO - [mozharness: 2017-10-03 00:30:21.793235Z] Running download-and-extract step. 17:30:21 INFO - Running pre-action listener: _resource_record_pre_action 17:30:21 INFO - Running main action method: download_and_extract 17:30:21 INFO - mkdir: /builds/slave/test/build/tests 17:30: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')]} 17:30:21 INFO - trying https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.test_packages.json 17:30:21 INFO - Downloading https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:30:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:30:22 INFO - Downloaded 989 bytes. 17:30:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:30:22 INFO - Using the following test package requirements: 17:30:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:30:22 INFO - u'common': [u'target.common.tests.zip'], 17:30:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:30:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:30:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:30:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:30:22 INFO - u'mozbase': [u'target.common.tests.zip'], 17:30:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:30:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:30:22 INFO - u'web-platform': [u'target.common.tests.zip', 17:30:22 INFO - u'target.web-platform.tests.tar.gz'], 17:30:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:30:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 17:30:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.common.tests.zip 17:30:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:30:22 INFO - Fetch https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.common.tests.zip into memory 17:30:25 INFO - Content-Length response header: 55909693 17:30:25 INFO - Bytes received: 55909693 17:30:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.talos.tests.zip 17:30:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:30:35 INFO - Fetch https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.talos.tests.zip into memory 17:30:37 INFO - Content-Length response header: 14522427 17:30:37 INFO - Bytes received: 14522427 17:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:38 INFO - trying https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.tar.bz2 17:30:38 INFO - Downloading https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:30:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:30:40 INFO - Downloaded 62344145 bytes. 17:30:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.tar.bz2 17:30:40 INFO - mkdir: /builds/slave/test/properties 17:30:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:30:40 INFO - Writing to file /builds/slave/test/properties/build_url 17:30:40 INFO - Contents: 17:30:40 INFO - build_url:https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.tar.bz2 17:30:40 INFO - Running post-action listener: _resource_record_post_action 17:30:40 INFO - Running post-action listener: find_tests_for_verification 17:30:40 INFO - Running post-action listener: set_extra_try_arguments 17:30:40 INFO - [mozharness: 2017-10-03 00:30:40.753890Z] Finished download-and-extract step (success) 17:30:40 INFO - [mozharness: 2017-10-03 00:30:40.754034Z] Running populate-webroot step. 17:30:40 INFO - Running pre-action listener: _resource_record_pre_action 17:30:40 INFO - Running main action method: populate_webroot 17:30:40 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:30:40 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['tresize', 17:30:40 INFO - 'tcanvasmark']}, 17:30:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:30:40 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['dromaeo_css', 17:30:40 INFO - 'kraken']}, 17:30:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:30:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:40 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:40 INFO - 'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:30:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:30:40 INFO - 'tests': ['damp', 'tps']}, 17:30:40 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:40 INFO - 'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['damp', 'tps']}, 17:30:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:30:40 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['dromaeo_dom']}, 17:30:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:30:40 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['basic_compositor_video', 17:30:40 INFO - 'glvideo']}, 17:30:40 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:30:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:30:40 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:40 INFO - 'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['ts_paint_webext', 17:30:40 INFO - 'tp5o_webext']}, 17:30:40 INFO - 'other-e10s': {'tests': ['a11yr', 17:30:40 INFO - 'ts_paint', 17:30:40 INFO - 'tpaint', 17:30:40 INFO - 'sessionrestore', 17:30:40 INFO - 'sessionrestore_many_windows', 17:30:40 INFO - 'sessionrestore_no_auto_restore', 17:30:40 INFO - 'tabpaint', 17:30:40 INFO - 'cpstartup']}, 17:30:40 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['a11yr', 17:30:40 INFO - 'ts_paint', 17:30:40 INFO - 'tpaint', 17:30:40 INFO - 'sessionrestore', 17:30:40 INFO - 'sessionrestore_many_windows', 17:30:40 INFO - 'sessionrestore_no_auto_restore', 17:30:40 INFO - 'tabpaint', 17:30:40 INFO - 'cpstartup']}, 17:30:40 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 17:30:40 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 17:30:40 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['perf_reftest_singletons']}, 17:30:40 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['perf_reftest']}, 17:30:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:30:40 INFO - 'tsvgr_opacity', 17:30:40 INFO - 'tart', 17:30:40 INFO - 'tscrollx', 17:30:40 INFO - 'tsvg_static']}, 17:30:40 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['tsvgx', 17:30:40 INFO - 'tsvgr_opacity', 17:30:40 INFO - 'tart', 17:30:40 INFO - 'tscrollx', 17:30:40 INFO - 'tsvg_static']}, 17:30:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:30:40 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:40 INFO - 'talos_options': ['--disable-stylo'], 17:30:40 INFO - 'tests': ['tp5o']}, 17:30:40 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:40 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:30:40 INFO - 'talos_options': ['--mitmproxy', 17:30:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:40 INFO - '--firstNonBlankPaint'], 17:30:40 INFO - 'tests': ['tp6_google', 17:30:40 INFO - 'tp6_youtube', 17:30:40 INFO - 'tp6_amazon', 17:30:40 INFO - 'tp6_facebook']}, 17:30:40 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:40 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:30:40 INFO - 'talos_options': ['--disable-stylo', 17:30:40 INFO - '--mitmproxy', 17:30:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:40 INFO - '--firstNonBlankPaint'], 17:30:40 INFO - 'tests': ['tp6_google', 17:30:40 INFO - 'tp6_youtube', 17:30:40 INFO - 'tp6_amazon', 17:30:40 INFO - 'tp6_facebook']}, 17:30:40 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:30:40 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:30:40 INFO - 'talos_options': ['--stylo-threads=1', 17:30:40 INFO - '--mitmproxy', 17:30:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:30:40 INFO - '--firstNonBlankPaint'], 17:30:40 INFO - 'tests': ['tp6_google', 17:30:40 INFO - 'tp6_youtube', 17:30:40 INFO - 'tp6_amazon', 17:30:40 INFO - 'tp6_facebook']}, 17:30:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:30:40 INFO - 'talos_options': ['--xperf_path', 17:30:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:40 INFO - 'tests': ['tp5n']}, 17:30:40 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:30:40 INFO - 'talos_options': ['--disable-stylo', 17:30:40 INFO - '--xperf_path', 17:30:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:30:40 INFO - 'tests': ['tp5n']}}} 17:30:40 INFO - Running post-action listener: _resource_record_post_action 17:30:40 INFO - [mozharness: 2017-10-03 00:30:40.773899Z] Finished populate-webroot step (success) 17:30:40 INFO - [mozharness: 2017-10-03 00:30:40.773988Z] Running create-virtualenv step. 17:30:40 INFO - Running pre-action listener: _resource_record_pre_action 17:30:40 INFO - Running main action method: create_virtualenv 17:30:40 INFO - Creating virtualenv /builds/slave/test/build/venv 17:30:40 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:30:40 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:30:40 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:30:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:30:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:30:41 INFO - Installing distribute.............................................................................................................................................................................................done. 17:30:41 INFO - Installing pip...............done. 17:30:41 INFO - Return code: 0 17:30:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:30:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:30:41 INFO - Reading from file tmpfile_stdout 17:30:41 INFO - Output received: 17:30:41 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:30:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:41 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 0x18ff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c69d60>, '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 0x1c6aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aef0>, '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-1506979515.532875-1647473711', '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 17:30:41 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 17:30:41 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 17:30:41 INFO - Using env: {'DISPLAY': ':0', 17:30:41 INFO - 'HOME': '/home/cltbld', 17:30:41 INFO - 'LANG': 'en_US.UTF-8', 17:30:41 INFO - 'LANGUAGE': 'en_US:en', 17:30:41 INFO - 'LOGNAME': 'cltbld', 17:30:41 INFO - 'MAIL': '/var/mail/cltbld', 17:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:41 INFO - 'MOZ_NO_REMOTE': '1', 17:30:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:41 INFO - 'NO_EM_RESTART': '1', 17:30:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:41 INFO - 'PWD': '/builds/slave/test', 17:30:41 INFO - 'SHELL': '/bin/bash', 17:30:41 INFO - 'SHLVL': '1', 17:30:41 INFO - 'TERM': 'linux', 17:30:41 INFO - 'TMOUT': '86400', 17:30:41 INFO - 'USER': 'cltbld', 17:30:41 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:41 INFO - '_': '/tools/buildbot/bin/python'} 17:30:45 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:30:45 INFO - Downloading/unpacking pip>=1.5 17:30:45 INFO - Running setup.py egg_info for package pip 17:30:45 INFO - warning: no files found matching 'pip/cacert.pem' 17:30:45 INFO - warning: no files found matching '*.html' under directory 'docs' 17:30:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:30:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:30:45 INFO - Installing collected packages: pip 17:30:45 INFO - Found existing installation: pip 1.1 17:30:45 INFO - Uninstalling pip: 17:30:45 INFO - Successfully uninstalled pip 17:30:45 INFO - Running setup.py install for pip 17:30:45 INFO - warning: no files found matching 'pip/cacert.pem' 17:30:45 INFO - warning: no files found matching '*.html' under directory 'docs' 17:30:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:30:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:30:45 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:30:45 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:30:45 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:30:45 INFO - Successfully installed pip 17:30:45 INFO - Cleaning up... 17:30:45 INFO - Return code: 0 17:30:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:45 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 0x18ff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c69d60>, '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 0x1c6aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aef0>, '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-1506979515.532875-1647473711', '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 17:30:45 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 17:30:45 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 17:30:45 INFO - Using env: {'DISPLAY': ':0', 17:30:45 INFO - 'HOME': '/home/cltbld', 17:30:45 INFO - 'LANG': 'en_US.UTF-8', 17:30:45 INFO - 'LANGUAGE': 'en_US:en', 17:30:45 INFO - 'LOGNAME': 'cltbld', 17:30:45 INFO - 'MAIL': '/var/mail/cltbld', 17:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:45 INFO - 'MOZ_NO_REMOTE': '1', 17:30:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:45 INFO - 'NO_EM_RESTART': '1', 17:30:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:45 INFO - 'PWD': '/builds/slave/test', 17:30:45 INFO - 'SHELL': '/bin/bash', 17:30:45 INFO - 'SHLVL': '1', 17:30:45 INFO - 'TERM': 'linux', 17:30:45 INFO - 'TMOUT': '86400', 17:30:45 INFO - 'USER': 'cltbld', 17:30:45 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:45 INFO - '_': '/tools/buildbot/bin/python'} 17:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:45 INFO - Downloading/unpacking psutil>=3.1.1 17:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:30:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:46 INFO - Installing collected packages: psutil 17:30:46 INFO - Running setup.py install for psutil 17:30:46 INFO - building 'psutil._psutil_linux' extension 17:30:46 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 17:30:46 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 17:30:46 INFO - building 'psutil._psutil_posix' extension 17:30:46 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 17:30:46 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 17:30:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:30:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:30:46 INFO - Successfully installed psutil 17:30:46 INFO - Cleaning up... 17:30:47 INFO - Return code: 0 17:30:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:30: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')]} 17:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30: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')]} 17:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18ff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c69d60>, '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 0x1c6aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aef0>, '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-1506979515.532875-1647473711', '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 17:30:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:30:47 INFO - Using env: {'DISPLAY': ':0', 17:30:47 INFO - 'HOME': '/home/cltbld', 17:30:47 INFO - 'LANG': 'en_US.UTF-8', 17:30:47 INFO - 'LANGUAGE': 'en_US:en', 17:30:47 INFO - 'LOGNAME': 'cltbld', 17:30:47 INFO - 'MAIL': '/var/mail/cltbld', 17:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:47 INFO - 'MOZ_NO_REMOTE': '1', 17:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:47 INFO - 'NO_EM_RESTART': '1', 17:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:47 INFO - 'PWD': '/builds/slave/test', 17:30:47 INFO - 'SHELL': '/bin/bash', 17:30:47 INFO - 'SHLVL': '1', 17:30:47 INFO - 'TERM': 'linux', 17:30:47 INFO - 'TMOUT': '86400', 17:30:47 INFO - 'USER': 'cltbld', 17:30:47 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:47 INFO - '_': '/tools/buildbot/bin/python'} 17:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:30:47 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 17:30:47 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 17:30:47 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 17:30:47 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 17:30:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:30:48 INFO - Installing collected packages: mozsystemmonitor 17:30:48 INFO - Running setup.py install for mozsystemmonitor 17:30:48 INFO - Successfully installed mozsystemmonitor 17:30:48 INFO - Cleaning up... 17:30:48 INFO - Return code: 0 17:30:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:48 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 0x18ff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c69d60>, '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 0x1c6aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aef0>, '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-1506979515.532875-1647473711', '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 17:30:48 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 17:30:48 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 17:30:48 INFO - Using env: {'DISPLAY': ':0', 17:30:48 INFO - 'HOME': '/home/cltbld', 17:30:48 INFO - 'LANG': 'en_US.UTF-8', 17:30:48 INFO - 'LANGUAGE': 'en_US:en', 17:30:48 INFO - 'LOGNAME': 'cltbld', 17:30:48 INFO - 'MAIL': '/var/mail/cltbld', 17:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:48 INFO - 'MOZ_NO_REMOTE': '1', 17:30:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:48 INFO - 'NO_EM_RESTART': '1', 17:30:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:48 INFO - 'PWD': '/builds/slave/test', 17:30:48 INFO - 'SHELL': '/bin/bash', 17:30:48 INFO - 'SHLVL': '1', 17:30:48 INFO - 'TERM': 'linux', 17:30:48 INFO - 'TMOUT': '86400', 17:30:48 INFO - 'USER': 'cltbld', 17:30:48 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:48 INFO - '_': '/tools/buildbot/bin/python'} 17:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:48 INFO - Downloading/unpacking jsonschema==2.5.1 17:30:48 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 17:30:48 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 17:30:48 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 17:30:48 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 17:30:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:30:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:30:49 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 17:30:49 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 17:30:49 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 17:30:49 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 17:30:49 INFO - Downloading functools32-3.2.3-2.tar.gz 17:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:30:50 INFO - warning: no files found matching '*.txt' 17:30:50 INFO - no previously-included directories found matching 'build' 17:30:50 INFO - no previously-included directories found matching 'dist' 17:30:50 INFO - no previously-included directories found matching '.git*' 17:30:50 INFO - Installing collected packages: jsonschema, functools32 17:30:50 INFO - Running setup.py install for functools32 17:30:50 INFO - warning: no files found matching '*.txt' 17:30:50 INFO - no previously-included directories found matching 'build' 17:30:50 INFO - no previously-included directories found matching 'dist' 17:30:50 INFO - no previously-included directories found matching '.git*' 17:30:50 INFO - Successfully installed jsonschema functools32 17:30:50 INFO - Cleaning up... 17:30:50 INFO - Return code: 0 17:30:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:50 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 0x18ff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c69d60>, '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 0x1c6aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aef0>, '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-1506979515.532875-1647473711', '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 17:30:50 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 17:30:50 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 17:30:50 INFO - Using env: {'DISPLAY': ':0', 17:30:50 INFO - 'HOME': '/home/cltbld', 17:30:50 INFO - 'LANG': 'en_US.UTF-8', 17:30:50 INFO - 'LANGUAGE': 'en_US:en', 17:30:50 INFO - 'LOGNAME': 'cltbld', 17:30:50 INFO - 'MAIL': '/var/mail/cltbld', 17:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:50 INFO - 'MOZ_NO_REMOTE': '1', 17:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:50 INFO - 'NO_EM_RESTART': '1', 17:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:50 INFO - 'PWD': '/builds/slave/test', 17:30:50 INFO - 'SHELL': '/bin/bash', 17:30:50 INFO - 'SHLVL': '1', 17:30:50 INFO - 'TERM': 'linux', 17:30:50 INFO - 'TMOUT': '86400', 17:30:50 INFO - 'USER': 'cltbld', 17:30:50 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:50 INFO - '_': '/tools/buildbot/bin/python'} 17:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:30:50 INFO - Cleaning up... 17:30:50 INFO - Return code: 0 17:30:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:50 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 0x18ff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c69d60>, '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 0x1c6aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aef0>, '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-1506979515.532875-1647473711', '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 17:30:50 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 17:30:50 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 17:30:50 INFO - Using env: {'DISPLAY': ':0', 17:30:50 INFO - 'HOME': '/home/cltbld', 17:30:50 INFO - 'LANG': 'en_US.UTF-8', 17:30:50 INFO - 'LANGUAGE': 'en_US:en', 17:30:50 INFO - 'LOGNAME': 'cltbld', 17:30:50 INFO - 'MAIL': '/var/mail/cltbld', 17:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:50 INFO - 'MOZ_NO_REMOTE': '1', 17:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:50 INFO - 'NO_EM_RESTART': '1', 17:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:50 INFO - 'PWD': '/builds/slave/test', 17:30:50 INFO - 'SHELL': '/bin/bash', 17:30:50 INFO - 'SHLVL': '1', 17:30:50 INFO - 'TERM': 'linux', 17:30:50 INFO - 'TMOUT': '86400', 17:30:50 INFO - 'USER': 'cltbld', 17:30:50 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:50 INFO - '_': '/tools/buildbot/bin/python'} 17:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:50 INFO - Downloading/unpacking blobuploader==1.2.4 17:30:50 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 17:30:50 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 17:30:50 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 17:30:50 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 17:30:51 INFO - Downloading blobuploader-1.2.4.tar.gz 17:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:30:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:30:51 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 17:30:51 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 17:30:51 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 17:30:51 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 17:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:30:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:52 INFO - Downloading docopt-0.6.1.tar.gz 17:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:30:52 INFO - Installing collected packages: blobuploader, requests, docopt 17:30:52 INFO - Running setup.py install for blobuploader 17:30:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:30:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:30:52 INFO - Running setup.py install for requests 17:30:52 INFO - Running setup.py install for docopt 17:30:52 INFO - Successfully installed blobuploader requests docopt 17:30:52 INFO - Cleaning up... 17:30:52 INFO - Return code: 0 17:30:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:52 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 0x18ff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c69d60>, '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 0x1c6aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aef0>, '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-1506979515.532875-1647473711', '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 17:30:52 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 17:30:52 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 17:30:52 INFO - Using env: {'DISPLAY': ':0', 17:30:52 INFO - 'HOME': '/home/cltbld', 17:30:52 INFO - 'LANG': 'en_US.UTF-8', 17:30:52 INFO - 'LANGUAGE': 'en_US:en', 17:30:52 INFO - 'LOGNAME': 'cltbld', 17:30:52 INFO - 'MAIL': '/var/mail/cltbld', 17:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:52 INFO - 'MOZ_NO_REMOTE': '1', 17:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:52 INFO - 'NO_EM_RESTART': '1', 17:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:52 INFO - 'PWD': '/builds/slave/test', 17:30:52 INFO - 'SHELL': '/bin/bash', 17:30:52 INFO - 'SHLVL': '1', 17:30:52 INFO - 'TERM': 'linux', 17:30:52 INFO - 'TMOUT': '86400', 17:30:52 INFO - 'USER': 'cltbld', 17:30:52 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:52 INFO - '_': '/tools/buildbot/bin/python'} 17:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:53 INFO - Running setup.py (path:/tmp/pip-E_e25g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:53 INFO - Running setup.py (path:/tmp/pip-MCmKxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:53 INFO - Running setup.py (path:/tmp/pip-fmQK9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:53 INFO - Running setup.py (path:/tmp/pip-BDG1t9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:53 INFO - Running setup.py (path:/tmp/pip-WhuY8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:53 INFO - Running setup.py (path:/tmp/pip-unpUPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:53 INFO - Running setup.py (path:/tmp/pip-vz146H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:53 INFO - Running setup.py (path:/tmp/pip-CLlBNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:53 INFO - Running setup.py (path:/tmp/pip-07qCnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:53 INFO - Running setup.py (path:/tmp/pip-es3Npj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:53 INFO - Running setup.py (path:/tmp/pip-s56BnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:53 INFO - Running setup.py (path:/tmp/pip-TvzG19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:54 INFO - Running setup.py (path:/tmp/pip-3NvoJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:54 INFO - Running setup.py (path:/tmp/pip-SutQ2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:54 INFO - Running setup.py (path:/tmp/pip-EQDCKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:54 INFO - Running setup.py (path:/tmp/pip-woryA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:54 INFO - Running setup.py (path:/tmp/pip-imj74U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:30:54 INFO - Running setup.py install for manifestparser 17:30:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:30:54 INFO - Running setup.py install for mozcrash 17:30:54 INFO - Running setup.py install for mozdebug 17:30:54 INFO - Running setup.py install for mozdevice 17:30:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:30:54 INFO - Running setup.py install for mozfile 17:30:54 INFO - Running setup.py install for mozhttpd 17:30:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:30:54 INFO - Running setup.py install for mozinfo 17:30:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Running setup.py install for mozInstall 17:30:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Running setup.py install for mozleak 17:30:55 INFO - Running setup.py install for mozlog 17:30:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Running setup.py install for moznetwork 17:30:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Running setup.py install for mozprocess 17:30:55 INFO - Running setup.py install for mozprofile 17:30:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Running setup.py install for mozrunner 17:30:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:30:55 INFO - Running setup.py install for mozscreenshot 17:30:55 INFO - Running setup.py install for moztest 17:30:56 INFO - Running setup.py install for mozversion 17:30:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:30:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:30:56 INFO - Cleaning up... 17:30:56 INFO - Return code: 0 17:30:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:56 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 0x18ff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c69d60>, '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 0x1c6aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aef0>, '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-1506979515.532875-1647473711', '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 17:30:56 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 17:30:56 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 17:30:56 INFO - Using env: {'DISPLAY': ':0', 17:30:56 INFO - 'HOME': '/home/cltbld', 17:30:56 INFO - 'LANG': 'en_US.UTF-8', 17:30:56 INFO - 'LANGUAGE': 'en_US:en', 17:30:56 INFO - 'LOGNAME': 'cltbld', 17:30:56 INFO - 'MAIL': '/var/mail/cltbld', 17:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:56 INFO - 'MOZ_NO_REMOTE': '1', 17:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:56 INFO - 'NO_EM_RESTART': '1', 17:30:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:56 INFO - 'PWD': '/builds/slave/test', 17:30:56 INFO - 'SHELL': '/bin/bash', 17:30:56 INFO - 'SHLVL': '1', 17:30:56 INFO - 'TERM': 'linux', 17:30:56 INFO - 'TMOUT': '86400', 17:30:56 INFO - 'USER': 'cltbld', 17:30:56 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:56 INFO - '_': '/tools/buildbot/bin/python'} 17:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:30:56 INFO - Running setup.py (path:/tmp/pip-ol9Xix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:30:56 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)) 17:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:30:56 INFO - Running setup.py (path:/tmp/pip-0cvY5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:30:56 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)) 17:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:30:56 INFO - Running setup.py (path:/tmp/pip-xPp6TM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:30:56 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)) 17:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:30:56 INFO - Running setup.py (path:/tmp/pip-BElBzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:30:56 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)) 17:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:30:56 INFO - Running setup.py (path:/tmp/pip-kayJmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:30:56 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)) 17:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:30:56 INFO - Running setup.py (path:/tmp/pip-NMWF3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:30:56 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)) 17:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:30:56 INFO - Running setup.py (path:/tmp/pip-YQ1VJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:30:56 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)) 17:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:30:56 INFO - Running setup.py (path:/tmp/pip-KWp73R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:30:56 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)) 17:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:30:56 INFO - Running setup.py (path:/tmp/pip-3KxRwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:30:56 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)) 17:30:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:30:56 INFO - Running setup.py (path:/tmp/pip-HJmgmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:30:57 INFO - Running setup.py (path:/tmp/pip-LdFyin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:30:57 INFO - Running setup.py (path:/tmp/pip-X_QtMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:30:57 INFO - Running setup.py (path:/tmp/pip-qTSxI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:30:57 INFO - Running setup.py (path:/tmp/pip-jrhQR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:57 INFO - Running setup.py (path:/tmp/pip-OMExF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:30:57 INFO - Running setup.py (path:/tmp/pip-e1bgo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:30:57 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)) 17:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:30:57 INFO - Running setup.py (path:/tmp/pip-Fvc_je-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:30:57 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)) 17:30:57 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)) 17:30:57 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)) 17:30:57 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)) 17:30:57 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)) 17:30:57 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)) 17:30:57 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)) 17:30:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:30:57 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 17:30:57 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 17:30:57 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 17:30:57 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 17:30:58 INFO - Downloading blessings-1.6.tar.gz 17:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:30:58 INFO - Installing collected packages: blessings 17:30:58 INFO - Running setup.py install for blessings 17:30:58 INFO - Successfully installed blessings 17:30:58 INFO - Cleaning up... 17:30:58 INFO - Return code: 0 17:30:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:30:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:30:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:30:59 INFO - Reading from file tmpfile_stdout 17:30:59 INFO - Current package versions: 17:30:59 INFO - argparse == 1.2.1 17:30:59 INFO - blessings == 1.6 17:30:59 INFO - blobuploader == 1.2.4 17:30:59 INFO - docopt == 0.6.1 17:30:59 INFO - functools32 == 3.2.3-2 17:30:59 INFO - jsonschema == 2.5.1 17:30:59 INFO - manifestparser == 1.1 17:30:59 INFO - mozInstall == 1.13 17:30:59 INFO - mozcrash == 1.0 17:30:59 INFO - mozdebug == 0.1 17:30:59 INFO - mozdevice == 0.51 17:30:59 INFO - mozfile == 1.2 17:30:59 INFO - mozhttpd == 0.7 17:30:59 INFO - mozinfo == 0.10 17:30:59 INFO - mozleak == 0.1 17:30:59 INFO - mozlog == 3.5 17:30:59 INFO - moznetwork == 0.27 17:30:59 INFO - mozprocess == 0.25 17:30:59 INFO - mozprofile == 0.28 17:30:59 INFO - mozrunner == 6.13 17:30:59 INFO - mozscreenshot == 0.1 17:30:59 INFO - mozsystemmonitor == 0.3 17:30:59 INFO - moztest == 0.8 17:30:59 INFO - mozversion == 1.4 17:30:59 INFO - psutil == 3.1.1 17:30:59 INFO - requests == 1.2.3 17:30:59 INFO - wsgiref == 0.1.2 17:30:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:30:59 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')]} 17:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:30:59 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')]} 17:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:30:59 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 0x18ff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c69d60>, '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 0x1c6aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aef0>, '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-1506979515.532875-1647473711', '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 17:30:59 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 17:30:59 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 17:30:59 INFO - Using env: {'DISPLAY': ':0', 17:30:59 INFO - 'HOME': '/home/cltbld', 17:30:59 INFO - 'LANG': 'en_US.UTF-8', 17:30:59 INFO - 'LANGUAGE': 'en_US:en', 17:30:59 INFO - 'LOGNAME': 'cltbld', 17:30:59 INFO - 'MAIL': '/var/mail/cltbld', 17:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:59 INFO - 'MOZ_NO_REMOTE': '1', 17:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:59 INFO - 'NO_EM_RESTART': '1', 17:30:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:59 INFO - 'PWD': '/builds/slave/test', 17:30:59 INFO - 'SHELL': '/bin/bash', 17:30:59 INFO - 'SHLVL': '1', 17:30:59 INFO - 'TERM': 'linux', 17:30:59 INFO - 'TMOUT': '86400', 17:30:59 INFO - 'USER': 'cltbld', 17:30:59 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:59 INFO - '_': '/tools/buildbot/bin/python'} 17:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:30:59 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)) 17:30:59 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)) 17:30:59 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)) 17:30:59 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)) 17:30:59 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)) 17:30:59 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)) 17:30:59 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)) 17:30:59 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)) 17:30:59 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)) 17:30:59 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:31:00 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)) 17:31:00 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)) 17:31:00 INFO - Installing collected packages: simplejson 17:31:00 INFO - Running setup.py install for simplejson 17:31:00 INFO - building 'simplejson._speedups' extension 17:31:00 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 17:31:01 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 17:31:01 INFO - Successfully installed simplejson 17:31:01 INFO - Cleaning up... 17:31:01 INFO - Return code: 0 17:31:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:31: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')]} 17:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:31: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')]} 17:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:31:01 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 0x18ff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac7b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c6c9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c69d60>, '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 0x1c6aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aef0>, '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-1506979515.532875-1647473711', '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 17:31:01 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 17:31:01 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 17:31:01 INFO - Using env: {'DISPLAY': ':0', 17:31:01 INFO - 'HOME': '/home/cltbld', 17:31:01 INFO - 'LANG': 'en_US.UTF-8', 17:31:01 INFO - 'LANGUAGE': 'en_US:en', 17:31:01 INFO - 'LOGNAME': 'cltbld', 17:31:01 INFO - 'MAIL': '/var/mail/cltbld', 17:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:01 INFO - 'MOZ_NO_REMOTE': '1', 17:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:01 INFO - 'NO_EM_RESTART': '1', 17:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:01 INFO - 'PWD': '/builds/slave/test', 17:31:01 INFO - 'SHELL': '/bin/bash', 17:31:01 INFO - 'SHLVL': '1', 17:31:01 INFO - 'TERM': 'linux', 17:31:01 INFO - 'TMOUT': '86400', 17:31:01 INFO - 'USER': 'cltbld', 17:31:01 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:01 INFO - '_': '/tools/buildbot/bin/python'} 17:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:31:01 INFO - Cleaning up... 17:31:01 INFO - Return code: 0 17:31:01 INFO - Running post-action listener: _resource_record_post_action 17:31:01 INFO - Running post-action listener: _start_resource_monitoring 17:31:01 INFO - Starting resource monitoring. 17:31:01 INFO - [mozharness: 2017-10-03 00:31:01.840244Z] Finished create-virtualenv step (success) 17:31:01 INFO - [mozharness: 2017-10-03 00:31:01.840838Z] Running install step. 17:31:01 INFO - Running pre-action listener: _resource_record_pre_action 17:31:01 INFO - Running main action method: install 17:31:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:31:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:31:02 INFO - Reading from file tmpfile_stdout 17:31:02 INFO - Detecting whether we're running mozinstall >=1.0... 17:31:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:31:02 INFO - Reading from file tmpfile_stdout 17:31:02 INFO - Output received: 17:31:02 INFO - Usage: mozinstall [options] installer 17:31:02 INFO - Options: 17:31:02 INFO - -h, --help show this help message and exit 17:31:02 INFO - -d DEST, --destination=DEST 17:31:02 INFO - Directory to install application into. [default: 17:31:02 INFO - "/builds/slave/test"] 17:31:02 INFO - --app=APP Application being installed. [default: firefox] 17:31:02 INFO - mkdir: /builds/slave/test/build/application 17:31:02 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'] 17:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:31:19 INFO - Reading from file tmpfile_stdout 17:31:19 INFO - Output received: 17:31:19 INFO - /builds/slave/test/build/application/firefox/firefox 17:31:19 INFO - Running post-action listener: _resource_record_post_action 17:31:19 INFO - [mozharness: 2017-10-03 00:31:19.841363Z] Finished install step (success) 17:31:19 INFO - [mozharness: 2017-10-03 00:31:19.841484Z] Running setup-mitmproxy step. 17:31:19 INFO - Running pre-action listener: _resource_record_pre_action 17:31:19 INFO - Running main action method: setup_mitmproxy 17:31:19 INFO - Skipping: mitmproxy is not required 17:31:19 INFO - Running post-action listener: _resource_record_post_action 17:31:19 INFO - [mozharness: 2017-10-03 00:31:19.842575Z] Finished setup-mitmproxy step (success) 17:31:19 INFO - [mozharness: 2017-10-03 00:31:19.842815Z] Running run-tests step. 17:31:19 INFO - Running pre-action listener: _resource_record_pre_action 17:31:19 INFO - Running pre-action listener: _set_gcov_prefix 17:31:19 INFO - Running main action method: run_tests 17:31:19 WARNING - Try message not found. 17:31:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:31:19 INFO - Python 2.7.3 17:31:19 INFO - Return code: 0 17:31:19 INFO - grabbing minidump binary from tooltool 17:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:31:19 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 0x1c69d60>, '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 0x1c6aa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:31:19 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 17:31:19 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 17:31:19 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 17:31:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:31:19 INFO - Return code: 0 17:31:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:31:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:31:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:31:19 INFO - ENV: RUST_BACKTRACE is now full 17:31:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:31:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:31:19 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/O8PaNEsoTpCKDFDDGb4FLA/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 17:31:19 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/O8PaNEsoTpCKDFDDGb4FLA/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 17:31:19 INFO - Using env: {'DISPLAY': ':0', 17:31:19 INFO - 'HOME': '/home/cltbld', 17:31:19 INFO - 'LANG': 'en_US.UTF-8', 17:31:19 INFO - 'LANGUAGE': 'en_US:en', 17:31:19 INFO - 'LOGNAME': 'cltbld', 17:31:19 INFO - 'MAIL': '/var/mail/cltbld', 17:31:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:31:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:31:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:19 INFO - 'MOZ_NO_REMOTE': '1', 17:31:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:31:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:19 INFO - 'NO_EM_RESTART': '1', 17:31:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:19 INFO - 'PWD': '/builds/slave/test', 17:31:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:31:19 INFO - 'RUST_BACKTRACE': 'full', 17:31:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:31:19 INFO - 'SHELL': '/bin/bash', 17:31:19 INFO - 'SHLVL': '1', 17:31:19 INFO - 'TERM': 'linux', 17:31:19 INFO - 'TMOUT': '86400', 17:31:19 INFO - 'USER': 'cltbld', 17:31:19 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:31:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:19 INFO - '_': '/tools/buildbot/bin/python'} 17:31:19 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/O8PaNEsoTpCKDFDDGb4FLA/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 17:31:20 INFO - mozversion application_buildid: 20171002230255 17:31:20 INFO - mozversion application_changeset: 8c6b4fd1d76927cf8b07eb045c5aadd47b77d910 17:31:20 INFO - mozversion application_display_name: Nightly 17:31:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:31:20 INFO - mozversion application_name: Firefox 17:31:20 INFO - mozversion application_remotingname: firefox 17:31:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:31:20 INFO - mozversion application_vendor: Mozilla 17:31:20 INFO - mozversion application_version: 58.0a1 17:31:20 INFO - mozversion platform_buildid: 20171002230255 17:31:20 INFO - mozversion platform_changeset: 8c6b4fd1d76927cf8b07eb045c5aadd47b77d910 17:31:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:31:20 INFO - mozversion platform_version: 58.0a1 17:31:20 INFO - using testdate: 1506990680 17:31:20 INFO - actual date: 1506990680 17:31:20 INFO - starting webserver on 'localhost:33800' 17:31:20 INFO - SUITE-START | Running 2 tests 17:31:20 INFO - TEST-START | dromaeo_css 17:31:20 INFO - Initialising browser for dromaeo_css test... 17:31:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33800/getInfo.html -profile /tmp/tmpdC9J0e/profile 17:31:20 INFO - TEST-INFO | started process 16280 (/builds/slave/test/build/application/firefox/firefox http://localhost:33800/getInfo.html) 17:31:25 INFO - TEST-INFO | 16280: exit 0 17:31:25 INFO - Browser initialized. 17:31:25 INFO - Running cycle 1/1 for dromaeo_css test... 17:31:25 INFO - Using env: {'DISPLAY': ':0', 17:31:25 INFO - 'HOME': '/home/cltbld', 17:31:25 INFO - 'JSGC_DISABLE_POISONING': '1', 17:31:25 INFO - 'LANG': 'en_US.UTF-8', 17:31:25 INFO - 'LANGUAGE': 'en_US:en', 17:31:25 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:31:25 INFO - 'LOGNAME': 'cltbld', 17:31:25 INFO - 'MAIL': '/var/mail/cltbld', 17:31:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:31:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:31:25 INFO - 'MOZ_CRASHREPORTER': '1', 17:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:31:25 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:31:25 INFO - 'MOZ_NO_REMOTE': '1', 17:31:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:31:25 INFO - 'NO_EM_RESTART': '1', 17:31:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:31:25 INFO - 'PWD': '/builds/slave/test', 17:31:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:31:25 INFO - 'RUST_BACKTRACE': 'full', 17:31:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:31:25 INFO - 'SHELL': '/bin/bash', 17:31:25 INFO - 'SHLVL': '1', 17:31:25 INFO - 'TERM': 'linux', 17:31:25 INFO - 'TMOUT': '86400', 17:31:25 INFO - 'USER': 'cltbld', 17:31:25 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:31:25 INFO - '_': '/tools/buildbot/bin/python'} 17:31:25 INFO - TEST-INFO | started process 16518 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdC9J0e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:31:26 INFO - PID 16518 | 17:31:26 INFO - PID 16518 | (/builds/slave/test/build/application/firefox/firefox:16568): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:31:26 INFO - PID 16518 | 17:31:36 INFO - PID 16518 | RSS: Main: 147415040 17:31:36 INFO - PID 16518 | 17:34:33 INFO - PID 16518 | Cycle 1(1): loaded http://localhost:33800/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33800/tests/dromaeo/cssquery-ext.html) 17:34:34 INFO - PID 16518 | RSS: Main: 143810560 17:34:34 INFO - PID 16518 | 17:37:36 INFO - PID 16518 | Cycle 1(1): loaded http://localhost:33800/tests/dromaeo/cssquery-ext.html (next: http://localhost:33800/tests/dromaeo/cssquery-jquery.html) 17:37:36 INFO - PID 16518 | RSS: Main: 145170432 17:37:36 INFO - PID 16518 | 17:40:38 INFO - PID 16518 | Cycle 1(1): loaded http://localhost:33800/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33800/tests/dromaeo/cssquery-mootools.html) 17:40:38 INFO - PID 16518 | RSS: Main: 146096128 17:40:38 INFO - PID 16518 | 17:43:40 INFO - PID 16518 | Cycle 1(1): loaded http://localhost:33800/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33800/tests/dromaeo/cssquery-prototype.html) 17:43:41 INFO - PID 16518 | RSS: Main: 145874944 17:43:41 INFO - PID 16518 | 17:46:44 INFO - PID 16518 | Cycle 1(1): loaded http://localhost:33800/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33800/tests/dromaeo/cssquery-yui.html) 17:46:44 INFO - PID 16518 | RSS: Main: 149434368 17:46:44 INFO - PID 16518 | 17:49:46 INFO - PID 16518 | Cycle 1(1): loaded http://localhost:33800/tests/dromaeo/cssquery-yui.html (next: http://localhost:33800/tests/dromaeo/cssquery-dojo.html) 17:49:46 INFO - PID 16518 | RSS: Main: 150282240 17:49:46 INFO - PID 16518 | 17:49:46 INFO - PID 16518 | __start_tp_report 17:49:46 INFO - PID 16518 | _x_x_mozilla_page_load 17:49:46 INFO - PID 16518 | _x_x_mozilla_page_load_details 17:49:46 INFO - PID 16518 | |i|pagename|runs| 17:49:46 INFO - PID 16518 | |0;dojo.html;3881.1267464820435;3893.299206142894;3912.5304963404387;3932.2725295820273;3938.803059847007;6106.297775755789;6184.319724830277;6246.187995560576;6273.372662733725;6332.5567210295185;6149;6192.225971753531;6192.659403732793;6207.348228436017;6225.066240063993;5583.99999999999;5611.719414029302;5664.291963504562;5689.203511508392;5702.971485142569;5518.724063796814;5562.7774889004395;5578.191162281467;5604.719764011804;5616.494515493605;370.7825246748616;5445.591580631449;5456.91814622781;5500.50495455407;5585.58108141889;5443.237946687467;5486.588505862076;5540.279763630742;5551.2228288039705;5561.972190139064;5465.316835395575;5480.47080288349;5547.528460080898;5551.861203469905;5554.166874968747;5921.792737254218;5924.822255332304;5982.760689572412;6149;6150.723217455181;6497.447716944018;6510.4140627343295;6566.606003639797;6579.47364210862;6604.570702904296;6648.102506161655;6656.93343066568;6686.197656281277;6700.899486507707;6704.83237919051;6205.441510264098;6233.532485063638;6266.874662506724;6274.780382686629;6278.277998030239;6863.450923926173;6945.305469453015;6979.336962988508;6980.336867997532;7051.964740176266;6634.966825165843;6644.069830223676;6709;6723.798286051427;6750.257471678112;6688;6764.425023873026;6776.898346524806;6854.554453960476;6885.036094946711;6670.799876003728;6728.798136055926;6776.220734615582;6784.7964561063245;6800.353966373186;6781.6609169541325;6805.6597170141295;6818.386345228953;6834.555753875982;6842.04211410403;4821.445533763592;4882.707037577757;4887.95112048882;4940.333055037596;5024.547790698782;4449.710768800082;4474.239379305526;4499.752513611717;4504.2793153095345;4522.25382811839;6174.66039367839;6191.504679625619;6266.028765541349;6267.592606480564;6275.403836635513;6676.53264271506;6704.865902681919;6731.596104233761;6766.458683305422;6773.085633439521;6182.319944806068;6186.350433204449;6235.345288744707;6251.937480625226;6258.624482530971;5389.27244821944;5394.163904594794;5470.753816078286;5472.015037293326;5480.506754392044;6923.965380173167;6936.132983377078;7014.789556313319;7022.859542809115;7031.402330801837;6665.733370665216;6754.155730533684;6772.25505194438;6775.254721980479;6783.321667833177;6871.9656401717675;6953.130858642669;6971.887709947091;7035.894461583182;7046.154461464452;6708.932910670831;6719.059331693665;6794.252632210354;6803.455723542006;6814.795556133324;6749.156355455568;6812.795616131524;6880.415164711056;6914.723411063501;6918;6449.097126402214;6534.673266336759;6554.901676474952;6626.801195964129;6629.7679581214425;6583.078369028436;6589.24223714279;6616.603003819786;6653.268140504638;6672.8999065013095;6482.448991835558;6485.805425837232;6551.70517326731;6569.408753212234;6585.176852893388;6527.967360163168;6554.672266386567;6633.635150066792;6742.9662851685425;6752.561083529555;6394.968025159845;6416.230052393743;6420.6147631142285;6426.389492998158;6427.807165785034 17:49:46 INFO - PID 16518 | |1;ext.html;967.6516454076527;969.4716379573132;987.5259875259875;1020.2297265564494;1023.6673081248591;44115;44740.77629611856;44753.32870006945;44808.55191448084;44935.77532112339;41291;41797;41808;41817.37273940894;41955.58044419555;7466.32803047727;7513.173550909396;7549.698012079511;7562.205968373324;7566.356859666935;7551.093868735759;7622.961885190566;7633.198514156017;7643;7652.4260680448915;7633.618319084051;8396.916030839706;8555.871661925075;8558.272546833527;8582.485050896936;8468.576571171448;8531.658733650647;8539.06070332263;8539.914600854036;8546.743597692048;8281.337493000545;8326.958365208196;8408.075111737704;8417.873731894026;8449.662013519452;40330.79834600808;40707.592924070676;40722.38916416256;40788.79605601953;40832.591674083174;3630.909227269313;3676.191237927652;3681.263747250534;3681.5582130144285;3687.3178461984617;32994.3401131976;33005.17487062819;33292.50061249059;33411.6658833411;33563.328733425195;5417.566594672456;5470.370907345627;5471;5489.58828087895;5533.6956467394275;226117;229526;230222.84888575616;231170;231350;55144.172837407474;55329;55421.99999999919;55475.44524554703;55591;55796;56020.71989640026;56508;56524.71737641285;56818;16238.675226495405;16298.674026519404;16431.424900128677;16461.58846028859;16467.67064658724;13103.672408189681;13132.277724725041;13168.670783230496;13245.867541324464;13267.800982985264;15654.608634783994;15857.127857967922;15920.601984950468;15981.520554383387;16043.839561604467;6262.279837818618;6314.305426403092;6326.746930122744;6333.271673757485;6337.017762246859;1097.7091070866227;1108.8669359676728;1119.1270808769173;1122.831575263717;1123.859517560305;26074.86962565175;26223.475530489664;26298.6055209172;26335;26495.337616559355;1201.501376928565;1215.878412158777;1231.384307846077;1234.3643023842549;1239.8884100431005;13678.589642310746;13856.653583660489;13985.160890346611;14015;14106.71786564263;305315;305743;309093;309418;310170;51785.74107129515;53183.73408132935;59217.40782592119;59823.70088149618;60869;1508.328793686799;1511.5087596530952;1522.91623960681;1530.319008041433;1544.0581245440274;31041.534376983913;32095.358092837545;32252.677473225907;32540.674593253763;32753.50869736908;42101;42340.576594233666;42419.7879010603;42440.787796060824;42575;251497;252399;254336;254427;255290;36253;37886;38109.61890381061;38219.23561528698;38278.61721382751;47134.76432617815;48156;48467.27299090587;48468.2729759061;48575.75712121417;35836.28327433489;36391.27217455689;36417;36508.45237321389;36643.083922902144;36076.81961590175;36248.09379765527;36297.81851090727;36441.635583644886;36897.44653830141;40722;42326.788366059205;42355.576444235165;42442.363364550176;42980.785096074316;35092.473612895315;35185.824070879484;35375.64624353724;35454.8227258862;35844.28311433809;26007.739922600533;26222.60666089972;26445.60331594989;26738.59892101659;27212.183634490997 17:49:46 INFO - PID 16518 | |2;jquery.html;622.3962756126556;624.9093881387199;641.2049059166633;648.0506058624114;648.1671052697285;22925.656115158286;23025.078996840148;23654.290371288847;24046.75953240467;24104.156354527575;25986.74013259867;26545.867270663668;26567.601485977724;26608.60087098695;26651.86674066632;6569.967150164242;6634.436072933782;6650.1022361980995;6720.3951644352;6722.798316050526;6653.20161580611;6923.757668481604;6962.582245065287;6963.860722785541;6972.721091156348;6888.690008949584;6900.171979362483;6912.723491060352;6919.065926099987;6919.550229235108;6665.500087493433;6871.312868713138;6910.930890691079;6927.757528486504;6941.132358455193;6412.230532336102;6413.7755178569;6420.101185833987;6436.16329877113;6457.225132984025;29835.25411864699;30050;30217.395652087056;30258.243543911358;30382;2308.3882771065682;2328.6390609455393;2347.213683416058;2359.5162991586685;2361.409647588103;19728.90135549313;20399.694004589943;20430.69353959692;20467.385978420672;20493.4876628084;2594.2347007632793;2609.8042646801564;2667.679878414583;2679.4507126039116;2679.5444774388206;143326.28336858354;150398;169515;174574;174675.12662436735;23291.650625240636;23822.404439889142;24524.887782542864;24776.876115619667;24810.627840582405;48931.26603100956;49205.75397123063;49236;49287.260691089665;49475.25787113196;11845.5854045108;11969.222000569935;12132.878671213351;12173;12173.330466824233;9701.272404569685;9726.610935562498;9788.412695238167;9895.901040989498;9921.355111917703;11955.342456164994;12515.624531264077;12722.809157862639;12778.61664150077;12865.549705760253;3832.405977073558;3856.7107466940092;3874.7690617665926;4392.127901638672;4464.352668862996;1007.8337074382791;1032.1484775060455;1039.3711804358254;1054.5781687325132;1057.3338796558273;22617.4345641357;22743.772562274495;22844.65733014006;22875.885620572124;22969.655455168187;1100.4222783038967;1103.1175059952006;1103.2828661370013;1108.2408550143177;1117.2458590451413;12028;12180.45187966526;12311.876881231252;12394.690132746753;12398.752024959631;576169;581675;592587;599845;604636;131808.34095829653;132659.33670331587;133564;138177;139409.30295348458;1211.3761412872377;1212.860521040074;1242.8508578970582;1255.3911352993973;1259.8614152443224;19515.60968780645;19621.356225911208;19774;19782.90108549448;20010.69983950271;65312;65780.6710966442;66844.66577667081;67252;68397.31602684109;85220;85613;85794;86310;86995;22184.445388865406;22435.32694019182;22654.886725566266;22681.546369072857;22724.886375568018;25008.62487062659;25019.12433064907;25221.621675675247;25347.239582812545;25363.87318063398;20255.18979240814;20583.8970805151;20652.277170298967;20766.68849967282;20787.8960605196;17844.91077544604;17894.463166105037;18248.635027299646;18400.723989140442;18505.444836654922;20302.492437689176;20560.177592896118;20680;20724.48188795232;20883.851388173796;22036.228731994303;22076.2273320433;22108;22137.778622213573;22254.443638909157;19891.10490027981;19967.900160499685;20247.19011239534;20261.29085482001;20423.59152816965 17:49:46 INFO - PID 16518 | |3;mootools.html;1389.0970868935192;1439.8128243328365;1442.5095467541034;1445.1618061524316;1447.8841692664587;14284.714305713893;14938.103713777167;16766.580835479104;17413.084560698346;17422.651546969086;17067.231974561142;17207.483775486726;17381.73927391087;17397.652046959054;17407.82592174078;2782.401783616522;2785.5264605017096;2849.8860045598153;2863.770898328139;2886.5525843494293;2930.516464783308;2950.8081974671686;2961.318896653771;2961.511350627144;2967.747741441969;2909.912702618925;2937.4125174964984;2940.2649337665584;2941.573471846581;2947.1453278549193;2850.686424493304;2867.6845546989816;2891.9855400722863;2895.6814750377443;2897.768178545711;2808.606795048695;2846.245744877609;2863.2698661841305;2880.3663194097267;2893.363460038788;16376.75434868466;16499.17504124789;16501.257443415074;16506;16568.254428550743;8301.045379781342;8601.354898382646;8901.910980890238;8917.6878809242;8918.464892106495;5839.7664093436215;5902.114682797572;5910.852228694274;6020.337762846083;6042.788502402394;2080.0847627044054;2089.477630592352;2116.4391436269248;2135.444784356063;2146.6350720377573;29930.251743706365;30018;30521.542176867366;31533.684663153304;31567.89512367079;7315.597642129627;7409.962950185214;7537.472376933562;7649.885251721229;7755.534667919943;20218.090185941666;20222.696659550118;20224.69662955057;20253.89873050655;20297.797022029885;8715.825683486295;8803.823923521493;8850.291976641853;8865.822683546294;8869.290456763443;7841.764747057598;7990.480618759762;8083.595820208966;8128.512289262545;8174.38692098095;5953.255843019622;5956.315023772235;5973.163757074013;5995.850103747442;6045.576809623284;1727.1709579401956;1730.2127531972922;1744.8691348148939;1759.472158352489;1764.7176451767655;953.4994128082674;966.917811985975;975.6438550414929;995.7411073121041;1004.0812656419295;8743.91256087431;8785.136063566051;8847.424917380478;8966.910330896608;9029;788.7357735158671;800.067920872189;804.3926835239474;808.4744915804656;813.0893399392718;5333.306670132858;5367;5669.943300567024;6107.450329470369;6295.905561416583;23864.16475423352;24247.151349702675;24802.25593232206;25074.24777256685;25120;24458.633120503207;24655.38361540976;24936.875315623307;24966.251012469656;24970.750292496843;12202.572909948109;12357.073219508573;12444.502219911283;12538.373081345897;12650.81023784644;9511.19154871816;9740.756481087747;9789.9021009789;9826.115649591571;9850.211983041196;20689.27587534429;20753.273635422687;21112;21272.893635531724;21525;21560;21742;21803.236886708888;21881.452963675398;21929.451763706034;12688.936555317534;12923.612291631267;12934.935325323313;13102.475900963855;13117.672058198621;15832.92083539575;16061.437849675205;16079.196040197708;16088.839111609203;16117.597060073123;12915.935420322838;13008.869911300766;13030.54393096237;13088.738225235633;13147.868521314664;12317.322547259628;12523.499060037497;12684.556040538537;12713.237205767684;12926.935365323114;11023.228374013743;11100.722481938015;11116.888831111586;11126.554937802397;11129.220954533104;8657.523836188819;8681.696140635047;8684;8694.391392602458;8771.868421973548;8650.870236946577;8718.65125394977;8769.38614296993;8790.604422800861;8804.383693141419 17:49:46 INFO - PID 16518 | |4;prototype.html;550.5650536076499;558.5140927393167;572.9054705973515;585.0668184246126;587.6151121015736;5717.799877004306;6112.449879510843;6116.847078823032;6143.232095988002;6318.052292156179;6072.7874524391655;6153.661548614825;6155.322914479404;6179.969100154504;6196.13254144419;4993.925091123636;5010.874728131789;5050.267711181877;5051.166557518014;5058.671186372892;4892.510748925115;5045.142325804603;5057.5701065409485;5057.822976195834;5106.310648062501;5017.84946451607;5032.622553308498;5041.924371134436;5055.721935293557;5070.29015937769;4978.427480839678;4979.0788704089855;5037.345145131146;5047.924281135804;5050.217216331474;4617.630589552828;4619.1685496610935;4621.30680397943;4624.445066591997;4654.0924519718665;6004.547385253497;6069.605475644069;6102.450779429828;6135.2024236849375;6186.164867742842;5854.795082172103;5873.442023007808;5908.025175846024;5928.28860536734;5932.406759324033;6606.966965165191;6626.834329141761;6650.600963942131;6657.202555310039;6666.600003999775;4884.5603895649565;5115.539601435851;5124.666896651705;5133.101707201225;5134.794608215667;6720.966395168041;6722.798316050526;6814;6879.58722476643;6879.798464491415;5269.1569348903995;5274.103402421598;5284.022455845681;5296.947030529645;5307.495787900143;5320.547753440924;5353.126405757954;5368.32895888014;5408.540274076748;5410.323709536308;5843.065109582446;5846.4153584642045;5880.941430542458;5889.528837692974;5907.616004959664;6543.607383557001;6583.83540411484;6586.595892499299;6634.933650663432;6645.534812563074;1838.7058070708622;1846.5937493751353;1857.2570971611462;1861.9255229790926;1865.8058842340913;1273.7643535945879;1321.1742660836976;1326.5688651188402;1338.7791014482696;1355.1868878672717;5991.970040149771;5995.490383317379;6057.757689692451;6082.878342433127;6109.419605137505;6442.742290308429;6511.34886511345;6555.508336874754;6569.178852643419;6627.271000189975;6065.60573562717;6082.665453400105;6098.35967223444;6103.481204097613;6118.357572454917;3523.788572685647;3532.116970757311;3532.31119931616;3542.769719968194;3545.2023294758474;6903.585784852925;6922.411595014481;6925.099737034153;6955.89566156508;6986.860262794716;6814.965925170442;6836.863262734817;6895.172579290517;6915.654217289115;6942.826429339206;6717.2946840581035;6769.661516924134;6885.483588730865;6914.204866440424;6916.3429474199875;6544.149260596092;6570;6570.310117437792;6618.113837556273;6640.734370625121;6358.427741503287;6503.967480162569;6559.6064236145985;6572.291204287016;6610.900836487361;6712.496562757715;6740;6766.966165169143;6806.829829254309;6812.557183783038;5158.922616160686;5191.8182863599595;5215.55667768244;5230.110881150137;5282.920756188583;6260.968695156495;6497.447716943923;6567.27759946396;6663.200415950117;6673.833154171185;6328.493720502257;6409.583377080475;6411.301005233524;6439.935600643934;6527.281998980014;6422.26143993428;6468.482521398371;6470.6735119301375;6476.31998640136;6477.672077224063;6342.365763423621;6394.680265986589;6402.135711678864;6419;6429.099926010269;6498.577592456475;6524.4454221389815;6550.934490655032;6571.704273307617;6585.5719378240265;6247.031710084944;6284.937150628618;6343.207099112611;6424.132742079946;6431.871362572629 17:49:46 INFO - PID 16518 | |5;yui.html;939.0843927171007;989.5299732626999;992.9056739609739;997.6458357283165;997.9251556133287;20777.896110519447;21050.789492105076;21149.259775907845;21159.576808463822;21231.893840530796;19304.420867373967;19583.314583989562;19591.608167836635;19625.214991400364;19632.509187270327;1056.8203405421077;1079.1420820447763;1080.2114456446798;1082.7888561730465;1084.7884662490817;1048.3447845096814;1079.1259080145107;1088.6897234288228;1094.4254266510106;1099.7140743406733;1158.368689064462;1161.8605767307936;1162.750008748119;1163.4589915689203;1166.9124815638818;1145.2784745610295;1153.8673052598933;1158.8899054589801;1159.7854396936552;1166.7666466706692;846.1622993236713;848.6775025490335;848.766589187972;849.0193826130767;850.6129710981488;19700.50748731279;20145.395638130878;20255.898720506302;20337.18651253948;22594.88702556493;23016.884915575316;23287.18494852672;23482;23526.52946941069;23597.7640223599;18141.818581814277;18660.440186794418;18711.157997890128;18744.343947961894;18831.529211769677;3891.922161556753;3958.34687276599;3965.8413663453425;3971.801409929492;3976.363781794928;166312;167669;168592.15703921524;168720;170604;15855.920720396323;15886.285117169755;16063.518094456951;16069.678606427806;16104.838951610569;33707.49438758421;34491.827540862134;34690.13274668103;34722;34762.304753905286;9478.194353480032;9647;9760.707178784647;9768.853467197856;9835.852462213072;4753.168195565762;4837.129316723023;4853.975730121327;4869.318295438641;4871.756412179377;6133.6013159145405;6228.968855155786;6250.656213908188;6263.655498947511;6286.465650419766;1105.4528008635616;1106.6956586938495;1124.7637996020928;1127.3292391027326;1128.4865386249237;717.5443593318176;726.5894769455241;745.9477836551498;748.1321666866409;754.6302311867146;12168.514677132986;13060.738785224243;13292.73414531704;13436.664083397993;13524.661883452796;725.9455540834458;727.7707522130618;743.1008479739576;752.059925093633;757.238974830292;5624.3813180550105;5677.13067188698;5728.1407788831175;5868.853278667982;5879.941200588025;156374;158310;160678;162970;163594;131463.34268328597;131858;132216;132620.33689831488;134096.3295183518;116108;118763;121561.39219303847;122481.38759306146;123172.38413807873;10877.401742904216;10880.238383313092;10948.56205751761;11084.334939903632;11094.778104438028;54432.18351724648;55276.44723552713;56000;57022.71488642696;57085;72754;73376;74110.62944685241;74288;75307;16222;16739.33042678279;16959.491215263562;17020.234089466252;17258.309667613154;19255.422337329903;19292.32476863303;19340.129694163516;19617.411477655693;19653.31213407524;16373.67252654964;16995.830041699424;17012.82987170162;17058.061806600756;17425.912870435568;17885;17962;18000;18111.09444527742;18126.63746725032;15969.920150399174;16561.50315490537;16627.334906604083;16679.666406671557;16730.330786768394;12541.49834006666;12793.29636869981;12966.740665186455;13018.869811301765;13059.738805223653;12285.508579656713;12395.938020310201;12532.686682833002;12852.678683032998;12892.355382230664 17:49:46 INFO - PID 16518 | __end_tp_report 17:49:46 INFO - PID 16518 | __start_cc_report 17:49:46 INFO - PID 16518 | _x_x_mozilla_cycle_collect,258 17:49:46 INFO - PID 16518 | __end_cc_report 17:49:46 INFO - PID 16518 | __startTimestamp1506991786464__endTimestamp 17:49:46 INFO - PID 16518 | 17:49:46 INFO - PID 16518 | ------- Summary: start ------- 17:49:46 INFO - PID 16518 | Number of tests: 6 17:49:46 INFO - PID 16518 | 17:49:46 INFO - PID 16518 | [#0] dojo.html Cycles:165 Average:6173.90 Median:6485.81 stddev:868.53 (13.4%) stddev-sans-first:852.36 17:49:46 INFO - PID 16518 | Values: 3881.1 3893.3 3912.5 3932.3 3938.8 6106.3 6184.3 6246.2 6273.4 6332.6 6149.0 6192.2 6192.7 6207.3 6225.1 5584.0 5611.7 5664.3 5689.2 5703.0 5518.7 5562.8 5578.2 5604.7 5616.5 370.8 5445.6 5456.9 5500.5 5585.6 5443.2 5486.6 5540.3 5551.2 5562.0 5465.3 5480.5 5547.5 5551.9 5554.2 5921.8 5924.8 5982.8 6149.0 6150.7 6497.4 6510.4 6566.6 6579.5 6604.6 6648.1 6656.9 6686.2 6700.9 6704.8 6205.4 6233.5 6266.9 6274.8 6278.3 6863.5 6945.3 6979.3 6980.3 7052.0 6635.0 6644.1 6709.0 6723.8 6750.3 6688.0 6764.4 6776.9 6854.6 6885.0 6670.8 6728.8 6776.2 6784.8 6800.4 6781.7 6805.7 6818.4 6834.6 6842.0 4821.4 4882.7 4888.0 4940.3 5024.5 4449.7 4474.2 4499.8 4504.3 4522.3 6174.7 6191.5 6266.0 6267.6 6275.4 6676.5 6704.9 6731.6 6766.5 6773.1 6182.3 6186.4 6235.3 6251.9 6258.6 5389.3 5394.2 5470.8 5472.0 5480.5 6924.0 6936.1 7014.8 7022.9 7031.4 6665.7 6754.2 6772.3 6775.3 6783.3 6872.0 6953.1 6971.9 7035.9 7046.2 6708.9 6719.1 6794.3 6803.5 6814.8 6749.2 6812.8 6880.4 6914.7 6918.0 6449.1 6534.7 6554.9 6626.8 6629.8 6583.1 6589.2 6616.6 6653.3 6672.9 6482.4 6485.8 6551.7 6569.4 6585.2 6528.0 6554.7 6633.6 6743.0 6752.6 6395.0 6416.2 6420.6 6426.4 6427.8 17:49:46 INFO - PID 16518 | 17:49:46 INFO - PID 16518 | [#1] ext.html Cycles:180 Average:44552.58 Median:3684.44 stddev:69192.30 (1878.0%) stddev-sans-first:69309.01 17:49:46 INFO - PID 16518 | Values: 967.7 969.5 987.5 1020.2 1023.7 44115.0 44740.8 44753.3 44808.6 44935.8 41291.0 41797.0 41808.0 41817.4 41955.6 7466.3 7513.2 7549.7 7562.2 7566.4 7551.1 7623.0 7633.2 7643.0 7652.4 7633.6 8396.9 8555.9 8558.3 8582.5 8468.6 8531.7 8539.1 8539.9 8546.7 8281.3 8327.0 8408.1 8417.9 8449.7 40330.8 40707.6 40722.4 40788.8 40832.6 3630.9 3676.2 3681.3 3681.6 3687.3 32994.3 33005.2 33292.5 33411.7 33563.3 5417.6 5470.4 5471.0 5489.6 5533.7 226117.0 229526.0 230222.8 231170.0 231350.0 55144.2 55329.0 55422.0 55475.4 55591.0 55796.0 56020.7 56508.0 56524.7 56818.0 16238.7 16298.7 16431.4 16461.6 16467.7 13103.7 13132.3 13168.7 13245.9 13267.8 15654.6 15857.1 15920.6 15981.5 16043.8 6262.3 6314.3 6326.7 6333.3 6337.0 1097.7 1108.9 1119.1 1122.8 1123.9 26074.9 26223.5 26298.6 26335.0 26495.3 1201.5 1215.9 1231.4 1234.4 1239.9 13678.6 13856.7 13985.2 14015.0 14106.7 305315.0 305743.0 309093.0 309418.0 310170.0 51785.7 53183.7 59217.4 59823.7 60869.0 1508.3 1511.5 1522.9 1530.3 1544.1 31041.5 32095.4 32252.7 32540.7 32753.5 42101.0 42340.6 42419.8 42440.8 42575.0 251497.0 252399.0 254336.0 254427.0 255290.0 36253.0 37886.0 38109.6 38219.2 38278.6 47134.8 48156.0 48467.3 48468.3 48575.8 35836.3 36391.3 36417.0 36508.5 36643.1 36076.8 36248.1 36297.8 36441.6 36897.4 40722.0 42326.8 42355.6 42442.4 42980.8 35092.5 35185.8 35375.6 35454.8 35844.3 26007.7 26222.6 26445.6 26738.6 27212.2 17:49:46 INFO - PID 16518 | 17:49:46 INFO - PID 16518 | [#2] jquery.html Cycles:180 Average:41688.15 Median:2353.36 stddev:99444.16 (4225.6%) stddev-sans-first:99675.33 17:49:46 INFO - PID 16518 | Values: 622.4 624.9 641.2 648.1 648.2 22925.7 23025.1 23654.3 24046.8 24104.2 25986.7 26545.9 26567.6 26608.6 26651.9 6570.0 6634.4 6650.1 6720.4 6722.8 6653.2 6923.8 6962.6 6963.9 6972.7 6888.7 6900.2 6912.7 6919.1 6919.6 6665.5 6871.3 6910.9 6927.8 6941.1 6412.2 6413.8 6420.1 6436.2 6457.2 29835.3 30050.0 30217.4 30258.2 30382.0 2308.4 2328.6 2347.2 2359.5 2361.4 19728.9 20399.7 20430.7 20467.4 20493.5 2594.2 2609.8 2667.7 2679.5 2679.5 143326.3 150398.0 169515.0 174574.0 174675.1 23291.7 23822.4 24524.9 24776.9 24810.6 48931.3 49205.8 49236.0 49287.3 49475.3 11845.6 11969.2 12132.9 12173.0 12173.3 9701.3 9726.6 9788.4 9895.9 9921.4 11955.3 12515.6 12722.8 12778.6 12865.5 3832.4 3856.7 3874.8 4392.1 4464.4 1007.8 1032.1 1039.4 1054.6 1057.3 22617.4 22743.8 22844.7 22875.9 22969.7 1100.4 1103.1 1103.3 1108.2 1117.2 12028.0 12180.5 12311.9 12394.7 12398.8 576169.0 581675.0 592587.0 599845.0 604636.0 131808.3 132659.3 133564.0 138177.0 139409.3 1211.4 1212.9 1242.9 1255.4 1259.9 19515.6 19621.4 19774.0 19782.9 20010.7 65312.0 65780.7 66844.7 67252.0 68397.3 85220.0 85613.0 85794.0 86310.0 86995.0 22184.4 22435.3 22654.9 22681.5 22724.9 25008.6 25019.1 25221.6 25347.2 25363.9 20255.2 20583.9 20652.3 20766.7 20787.9 17844.9 17894.5 18248.6 18400.7 18505.4 20302.5 20560.2 20680.0 20724.5 20883.9 22036.2 22076.2 22108.0 22137.8 22254.4 19891.1 19967.9 20247.2 20261.3 20423.6 17:49:46 INFO - PID 16518 | 17:49:46 INFO - PID 16518 | [#3] mootools.html Cycles:180 Average:10549.60 Median:2850.29 stddev:7681.74 (269.5%) stddev-sans-first:7672.45 17:49:46 INFO - PID 16518 | Values: 1389.1 1439.8 1442.5 1445.2 1447.9 14284.7 14938.1 16766.6 17413.1 17422.7 17067.2 17207.5 17381.7 17397.7 17407.8 2782.4 2785.5 2849.9 2863.8 2886.6 2930.5 2950.8 2961.3 2961.5 2967.7 2909.9 2937.4 2940.3 2941.6 2947.1 2850.7 2867.7 2892.0 2895.7 2897.8 2808.6 2846.2 2863.3 2880.4 2893.4 16376.8 16499.2 16501.3 16506.0 16568.3 8301.0 8601.4 8901.9 8917.7 8918.5 5839.8 5902.1 5910.9 6020.3 6042.8 2080.1 2089.5 2116.4 2135.4 2146.6 29930.3 30018.0 30521.5 31533.7 31567.9 7315.6 7410.0 7537.5 7649.9 7755.5 20218.1 20222.7 20224.7 20253.9 20297.8 8715.8 8803.8 8850.3 8865.8 8869.3 7841.8 7990.5 8083.6 8128.5 8174.4 5953.3 5956.3 5973.2 5995.9 6045.6 1727.2 1730.2 1744.9 1759.5 1764.7 953.5 966.9 975.6 995.7 1004.1 8743.9 8785.1 8847.4 8966.9 9029.0 788.7 800.1 804.4 808.5 813.1 5333.3 5367.0 5669.9 6107.5 6295.9 23864.2 24247.2 24802.3 25074.2 25120.0 24458.6 24655.4 24936.9 24966.3 24970.8 12202.6 12357.1 12444.5 12538.4 12650.8 9511.2 9740.8 9789.9 9826.1 9850.2 20689.3 20753.3 21112.0 21272.9 21525.0 21560.0 21742.0 21803.2 21881.5 21929.5 12688.9 12923.6 12934.9 13102.5 13117.7 15832.9 16061.4 16079.2 16088.8 16117.6 12915.9 13008.9 13030.5 13088.7 13147.9 12317.3 12523.5 12684.6 12713.2 12926.9 11023.2 11100.7 11116.9 11126.6 11129.2 8657.5 8681.7 8684.0 8694.4 8771.9 8650.9 8718.7 8769.4 8790.6 8804.4 17:49:46 INFO - PID 16518 | 17:49:46 INFO - PID 16518 | [#4] prototype.html Cycles:180 Average:5585.27 Median:6117.60 stddev:1524.18 (24.9%) stddev-sans-first:1480.87 17:49:46 INFO - PID 16518 | Values: 550.6 558.5 572.9 585.1 587.6 5717.8 6112.4 6116.8 6143.2 6318.1 6072.8 6153.7 6155.3 6180.0 6196.1 4993.9 5010.9 5050.3 5051.2 5058.7 4892.5 5045.1 5057.6 5057.8 5106.3 5017.8 5032.6 5041.9 5055.7 5070.3 4978.4 4979.1 5037.3 5047.9 5050.2 4617.6 4619.2 4621.3 4624.4 4654.1 6004.5 6069.6 6102.5 6135.2 6186.2 5854.8 5873.4 5908.0 5928.3 5932.4 6607.0 6626.8 6650.6 6657.2 6666.6 4884.6 5115.5 5124.7 5133.1 5134.8 6721.0 6722.8 6814.0 6879.6 6879.8 5269.2 5274.1 5284.0 5296.9 5307.5 5320.5 5353.1 5368.3 5408.5 5410.3 5843.1 5846.4 5880.9 5889.5 5907.6 6543.6 6583.8 6586.6 6634.9 6645.5 1838.7 1846.6 1857.3 1861.9 1865.8 1273.8 1321.2 1326.6 1338.8 1355.2 5992.0 5995.5 6057.8 6082.9 6109.4 6442.7 6511.3 6555.5 6569.2 6627.3 6065.6 6082.7 6098.4 6103.5 6118.4 3523.8 3532.1 3532.3 3542.8 3545.2 6903.6 6922.4 6925.1 6955.9 6986.9 6815.0 6836.9 6895.2 6915.7 6942.8 6717.3 6769.7 6885.5 6914.2 6916.3 6544.1 6570.0 6570.3 6618.1 6640.7 6358.4 6504.0 6559.6 6572.3 6610.9 6712.5 6740.0 6767.0 6806.8 6812.6 5158.9 5191.8 5215.6 5230.1 5282.9 6261.0 6497.4 6567.3 6663.2 6673.8 6328.5 6409.6 6411.3 6439.9 6527.3 6422.3 6468.5 6470.7 6476.3 6477.7 6342.4 6394.7 6402.1 6419.0 6429.1 6498.6 6524.4 6550.9 6571.7 6585.6 6247.0 6284.9 6343.2 6424.1 6431.9 17:49:46 INFO - PID 16518 | 17:49:46 INFO - PID 16518 | [#5] yui.html Cycles:180 Average:28943.45 Median:18401.13 stddev:44392.31 (241.2%) stddev-sans-first:44467.04 17:49:46 INFO - PID 16518 | Values: 939.1 989.5 992.9 997.6 997.9 20777.9 21050.8 21149.3 21159.6 21231.9 19304.4 19583.3 19591.6 19625.2 19632.5 1056.8 1079.1 1080.2 1082.8 1084.8 1048.3 1079.1 1088.7 1094.4 1099.7 1158.4 1161.9 1162.8 1163.5 1166.9 1145.3 1153.9 1158.9 1159.8 1166.8 846.2 848.7 848.8 849.0 850.6 19700.5 20145.4 20255.9 20337.2 22594.9 23016.9 23287.2 23482.0 23526.5 23597.8 18141.8 18660.4 18711.2 18744.3 18831.5 3891.9 3958.3 3965.8 3971.8 3976.4 166312.0 167669.0 168592.2 168720.0 170604.0 15855.9 15886.3 16063.5 16069.7 16104.8 33707.5 34491.8 34690.1 34722.0 34762.3 9478.2 9647.0 9760.7 9768.9 9835.9 4753.2 4837.1 4854.0 4869.3 4871.8 6133.6 6229.0 6250.7 6263.7 6286.5 1105.5 1106.7 1124.8 1127.3 1128.5 717.5 726.6 745.9 748.1 754.6 12168.5 13060.7 13292.7 13436.7 13524.7 725.9 727.8 743.1 752.1 757.2 5624.4 5677.1 5728.1 5868.9 5879.9 156374.0 158310.0 160678.0 162970.0 163594.0 131463.3 131858.0 132216.0 132620.3 134096.3 116108.0 118763.0 121561.4 122481.4 123172.4 10877.4 10880.2 10948.6 11084.3 11094.8 54432.2 55276.4 56000.0 57022.7 57085.0 72754.0 73376.0 74110.6 74288.0 75307.0 16222.0 16739.3 16959.5 17020.2 17258.3 19255.4 19292.3 19340.1 19617.4 19653.3 16373.7 16995.8 17012.8 17058.1 17425.9 17885.0 17962.0 18000.0 18111.1 18126.6 15969.9 16561.5 16627.3 16679.7 16730.3 12541.5 12793.3 12966.7 13018.9 13059.7 12285.5 12395.9 12532.7 12852.7 12892.4 17:49:46 INFO - PID 16518 | -------- Summary: end -------- 17:49:46 INFO - PID 16518 | 17:49:46 INFO - TEST-INFO | 16518: exit 0 17:49:46 INFO - TEST-OK | dromaeo_css | took 1106654ms 17:49:46 INFO - TEST-START | kraken 17:49:46 INFO - Initialising browser for kraken test... 17:49:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33800/getInfo.html -profile /tmp/tmpXOoFQ2/profile 17:49:46 INFO - TEST-INFO | started process 16876 (/builds/slave/test/build/application/firefox/firefox http://localhost:33800/getInfo.html) 17:49:51 INFO - TEST-INFO | 16876: exit 0 17:49:51 INFO - Browser initialized. 17:49:51 INFO - Running cycle 1/1 for kraken test... 17:49:51 INFO - Using env: {'DISPLAY': ':0', 17:49:51 INFO - 'HOME': '/home/cltbld', 17:49:51 INFO - 'JSGC_DISABLE_POISONING': '1', 17:49:51 INFO - 'LANG': 'en_US.UTF-8', 17:49:51 INFO - 'LANGUAGE': 'en_US:en', 17:49:51 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:49:51 INFO - 'LOGNAME': 'cltbld', 17:49:51 INFO - 'MAIL': '/var/mail/cltbld', 17:49:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:49:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:49:51 INFO - 'MOZ_CRASHREPORTER': '1', 17:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:49:51 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:49:51 INFO - 'MOZ_NO_REMOTE': '1', 17:49:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:49:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:49:51 INFO - 'NO_EM_RESTART': '1', 17:49:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:49:51 INFO - 'PWD': '/builds/slave/test', 17:49:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:49:51 INFO - 'RUST_BACKTRACE': 'full', 17:49:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:49:51 INFO - 'SHELL': '/bin/bash', 17:49:51 INFO - 'SHLVL': '1', 17:49:51 INFO - 'TERM': 'linux', 17:49:51 INFO - 'TMOUT': '86400', 17:49:51 INFO - 'USER': 'cltbld', 17:49:51 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1506979515.532875-1647473711', 17:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:49:51 INFO - '_': '/tools/buildbot/bin/python'} 17:49:51 INFO - TEST-INFO | started process 17114 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXOoFQ2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:49:52 INFO - PID 17114 | 17:49:52 INFO - PID 17114 | (/builds/slave/test/build/application/firefox/firefox:17164): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:49:52 INFO - PID 17114 | 17:50:02 INFO - PID 17114 | RSS: Main: 147255296 17:50:02 INFO - PID 17114 | 17:50:05 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33800/tests/kraken/driver.html?testName=audio-beat-detection) 17:50:06 INFO - PID 17114 | RSS: Main: 153391104 17:50:06 INFO - PID 17114 | 17:50:08 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33800/tests/kraken/driver.html?testName=audio-dft) 17:50:09 INFO - PID 17114 | RSS: Main: 153460736 17:50:09 INFO - PID 17114 | 17:50:12 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33800/tests/kraken/driver.html?testName=audio-fft) 17:50:12 INFO - PID 17114 | RSS: Main: 149544960 17:50:12 INFO - PID 17114 | 17:50:14 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33800/tests/kraken/driver.html?testName=audio-oscillator) 17:50:15 INFO - PID 17114 | RSS: Main: 150368256 17:50:15 INFO - PID 17114 | 17:50:17 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33800/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:50:18 INFO - PID 17114 | RSS: Main: 150753280 17:50:18 INFO - PID 17114 | 17:50:21 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33800/tests/kraken/driver.html?testName=imaging-darkroom) 17:50:21 INFO - PID 17114 | RSS: Main: 150573056 17:50:21 INFO - PID 17114 | 17:50:25 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33800/tests/kraken/driver.html?testName=imaging-desaturate) 17:50:25 INFO - PID 17114 | RSS: Main: 151891968 17:50:25 INFO - PID 17114 | 17:50:28 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33800/tests/kraken/driver.html?testName=json-parse-financial) 17:50:29 INFO - PID 17114 | RSS: Main: 153473024 17:50:29 INFO - PID 17114 | 17:50:30 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33800/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:50:31 INFO - PID 17114 | RSS: Main: 153468928 17:50:31 INFO - PID 17114 | 17:50:33 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33800/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:50:33 INFO - PID 17114 | RSS: Main: 153374720 17:50:33 INFO - PID 17114 | 17:50:35 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33800/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:50:36 INFO - PID 17114 | RSS: Main: 150478848 17:50:36 INFO - PID 17114 | 17:50:38 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:50:39 INFO - PID 17114 | RSS: Main: 150503424 17:50:39 INFO - PID 17114 | 17:50:42 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:50:42 INFO - PID 17114 | RSS: Main: 151044096 17:50:42 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | Cycle 1(1): loaded http://localhost:33800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33800/tests/kraken/driver.html?testName=ai-astar) 17:50:44 INFO - PID 17114 | RSS: Main: 152514560 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | __start_tp_report 17:50:44 INFO - PID 17114 | _x_x_mozilla_page_load 17:50:44 INFO - PID 17114 | _x_x_mozilla_page_load_details 17:50:44 INFO - PID 17114 | |i|pagename|runs| 17:50:44 INFO - PID 17114 | |0;ai-astar;106;107;111;105;105;108;99;106;105;105 17:50:44 INFO - PID 17114 | |1;audio-beat-detection;114;119;113;111;111;112;121;114;113;112 17:50:44 INFO - PID 17114 | |2;audio-dft;163;163;164;163;162;163;163;162;170;165 17:50:44 INFO - PID 17114 | |3;audio-fft;73;73;75;74;76;74;76;76;76;73 17:50:44 INFO - PID 17114 | |4;audio-oscillator;79;78;86;82;78;79;78;77;78;78 17:50:44 INFO - PID 17114 | |5;imaging-gaussian-blur;113;113;114;110;114;114;111;115;115;114 17:50:44 INFO - PID 17114 | |6;imaging-darkroom;154;149;150;152;150;148;149;150;150;149 17:50:44 INFO - PID 17114 | |7;imaging-desaturate;96;95;94;94;94;96;95;95;94;94 17:50:44 INFO - PID 17114 | |8;json-parse-financial;45;44;46;44;45;45;44;43;42;44 17:50:44 INFO - PID 17114 | |9;json-stringify-tinderbox;38;38;38;38;38;39;38;38;38;38 17:50:44 INFO - PID 17114 | |10;stanford-crypto-aes;70;69;75;74;69;68;70;69;68;75 17:50:44 INFO - PID 17114 | |11;stanford-crypto-ccm;106;105;113;107;109;110;109;102;110;110 17:50:44 INFO - PID 17114 | |12;stanford-crypto-pbkdf2;157;150;154;157;149;156;158;146;150;147 17:50:44 INFO - PID 17114 | |13;stanford-crypto-sha256-iterative;59;58;61;60;57;60;59;60;61;61 17:50:44 INFO - PID 17114 | __end_tp_report 17:50:44 INFO - PID 17114 | __start_cc_report 17:50:44 INFO - PID 17114 | _x_x_mozilla_cycle_collect,696 17:50:44 INFO - PID 17114 | __end_cc_report 17:50:44 INFO - PID 17114 | __startTimestamp1506991844602__endTimestamp 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | ------- Summary: start ------- 17:50:44 INFO - PID 17114 | Number of tests: 14 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#0] ai-astar Cycles:10 Average:105.70 Median:106.50 stddev:3.02 (2.8%) stddev-sans-first:3.20 17:50:44 INFO - PID 17114 | Values: 106.0 107.0 111.0 105.0 105.0 108.0 99.0 106.0 105.0 105.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#1] audio-beat-detection Cycles:10 Average:114.00 Median:113.50 stddev:3.37 (3.0%) stddev-sans-first:3.57 17:50:44 INFO - PID 17114 | Values: 114.0 119.0 113.0 111.0 111.0 112.0 121.0 114.0 113.0 112.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:2.35 (1.4%) stddev-sans-first:2.47 17:50:44 INFO - PID 17114 | Values: 163.0 163.0 164.0 163.0 162.0 163.0 163.0 162.0 170.0 165.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#3] audio-fft Cycles:10 Average:74.60 Median:75.50 stddev:1.35 (1.8%) stddev-sans-first:1.30 17:50:44 INFO - PID 17114 | Values: 73.0 73.0 75.0 74.0 76.0 74.0 76.0 76.0 76.0 73.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:78.50 stddev:2.71 (3.5%) stddev-sans-first:2.87 17:50:44 INFO - PID 17114 | Values: 79.0 78.0 86.0 82.0 78.0 79.0 78.0 77.0 78.0 78.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:114.00 stddev:1.64 (1.4%) stddev-sans-first:1.73 17:50:44 INFO - PID 17114 | Values: 113.0 113.0 114.0 110.0 114.0 114.0 111.0 115.0 115.0 114.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#6] imaging-darkroom Cycles:10 Average:150.10 Median:150.00 stddev:1.73 (1.2%) stddev-sans-first:1.12 17:50:44 INFO - PID 17114 | Values: 154.0 149.0 150.0 152.0 150.0 148.0 149.0 150.0 150.0 149.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.73 17:50:44 INFO - PID 17114 | Values: 96.0 95.0 94.0 94.0 94.0 96.0 95.0 95.0 94.0 94.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#8] json-parse-financial Cycles:10 Average:44.20 Median:44.50 stddev:1.14 (2.6%) stddev-sans-first:1.17 17:50:44 INFO - PID 17114 | Values: 45.0 44.0 46.0 44.0 45.0 45.0 44.0 43.0 42.0 44.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.32 (0.8%) stddev-sans-first:0.33 17:50:44 INFO - PID 17114 | Values: 38.0 38.0 38.0 38.0 38.0 39.0 38.0 38.0 38.0 38.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#10] stanford-crypto-aes Cycles:10 Average:70.70 Median:70.00 stddev:2.83 (4.0%) stddev-sans-first:2.99 17:50:44 INFO - PID 17114 | Values: 70.0 69.0 75.0 74.0 69.0 68.0 70.0 69.0 68.0 75.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#11] stanford-crypto-ccm Cycles:10 Average:108.10 Median:109.50 stddev:3.14 (2.9%) stddev-sans-first:3.24 17:50:44 INFO - PID 17114 | Values: 106.0 105.0 113.0 107.0 109.0 110.0 109.0 102.0 110.0 110.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.40 Median:155.00 stddev:4.50 (2.9%) stddev-sans-first:4.46 17:50:44 INFO - PID 17114 | Values: 157.0 150.0 154.0 157.0 149.0 156.0 158.0 146.0 150.0 147.0 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.60 Median:60.00 stddev:1.35 (2.2%) stddev-sans-first:1.41 17:50:44 INFO - PID 17114 | Values: 59.0 58.0 61.0 60.0 57.0 60.0 59.0 60.0 61.0 61.0 17:50:44 INFO - PID 17114 | -------- Summary: end -------- 17:50:44 INFO - PID 17114 | 17:50:44 INFO - PID 17114 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:50:45 INFO - TEST-INFO | 17114: exit 0 17:50:45 INFO - TEST-OK | kraken | took 58303ms 17:50:45 INFO - SUITE-END | took 1164s 17:50:45 INFO - Completed test suite (00:19:24) 17:50:45 INFO - javascript benchmark 17:50:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9226.045978903896, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3881.1267464820435, 3893.299206142894, 3912.5304963404387, 3932.2725295820273, 3938.803059847007, 6106.297775755789, 6184.319724830277, 6246.187995560576, 6273.372662733725, 6332.5567210295185, 6149.0, 6192.225971753531, 6192.659403732793, 6207.348228436017, 6225.066240063993, 5583.99999999999, 5611.719414029302, 5664.291963504562, 5689.203511508392, 5702.971485142569, 5518.724063796814, 5562.7774889004395, 5578.191162281467, 5604.719764011804, 5616.494515493605, 370.7825246748616, 5445.591580631449, 5456.91814622781, 5500.50495455407, 5585.58108141889, 5443.237946687467, 5486.588505862076, 5540.279763630742, 5551.2228288039705, 5561.972190139064, 5465.316835395575, 5480.47080288349, 5547.528460080898, 5551.861203469905, 5554.166874968747, 5921.792737254218, 5924.822255332304, 5982.760689572412, 6149.0, 6150.723217455181, 6497.447716944018, 6510.4140627343295, 6566.606003639797, 6579.47364210862, 6604.570702904296, 6648.102506161655, 6656.93343066568, 6686.197656281277, 6700.899486507707, 6704.83237919051, 6205.441510264098, 6233.532485063638, 6266.874662506724, 6274.780382686629, 6278.277998030239, 6863.450923926173, 6945.305469453015, 6979.336962988508, 6980.336867997532, 7051.964740176266, 6634.966825165843, 6644.069830223676, 6709.0, 6723.798286051427, 6750.257471678112, 6688.0, 6764.425023873026, 6776.898346524806, 6854.554453960476, 6885.036094946711, 6670.799876003728, 6728.798136055926, 6776.220734615582, 6784.7964561063245, 6800.353966373186, 6781.6609169541325, 6805.6597170141295, 6818.386345228953, 6834.555753875982, 6842.04211410403, 4821.445533763592, 4882.707037577757, 4887.95112048882, 4940.333055037596, 5024.547790698782, 4449.710768800082, 4474.239379305526, 4499.752513611717, 4504.2793153095345, 4522.25382811839, 6174.66039367839, 6191.504679625619, 6266.028765541349, 6267.592606480564, 6275.403836635513, 6676.53264271506, 6704.865902681919, 6731.596104233761, 6766.458683305422, 6773.085633439521, 6182.319944806068, 6186.350433204449, 6235.345288744707, 6251.937480625226, 6258.624482530971, 5389.27244821944, 5394.163904594794, 5470.753816078286, 5472.015037293326, 5480.506754392044, 6923.965380173167, 6936.132983377078, 7014.789556313319, 7022.859542809115, 7031.402330801837, 6665.733370665216, 6754.155730533684, 6772.25505194438, 6775.254721980479, 6783.321667833177, 6871.9656401717675, 6953.130858642669, 6971.887709947091, 7035.894461583182, 7046.154461464452, 6708.932910670831, 6719.059331693665, 6794.252632210354, 6803.455723542006, 6814.795556133324, 6749.156355455568, 6812.795616131524, 6880.415164711056, 6914.723411063501, 6918.0, 6449.097126402214, 6534.673266336759, 6554.901676474952, 6626.801195964129, 6629.7679581214425, 6583.078369028436, 6589.24223714279, 6616.603003819786, 6653.268140504638, 6672.8999065013095, 6482.448991835558, 6485.805425837232, 6551.70517326731, 6569.408753212234, 6585.176852893388, 6527.967360163168, 6554.672266386567, 6633.635150066792, 6742.9662851685425, 6752.561083529555, 6394.968025159845, 6416.230052393743, 6420.6147631142285, 6426.389492998158, 6427.807165785034], "value": 6116.382025866706, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [967.6516454076527, 969.4716379573132, 987.5259875259875, 1020.2297265564494, 1023.6673081248591, 44115.0, 44740.77629611856, 44753.32870006945, 44808.55191448084, 44935.77532112339, 41291.0, 41797.0, 41808.0, 41817.37273940894, 41955.58044419555, 7466.32803047727, 7513.173550909396, 7549.698012079511, 7562.205968373324, 7566.356859666935, 7551.093868735759, 7622.961885190566, 7633.198514156017, 7643.0, 7652.4260680448915, 7633.618319084051, 8396.916030839706, 8555.871661925075, 8558.272546833527, 8582.485050896936, 8468.576571171448, 8531.658733650647, 8539.06070332263, 8539.914600854036, 8546.743597692048, 8281.337493000545, 8326.958365208196, 8408.075111737704, 8417.873731894026, 8449.662013519452, 40330.79834600808, 40707.592924070676, 40722.38916416256, 40788.79605601953, 40832.591674083174, 3630.909227269313, 3676.191237927652, 3681.263747250534, 3681.5582130144285, 3687.3178461984617, 32994.3401131976, 33005.17487062819, 33292.50061249059, 33411.6658833411, 33563.328733425195, 5417.566594672456, 5470.370907345627, 5471.0, 5489.58828087895, 5533.6956467394275, 226117.0, 229526.0, 230222.84888575616, 231170.0, 231350.0, 55144.172837407474, 55329.0, 55421.99999999919, 55475.44524554703, 55591.0, 55796.0, 56020.71989640026, 56508.0, 56524.71737641285, 56818.0, 16238.675226495405, 16298.674026519404, 16431.424900128677, 16461.58846028859, 16467.67064658724, 13103.672408189681, 13132.277724725041, 13168.670783230496, 13245.867541324464, 13267.800982985264, 15654.608634783994, 15857.127857967922, 15920.601984950468, 15981.520554383387, 16043.839561604467, 6262.279837818618, 6314.305426403092, 6326.746930122744, 6333.271673757485, 6337.017762246859, 1097.7091070866227, 1108.8669359676728, 1119.1270808769173, 1122.831575263717, 1123.859517560305, 26074.86962565175, 26223.475530489664, 26298.6055209172, 26335.0, 26495.337616559355, 1201.501376928565, 1215.878412158777, 1231.384307846077, 1234.3643023842549, 1239.8884100431005, 13678.589642310746, 13856.653583660489, 13985.160890346611, 14015.0, 14106.71786564263, 305315.0, 305743.0, 309093.0, 309418.0, 310170.0, 51785.74107129515, 53183.73408132935, 59217.40782592119, 59823.70088149618, 60869.0, 1508.328793686799, 1511.5087596530952, 1522.91623960681, 1530.319008041433, 1544.0581245440274, 31041.534376983913, 32095.358092837545, 32252.677473225907, 32540.674593253763, 32753.50869736908, 42101.0, 42340.576594233666, 42419.7879010603, 42440.787796060824, 42575.0, 251497.0, 252399.0, 254336.0, 254427.0, 255290.0, 36253.0, 37886.0, 38109.61890381061, 38219.23561528698, 38278.61721382751, 47134.76432617815, 48156.0, 48467.27299090587, 48468.2729759061, 48575.75712121417, 35836.28327433489, 36391.27217455689, 36417.0, 36508.45237321389, 36643.083922902144, 36076.81961590175, 36248.09379765527, 36297.81851090727, 36441.635583644886, 36897.44653830141, 40722.0, 42326.788366059205, 42355.576444235165, 42442.363364550176, 42980.785096074316, 35092.473612895315, 35185.824070879484, 35375.64624353724, 35454.8227258862, 35844.28311433809, 26007.739922600533, 26222.60666089972, 26445.60331594989, 26738.59892101659, 27212.183634490997], "value": 19044.52580204776, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.3962756126556, 624.9093881387199, 641.2049059166633, 648.0506058624114, 648.1671052697285, 22925.656115158286, 23025.078996840148, 23654.290371288847, 24046.75953240467, 24104.156354527575, 25986.74013259867, 26545.867270663668, 26567.601485977724, 26608.60087098695, 26651.86674066632, 6569.967150164242, 6634.436072933782, 6650.1022361980995, 6720.3951644352, 6722.798316050526, 6653.20161580611, 6923.757668481604, 6962.582245065287, 6963.860722785541, 6972.721091156348, 6888.690008949584, 6900.171979362483, 6912.723491060352, 6919.065926099987, 6919.550229235108, 6665.500087493433, 6871.312868713138, 6910.930890691079, 6927.757528486504, 6941.132358455193, 6412.230532336102, 6413.7755178569, 6420.101185833987, 6436.16329877113, 6457.225132984025, 29835.25411864699, 30050.0, 30217.395652087056, 30258.243543911358, 30382.0, 2308.3882771065682, 2328.6390609455393, 2347.213683416058, 2359.5162991586685, 2361.409647588103, 19728.90135549313, 20399.694004589943, 20430.69353959692, 20467.385978420672, 20493.4876628084, 2594.2347007632793, 2609.8042646801564, 2667.679878414583, 2679.4507126039116, 2679.5444774388206, 143326.28336858354, 150398.0, 169515.0, 174574.0, 174675.12662436735, 23291.650625240636, 23822.404439889142, 24524.887782542864, 24776.876115619667, 24810.627840582405, 48931.26603100956, 49205.75397123063, 49236.0, 49287.260691089665, 49475.25787113196, 11845.5854045108, 11969.222000569935, 12132.878671213351, 12173.0, 12173.330466824233, 9701.272404569685, 9726.610935562498, 9788.412695238167, 9895.901040989498, 9921.355111917703, 11955.342456164994, 12515.624531264077, 12722.809157862639, 12778.61664150077, 12865.549705760253, 3832.405977073558, 3856.7107466940092, 3874.7690617665926, 4392.127901638672, 4464.352668862996, 1007.8337074382791, 1032.1484775060455, 1039.3711804358254, 1054.5781687325132, 1057.3338796558273, 22617.4345641357, 22743.772562274495, 22844.65733014006, 22875.885620572124, 22969.655455168187, 1100.4222783038967, 1103.1175059952006, 1103.2828661370013, 1108.2408550143177, 1117.2458590451413, 12028.0, 12180.45187966526, 12311.876881231252, 12394.690132746753, 12398.752024959631, 576169.0, 581675.0, 592587.0, 599845.0, 604636.0, 131808.34095829653, 132659.33670331587, 133564.0, 138177.0, 139409.30295348458, 1211.3761412872377, 1212.860521040074, 1242.8508578970582, 1255.3911352993973, 1259.8614152443224, 19515.60968780645, 19621.356225911208, 19774.0, 19782.90108549448, 20010.69983950271, 65312.0, 65780.6710966442, 66844.66577667081, 67252.0, 68397.31602684109, 85220.0, 85613.0, 85794.0, 86310.0, 86995.0, 22184.445388865406, 22435.32694019182, 22654.886725566266, 22681.546369072857, 22724.886375568018, 25008.62487062659, 25019.12433064907, 25221.621675675247, 25347.239582812545, 25363.87318063398, 20255.18979240814, 20583.8970805151, 20652.277170298967, 20766.68849967282, 20787.8960605196, 17844.91077544604, 17894.463166105037, 18248.635027299646, 18400.723989140442, 18505.444836654922, 20302.492437689176, 20560.177592896118, 20680.0, 20724.48188795232, 20883.851388173796, 22036.228731994303, 22076.2273320433, 22108.0, 22137.778622213573, 22254.443638909157, 19891.10490027981, 19967.900160499685, 20247.19011239534, 20261.29085482001, 20423.59152816965], "value": 13984.755838387235, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.0970868935192, 1439.8128243328365, 1442.5095467541034, 1445.1618061524316, 1447.8841692664587, 14284.714305713893, 14938.103713777167, 16766.580835479104, 17413.084560698346, 17422.651546969086, 17067.231974561142, 17207.483775486726, 17381.73927391087, 17397.652046959054, 17407.82592174078, 2782.401783616522, 2785.5264605017096, 2849.8860045598153, 2863.770898328139, 2886.5525843494293, 2930.516464783308, 2950.8081974671686, 2961.318896653771, 2961.511350627144, 2967.747741441969, 2909.912702618925, 2937.4125174964984, 2940.2649337665584, 2941.573471846581, 2947.1453278549193, 2850.686424493304, 2867.6845546989816, 2891.9855400722863, 2895.6814750377443, 2897.768178545711, 2808.606795048695, 2846.245744877609, 2863.2698661841305, 2880.3663194097267, 2893.363460038788, 16376.75434868466, 16499.17504124789, 16501.257443415074, 16506.0, 16568.254428550743, 8301.045379781342, 8601.354898382646, 8901.910980890238, 8917.6878809242, 8918.464892106495, 5839.7664093436215, 5902.114682797572, 5910.852228694274, 6020.337762846083, 6042.788502402394, 2080.0847627044054, 2089.477630592352, 2116.4391436269248, 2135.444784356063, 2146.6350720377573, 29930.251743706365, 30018.0, 30521.542176867366, 31533.684663153304, 31567.89512367079, 7315.597642129627, 7409.962950185214, 7537.472376933562, 7649.885251721229, 7755.534667919943, 20218.090185941666, 20222.696659550118, 20224.69662955057, 20253.89873050655, 20297.797022029885, 8715.825683486295, 8803.823923521493, 8850.291976641853, 8865.822683546294, 8869.290456763443, 7841.764747057598, 7990.480618759762, 8083.595820208966, 8128.512289262545, 8174.38692098095, 5953.255843019622, 5956.315023772235, 5973.163757074013, 5995.850103747442, 6045.576809623284, 1727.1709579401956, 1730.2127531972922, 1744.8691348148939, 1759.472158352489, 1764.7176451767655, 953.4994128082674, 966.917811985975, 975.6438550414929, 995.7411073121041, 1004.0812656419295, 8743.91256087431, 8785.136063566051, 8847.424917380478, 8966.910330896608, 9029.0, 788.7357735158671, 800.067920872189, 804.3926835239474, 808.4744915804656, 813.0893399392718, 5333.306670132858, 5367.0, 5669.943300567024, 6107.450329470369, 6295.905561416583, 23864.16475423352, 24247.151349702675, 24802.25593232206, 25074.24777256685, 25120.0, 24458.633120503207, 24655.38361540976, 24936.875315623307, 24966.251012469656, 24970.750292496843, 12202.572909948109, 12357.073219508573, 12444.502219911283, 12538.373081345897, 12650.81023784644, 9511.19154871816, 9740.756481087747, 9789.9021009789, 9826.115649591571, 9850.211983041196, 20689.27587534429, 20753.273635422687, 21112.0, 21272.893635531724, 21525.0, 21560.0, 21742.0, 21803.236886708888, 21881.452963675398, 21929.451763706034, 12688.936555317534, 12923.612291631267, 12934.935325323313, 13102.475900963855, 13117.672058198621, 15832.92083539575, 16061.437849675205, 16079.196040197708, 16088.839111609203, 16117.597060073123, 12915.935420322838, 13008.869911300766, 13030.54393096237, 13088.738225235633, 13147.868521314664, 12317.322547259628, 12523.499060037497, 12684.556040538537, 12713.237205767684, 12926.935365323114, 11023.228374013743, 11100.722481938015, 11116.888831111586, 11126.554937802397, 11129.220954533104, 8657.523836188819, 8681.696140635047, 8684.0, 8694.391392602458, 8771.868421973548, 8650.870236946577, 8718.65125394977, 8769.38614296993, 8790.604422800861, 8804.383693141419], "value": 7405.214998967855, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.5650536076499, 558.5140927393167, 572.9054705973515, 585.0668184246126, 587.6151121015736, 5717.799877004306, 6112.449879510843, 6116.847078823032, 6143.232095988002, 6318.052292156179, 6072.7874524391655, 6153.661548614825, 6155.322914479404, 6179.969100154504, 6196.13254144419, 4993.925091123636, 5010.874728131789, 5050.267711181877, 5051.166557518014, 5058.671186372892, 4892.510748925115, 5045.142325804603, 5057.5701065409485, 5057.822976195834, 5106.310648062501, 5017.84946451607, 5032.622553308498, 5041.924371134436, 5055.721935293557, 5070.29015937769, 4978.427480839678, 4979.0788704089855, 5037.345145131146, 5047.924281135804, 5050.217216331474, 4617.630589552828, 4619.1685496610935, 4621.30680397943, 4624.445066591997, 4654.0924519718665, 6004.547385253497, 6069.605475644069, 6102.450779429828, 6135.2024236849375, 6186.164867742842, 5854.795082172103, 5873.442023007808, 5908.025175846024, 5928.28860536734, 5932.406759324033, 6606.966965165191, 6626.834329141761, 6650.600963942131, 6657.202555310039, 6666.600003999775, 4884.5603895649565, 5115.539601435851, 5124.666896651705, 5133.101707201225, 5134.794608215667, 6720.966395168041, 6722.798316050526, 6814.0, 6879.58722476643, 6879.798464491415, 5269.1569348903995, 5274.103402421598, 5284.022455845681, 5296.947030529645, 5307.495787900143, 5320.547753440924, 5353.126405757954, 5368.32895888014, 5408.540274076748, 5410.323709536308, 5843.065109582446, 5846.4153584642045, 5880.941430542458, 5889.528837692974, 5907.616004959664, 6543.607383557001, 6583.83540411484, 6586.595892499299, 6634.933650663432, 6645.534812563074, 1838.7058070708622, 1846.5937493751353, 1857.2570971611462, 1861.9255229790926, 1865.8058842340913, 1273.7643535945879, 1321.1742660836976, 1326.5688651188402, 1338.7791014482696, 1355.1868878672717, 5991.970040149771, 5995.490383317379, 6057.757689692451, 6082.878342433127, 6109.419605137505, 6442.742290308429, 6511.34886511345, 6555.508336874754, 6569.178852643419, 6627.271000189975, 6065.60573562717, 6082.665453400105, 6098.35967223444, 6103.481204097613, 6118.357572454917, 3523.788572685647, 3532.116970757311, 3532.31119931616, 3542.769719968194, 3545.2023294758474, 6903.585784852925, 6922.411595014481, 6925.099737034153, 6955.89566156508, 6986.860262794716, 6814.965925170442, 6836.863262734817, 6895.172579290517, 6915.654217289115, 6942.826429339206, 6717.2946840581035, 6769.661516924134, 6885.483588730865, 6914.204866440424, 6916.3429474199875, 6544.149260596092, 6570.0, 6570.310117437792, 6618.113837556273, 6640.734370625121, 6358.427741503287, 6503.967480162569, 6559.6064236145985, 6572.291204287016, 6610.900836487361, 6712.496562757715, 6740.0, 6766.966165169143, 6806.829829254309, 6812.557183783038, 5158.922616160686, 5191.8182863599595, 5215.55667768244, 5230.110881150137, 5282.920756188583, 6260.968695156495, 6497.447716943923, 6567.27759946396, 6663.200415950117, 6673.833154171185, 6328.493720502257, 6409.583377080475, 6411.301005233524, 6439.935600643934, 6527.281998980014, 6422.26143993428, 6468.482521398371, 6470.6735119301375, 6476.31998640136, 6477.672077224063, 6342.365763423621, 6394.680265986589, 6402.135711678864, 6419.0, 6429.099926010269, 6498.577592456475, 6524.4454221389815, 6550.934490655032, 6571.704273307617, 6585.5719378240265, 6247.031710084944, 6284.937150628618, 6343.207099112611, 6424.132742079946, 6431.871362572629], "value": 5152.957965902598, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [939.0843927171007, 989.5299732626999, 992.9056739609739, 997.6458357283165, 997.9251556133287, 20777.896110519447, 21050.789492105076, 21149.259775907845, 21159.576808463822, 21231.893840530796, 19304.420867373967, 19583.314583989562, 19591.608167836635, 19625.214991400364, 19632.509187270327, 1056.8203405421077, 1079.1420820447763, 1080.2114456446798, 1082.7888561730465, 1084.7884662490817, 1048.3447845096814, 1079.1259080145107, 1088.6897234288228, 1094.4254266510106, 1099.7140743406733, 1158.368689064462, 1161.8605767307936, 1162.750008748119, 1163.4589915689203, 1166.9124815638818, 1145.2784745610295, 1153.8673052598933, 1158.8899054589801, 1159.7854396936552, 1166.7666466706692, 846.1622993236713, 848.6775025490335, 848.766589187972, 849.0193826130767, 850.6129710981488, 19700.50748731279, 20145.395638130878, 20255.898720506302, 20337.18651253948, 22594.88702556493, 23016.884915575316, 23287.18494852672, 23482.0, 23526.52946941069, 23597.7640223599, 18141.818581814277, 18660.440186794418, 18711.157997890128, 18744.343947961894, 18831.529211769677, 3891.922161556753, 3958.34687276599, 3965.8413663453425, 3971.801409929492, 3976.363781794928, 166312.0, 167669.0, 168592.15703921524, 168720.0, 170604.0, 15855.920720396323, 15886.285117169755, 16063.518094456951, 16069.678606427806, 16104.838951610569, 33707.49438758421, 34491.827540862134, 34690.13274668103, 34722.0, 34762.304753905286, 9478.194353480032, 9647.0, 9760.707178784647, 9768.853467197856, 9835.852462213072, 4753.168195565762, 4837.129316723023, 4853.975730121327, 4869.318295438641, 4871.756412179377, 6133.6013159145405, 6228.968855155786, 6250.656213908188, 6263.655498947511, 6286.465650419766, 1105.4528008635616, 1106.6956586938495, 1124.7637996020928, 1127.3292391027326, 1128.4865386249237, 717.5443593318176, 726.5894769455241, 745.9477836551498, 748.1321666866409, 754.6302311867146, 12168.514677132986, 13060.738785224243, 13292.73414531704, 13436.664083397993, 13524.661883452796, 725.9455540834458, 727.7707522130618, 743.1008479739576, 752.059925093633, 757.238974830292, 5624.3813180550105, 5677.13067188698, 5728.1407788831175, 5868.853278667982, 5879.941200588025, 156374.0, 158310.0, 160678.0, 162970.0, 163594.0, 131463.34268328597, 131858.0, 132216.0, 132620.33689831488, 134096.3295183518, 116108.0, 118763.0, 121561.39219303847, 122481.38759306146, 123172.38413807873, 10877.401742904216, 10880.238383313092, 10948.56205751761, 11084.334939903632, 11094.778104438028, 54432.18351724648, 55276.44723552713, 56000.0, 57022.71488642696, 57085.0, 72754.0, 73376.0, 74110.62944685241, 74288.0, 75307.0, 16222.0, 16739.33042678279, 16959.491215263562, 17020.234089466252, 17258.309667613154, 19255.422337329903, 19292.32476863303, 19340.129694163516, 19617.411477655693, 19653.31213407524, 16373.67252654964, 16995.830041699424, 17012.82987170162, 17058.061806600756, 17425.912870435568, 17885.0, 17962.0, 18000.0, 18111.09444527742, 18126.63746725032, 15969.920150399174, 16561.50315490537, 16627.334906604083, 16679.666406671557, 16730.330786768394, 12541.49834006666, 12793.29636869981, 12966.740665186455, 13018.869811301765, 13059.738805223653, 12285.508579656713, 12395.938020310201, 12532.686682833002, 12852.678683032998, 12892.355382230664], "value": 9920.890026576022, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1368.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 111.0, 105.0, 105.0, 108.0, 99.0, 106.0, 105.0, 105.0], "value": 105.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 113.0, 111.0, 111.0, 112.0, 121.0, 114.0, 113.0, 112.0], "value": 114.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 163.0, 162.0, 163.0, 163.0, 162.0, 170.0, 165.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 75.0, 74.0, 76.0, 74.0, 76.0, 76.0, 76.0, 73.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 86.0, 82.0, 78.0, 79.0, 78.0, 77.0, 78.0, 78.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 110.0, 114.0, 114.0, 111.0, 115.0, 115.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 149.0, 150.0, 152.0, 150.0, 148.0, 149.0, 150.0, 150.0, 149.0], "value": 150.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 94.0, 94.0, 94.0, 96.0, 95.0, 95.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 44.0, 46.0, 44.0, 45.0, 45.0, 44.0, 43.0, 42.0, 44.0], "value": 44.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0, 38.0, 38.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 75.0, 74.0, 69.0, 68.0, 70.0, 69.0, 68.0, 75.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 113.0, 107.0, 109.0, 110.0, 109.0, 102.0, 110.0, 110.0], "value": 108.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 150.0, 154.0, 157.0, 149.0, 156.0, 158.0, 146.0, 150.0, 147.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 61.0, 60.0, 57.0, 60.0, 59.0, 60.0, 61.0, 61.0], "value": 59.6, "unit": "score"}]}]} 17:50:45 INFO - Return code: 0 17:50:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:50:45 INFO - # TBPL SUCCESS # 17:50:45 INFO - Running post-action listener: _package_coverage_data 17:50:45 INFO - Running post-action listener: _resource_record_post_action 17:50:45 INFO - [mozharness: 2017-10-03 00:50:45.390186Z] Finished run-tests step (success) 17:50:45 INFO - Running post-run listener: _resource_record_post_run 17:50:45 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 208896; Write bytes: 422248448; Read time: 428; Write time: 250148 17:50:45 INFO - TinderboxPrint: CPU usage
13.1% 17:50:45 INFO - TinderboxPrint: I/O read bytes / time
208,896 / 428 17:50:45 INFO - TinderboxPrint: I/O write bytes / time
422,248,448 / 250,148 17:50:45 INFO - TinderboxPrint: CPU idle
8,199.3 (86.9%) 17:50:45 INFO - TinderboxPrint: CPU user
1,184.4 (12.6%) 17:50:45 INFO - TinderboxPrint: Swap in / out
0 / 0 17:50:45 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 49152; Write bytes: 106475520; Read time: 284; Write time: 70748 17:50:45 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:50:45 INFO - run-tests - Wall time: 1166s; CPU: 13.0%; Read bytes: 159744; Write bytes: 315473920; Read time: 144; Write time: 179376 17:50:46 INFO - Running post-run listener: _upload_blobber_files 17:50:46 INFO - Blob upload gear active. 17:50:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:50:46 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 17:50:46 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'] 17:50:46 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 17:50:46 INFO - (blobuploader) - INFO - Open directory for files ... 17:50:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:50:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:50:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:47 INFO - (blobuploader) - INFO - Done attempting. 17:50:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:50:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:50:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:54 INFO - (blobuploader) - INFO - Done attempting. 17:50:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:50:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:50:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:54 INFO - (blobuploader) - INFO - Done attempting. 17:50:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:50:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:50:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:55 INFO - (blobuploader) - INFO - Done attempting. 17:50:55 INFO - (blobuploader) - INFO - Iteration through files over. 17:50:55 INFO - Return code: 0 17:50:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:50:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:50:55 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/266986bd4361d4460a4fbaae054a833645aaf59bf1a128eb7d46e9f0d3fe713cdaca07d5764d8c64d1a32581433d814651e3528d213412b89fcbecb501efc966", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4635bcbf5d4bba5fb7eb23bf864cfe741fc68c65977a700efa1a75076ae1863e3cace2621e820bad66169703687c680b25aaba12177a5aa887602f01d9e136b1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d3b287d029e3241f76b313ab366ccd3b88761d2e1cc9a67f78deb06c042634fc3d263055ae1ce2b826a7626ce786aec2d633a0ad5dd653624ce934b8dd01818", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f993825a74ac365b704a9e0b9536d6ff3108f4a1248b2a41d7b536d08348f09731a92251e7c00cc3247081d438a3bdebc1663fe2f5eb82fd5e05d9f55a8527"} 17:50:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:50:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:50:55 INFO - Contents: 17:50:55 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/266986bd4361d4460a4fbaae054a833645aaf59bf1a128eb7d46e9f0d3fe713cdaca07d5764d8c64d1a32581433d814651e3528d213412b89fcbecb501efc966", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4635bcbf5d4bba5fb7eb23bf864cfe741fc68c65977a700efa1a75076ae1863e3cace2621e820bad66169703687c680b25aaba12177a5aa887602f01d9e136b1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d3b287d029e3241f76b313ab366ccd3b88761d2e1cc9a67f78deb06c042634fc3d263055ae1ce2b826a7626ce786aec2d633a0ad5dd653624ce934b8dd01818", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f993825a74ac365b704a9e0b9536d6ff3108f4a1248b2a41d7b536d08348f09731a92251e7c00cc3247081d438a3bdebc1663fe2f5eb82fd5e05d9f55a8527"} 17:50:55 INFO - Running post-run listener: copy_logs_to_upload_dir 17:50:55 INFO - Copying logs to upload dir... 17:50:55 INFO - mkdir: /builds/slave/test/build/upload/logs 17:50:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.398262 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-10-02 17:50:55.913864) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:55.916890) ========= 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-1506979515.532875-1647473711 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/266986bd4361d4460a4fbaae054a833645aaf59bf1a128eb7d46e9f0d3fe713cdaca07d5764d8c64d1a32581433d814651e3528d213412b89fcbecb501efc966", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4635bcbf5d4bba5fb7eb23bf864cfe741fc68c65977a700efa1a75076ae1863e3cace2621e820bad66169703687c680b25aaba12177a5aa887602f01d9e136b1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d3b287d029e3241f76b313ab366ccd3b88761d2e1cc9a67f78deb06c042634fc3d263055ae1ce2b826a7626ce786aec2d633a0ad5dd653624ce934b8dd01818", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f993825a74ac365b704a9e0b9536d6ff3108f4a1248b2a41d7b536d08348f09731a92251e7c00cc3247081d438a3bdebc1663fe2f5eb82fd5e05d9f55a8527"} build_url:https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011664 build_url: 'https://queue.taskcluster.net/v1/task/O8PaNEsoTpCKDFDDGb4FLA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/266986bd4361d4460a4fbaae054a833645aaf59bf1a128eb7d46e9f0d3fe713cdaca07d5764d8c64d1a32581433d814651e3528d213412b89fcbecb501efc966", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4635bcbf5d4bba5fb7eb23bf864cfe741fc68c65977a700efa1a75076ae1863e3cace2621e820bad66169703687c680b25aaba12177a5aa887602f01d9e136b1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d3b287d029e3241f76b313ab366ccd3b88761d2e1cc9a67f78deb06c042634fc3d263055ae1ce2b826a7626ce786aec2d633a0ad5dd653624ce934b8dd01818", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0f993825a74ac365b704a9e0b9536d6ff3108f4a1248b2a41d7b536d08348f09731a92251e7c00cc3247081d438a3bdebc1663fe2f5eb82fd5e05d9f55a8527"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:55.946447) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:55.946814) ========= 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-1506979515.532875-1647473711 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004603 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:50:56.032483) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 17:50:56.032809) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 17:50:56.033093) ========= ========= Total master_lag: 0.19 =========