builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-088 starttime: 1505620816.84 results: success (0) revision: c079f3f1d24a9797205ba210e7f6a5d21d8c609b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:16.835910) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:16.836345) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:16.836617) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:16.858212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:16.858496) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-16 21:00:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.5K=0.3s 2017-09-16 21:00:17 (45.5 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.398026 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:17.282708) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:17.282997) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016395 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:17.362907) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-16 21:00:17.363232) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c079f3f1d24a9797205ba210e7f6a5d21d8c609b --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 c079f3f1d24a9797205ba210e7f6a5d21d8c609b --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=9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-16 21:00:17,418 truncating revision to first 12 chars 2017-09-16 21:00:17,418 Setting DEBUG logging. 2017-09-16 21:00:17,418 attempt 1/10 2017-09-16 21:00:17,418 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c079f3f1d24a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-16 21:00:17,891 attempt 1/10 2017-09-16 21:00:18,550 current task status: no status available at this point. state: STARTED 2017-09-16 21:00:18,551 sleeping for 10.00s (attempt 1/10) 2017-09-16 21:00:28,561 attempt 2/10 2017-09-16 21:00:28,927 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-16 21:00:29,118 unpacking tar archive at: mozilla-central-c079f3f1d24a/testing/mozharness/ program finished with exit code 0 elapsedTime=11.982428 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-16 21:00:29.406269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:29.406589) ========= script_repo_revision: c079f3f1d24a9797205ba210e7f6a5d21d8c609b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:29.406969) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:29.407228) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:29.443834) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-16 21:00:29.444216) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:00:29 INFO - MultiFileLogger online at 20170916 21:00:29 in /builds/slave/test 21:00:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 21:00:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:00:29 INFO - {'append_to_log': False, 21:00:29 INFO - 'base_work_dir': '/builds/slave/test', 21:00:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:00:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:00:29 INFO - 'branch': 'Firefox-Non-PGO', 21:00:29 INFO - 'buildbot_json_path': 'buildprops.json', 21:00:29 INFO - 'code_coverage': False, 21:00:29 INFO - 'config_files': ('talos/linux_config.py',), 21:00:29 INFO - 'default_actions': ('clobber', 21:00:29 INFO - 'read-buildbot-config', 21:00:29 INFO - 'download-and-extract', 21:00:29 INFO - 'populate-webroot', 21:00:29 INFO - 'create-virtualenv', 21:00:29 INFO - 'install', 21:00:29 INFO - 'setup-mitmproxy', 21:00:29 INFO - 'run-tests'), 21:00:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:00:29 INFO - 'disable_ccov_upload': False, 21:00:29 INFO - 'disable_stylo': False, 21:00:29 INFO - 'download_minidump_stackwalk': True, 21:00:29 INFO - 'download_symbols': 'ondemand', 21:00:29 INFO - 'enable_stylo': False, 21:00:29 INFO - 'enable_webrender': False, 21:00:29 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 21:00:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:00:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:00:29 INFO - 'gecko_profile': False, 21:00:29 INFO - 'gecko_profile_interval': 0, 21:00:29 INFO - 'installer_path': 'installer.exe', 21:00:29 INFO - 'log_level': 'info', 21:00:29 INFO - 'log_name': 'talos', 21:00:29 INFO - 'log_to_console': True, 21:00:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:00:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:00:29 INFO - 'opt_config_files': (), 21:00:29 INFO - 'pip_index': False, 21:00:29 INFO - 'suite': 'dromaeojs-e10s', 21:00:29 INFO - 'system_bits': '32', 21:00:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:00:29 INFO - 'title': 'talos-linux64-ix-088', 21:00:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:00:29 INFO - 'use_talos_json': True, 21:00:29 INFO - 'verify': 'False', 21:00:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:00:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:00:29 INFO - 'work_dir': 'build'} 21:00:29 INFO - [mozharness: 2017-09-17 04:00:29.599094Z] Running clobber step. 21:00:29 INFO - Running pre-action listener: _resource_record_pre_action 21:00:29 INFO - Running main action method: clobber 21:00:29 INFO - rmtree: /builds/slave/test/build 21:00:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:00:31 INFO - Running post-action listener: _resource_record_post_action 21:00:31 INFO - [mozharness: 2017-09-17 04:00:31.100668Z] Finished clobber step (success) 21:00:31 INFO - [mozharness: 2017-09-17 04:00:31.100766Z] Running read-buildbot-config step. 21:00:31 INFO - Running pre-action listener: _resource_record_pre_action 21:00:31 INFO - Running main action method: read_buildbot_config 21:00:31 INFO - Using buildbot properties: 21:00:31 INFO - { 21:00:31 INFO - "project": "", 21:00:31 INFO - "product": "firefox", 21:00:31 INFO - "who": "ryanvm@gmail.com", 21:00:31 INFO - "installer_path": "public/build/target.tar.bz2", 21:00:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 21:00:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 21:00:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 21:00:31 INFO - "stage_platform": "linux64", 21:00:31 INFO - "basedir": "/builds/slave/test", 21:00:31 INFO - "buildnumber": 836, 21:00:31 INFO - "platform": "ubuntu64_hw", 21:00:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:00:31 INFO - "slavebuilddir": "test", 21:00:31 INFO - "taskId": "KM6EAnCKS4OKItOb0VeBKg", 21:00:31 INFO - "branch": "mozilla-central", 21:00:31 INFO - "script_repo_revision": "production", 21:00:31 INFO - "revision": "c079f3f1d24a9797205ba210e7f6a5d21d8c609b", 21:00:31 INFO - "slavename": "talos-linux64-ix-088", 21:00:31 INFO - "repo_path": "mozilla-central" 21:00:31 INFO - } 21:00:31 INFO - Finding installer, test and symbols from parent task. 21:00:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KM6EAnCKS4OKItOb0VeBKg'}, attempt #1 21:00:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KM6EAnCKS4OKItOb0VeBKg'}, attempt #1 21:00:32 INFO - Task dependencies: CfqVyKmfSpuuqANp3SS4QQ 21:00:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ'}, attempt #1 21:00:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 21:00:32 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.test_packages.json 21:00:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.crashreporter-symbols.zip 21:00:32 INFO - Running post-action listener: _resource_record_post_action 21:00:32 INFO - [mozharness: 2017-09-17 04:00:32.451913Z] Finished read-buildbot-config step (success) 21:00:32 INFO - [mozharness: 2017-09-17 04:00:32.452128Z] Running download-and-extract step. 21:00:32 INFO - Running pre-action listener: _resource_record_pre_action 21:00:32 INFO - Running main action method: download_and_extract 21:00:32 INFO - mkdir: /builds/slave/test/build/tests 21:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:32 INFO - trying https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.test_packages.json 21:00:32 INFO - Downloading https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:00:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:00:33 INFO - Downloaded 989 bytes. 21:00:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:00:33 INFO - Using the following test package requirements: 21:00:33 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 21:00:33 INFO - u'common': [u'target.common.tests.zip'], 21:00:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:00:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:00:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:00:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:00:33 INFO - u'mozbase': [u'target.common.tests.zip'], 21:00:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:00:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:00:33 INFO - u'web-platform': [u'target.common.tests.zip', 21:00:33 INFO - u'target.web-platform.tests.tar.gz'], 21:00:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:00:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 21:00:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.common.tests.zip 21:00:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:00:33 INFO - Fetch https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.common.tests.zip into memory 21:00:34 INFO - Content-Length response header: 54731232 21:00:34 INFO - Bytes received: 54731232 21:00:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.talos.tests.zip 21:00:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:00:45 INFO - Fetch https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.talos.tests.zip into memory 21:00:45 INFO - Content-Length response header: 14508748 21:00:45 INFO - Bytes received: 14508748 21:00:46 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')]} 21:00:46 INFO - trying https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 21:00:46 INFO - Downloading https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:00:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:00:47 INFO - Downloaded 61581965 bytes. 21:00:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 21:00:47 INFO - mkdir: /builds/slave/test/properties 21:00:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:00:47 INFO - Writing to file /builds/slave/test/properties/build_url 21:00:47 INFO - Contents: 21:00:47 INFO - build_url:https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 21:00:47 INFO - Running post-action listener: _resource_record_post_action 21:00:47 INFO - Running post-action listener: find_tests_for_verification 21:00:47 INFO - Running post-action listener: set_extra_try_arguments 21:00:47 INFO - [mozharness: 2017-09-17 04:00:47.233224Z] Finished download-and-extract step (success) 21:00:47 INFO - [mozharness: 2017-09-17 04:00:47.233323Z] Running populate-webroot step. 21:00:47 INFO - Running pre-action listener: _resource_record_pre_action 21:00:47 INFO - Running main action method: populate_webroot 21:00:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:00:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['tresize', 21:00:47 INFO - 'tcanvasmark']}, 21:00:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:00:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['dromaeo_css', 21:00:47 INFO - 'kraken']}, 21:00:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:00:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:00:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:00:47 INFO - 'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:00:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:00:47 INFO - 'tests': ['damp', 'tps']}, 21:00:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:00:47 INFO - 'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['damp', 'tps']}, 21:00:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:00:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['dromaeo_dom']}, 21:00:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:00:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['basic_compositor_video', 21:00:47 INFO - 'glvideo']}, 21:00:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 21:00:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 21:00:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:00:47 INFO - 'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['ts_paint_webext', 21:00:47 INFO - 'tp5o_webext']}, 21:00:47 INFO - 'other-e10s': {'tests': ['a11yr', 21:00:47 INFO - 'ts_paint', 21:00:47 INFO - 'tpaint', 21:00:47 INFO - 'sessionrestore', 21:00:47 INFO - 'sessionrestore_many_windows', 21:00:47 INFO - 'sessionrestore_no_auto_restore', 21:00:47 INFO - 'tabpaint']}, 21:00:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['a11yr', 21:00:47 INFO - 'ts_paint', 21:00:47 INFO - 'tpaint', 21:00:47 INFO - 'sessionrestore', 21:00:47 INFO - 'sessionrestore_many_windows', 21:00:47 INFO - 'sessionrestore_no_auto_restore', 21:00:47 INFO - 'tabpaint']}, 21:00:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 21:00:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 21:00:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['perf_reftest_singletons']}, 21:00:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['bloom_basic']}, 21:00:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:00:47 INFO - 'tsvgr_opacity', 21:00:47 INFO - 'tart', 21:00:47 INFO - 'tscrollx', 21:00:47 INFO - 'tsvg_static']}, 21:00:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['tsvgx', 21:00:47 INFO - 'tsvgr_opacity', 21:00:47 INFO - 'tart', 21:00:47 INFO - 'tscrollx', 21:00:47 INFO - 'tsvg_static']}, 21:00:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:00:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:00:47 INFO - 'talos_options': ['--disable-stylo'], 21:00:47 INFO - 'tests': ['tp5o']}, 21:00:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:00:47 INFO - 'talos_options': ['--mitmproxy', 21:00:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:00:47 INFO - '--firstNonBlankPaint'], 21:00:47 INFO - 'tests': ['tp6_google', 21:00:47 INFO - 'tp6_youtube', 21:00:47 INFO - 'tp6_amazon', 21:00:47 INFO - 'tp6_facebook']}, 21:00:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:00:47 INFO - 'talos_options': ['--disable-stylo', 21:00:47 INFO - '--mitmproxy', 21:00:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:00:47 INFO - '--firstNonBlankPaint'], 21:00:47 INFO - 'tests': ['tp6_google', 21:00:47 INFO - 'tp6_youtube', 21:00:47 INFO - 'tp6_amazon', 21:00:47 INFO - 'tp6_facebook']}, 21:00:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:00:47 INFO - 'talos_options': ['--stylo-threads=1', 21:00:47 INFO - '--mitmproxy', 21:00:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:00:47 INFO - '--firstNonBlankPaint'], 21:00:47 INFO - 'tests': ['tp6_google', 21:00:47 INFO - 'tp6_youtube', 21:00:47 INFO - 'tp6_amazon', 21:00:47 INFO - 'tp6_facebook']}, 21:00:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:00:47 INFO - 'talos_options': ['--xperf_path', 21:00:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:00:47 INFO - 'tests': ['tp5n']}, 21:00:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:00:47 INFO - 'talos_options': ['--disable-stylo', 21:00:47 INFO - '--xperf_path', 21:00:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:00:47 INFO - 'tests': ['tp5n']}}} 21:00:47 INFO - Running post-action listener: _resource_record_post_action 21:00:47 INFO - [mozharness: 2017-09-17 04:00:47.248477Z] Finished populate-webroot step (success) 21:00:47 INFO - [mozharness: 2017-09-17 04:00:47.248577Z] Running create-virtualenv step. 21:00:47 INFO - Running pre-action listener: _resource_record_pre_action 21:00:47 INFO - Running main action method: create_virtualenv 21:00:47 INFO - Creating virtualenv /builds/slave/test/build/venv 21:00:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:00:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 21:00:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 21:00:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:00:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:00:48 INFO - Installing distribute.............................................................................................................................................................................................done. 21:00:48 INFO - Installing pip...............done. 21:00:48 INFO - Return code: 0 21:00:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:00:48 INFO - Reading from file tmpfile_stdout 21:00:48 INFO - Output received: 21:00:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 21:00:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:00: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')]} 21:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00: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')]} 21:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1989a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d090>, '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 0x198dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1987f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', '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 21:00: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', 'pip>=1.5'] in /builds/slave/test/build 21:00: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 pip>=1.5 21:00:48 INFO - Using env: {'DISPLAY': ':0', 21:00:48 INFO - 'HOME': '/home/cltbld', 21:00:48 INFO - 'LANG': 'en_US.UTF-8', 21:00:48 INFO - 'LANGUAGE': 'en_US:en', 21:00:48 INFO - 'LOGNAME': 'cltbld', 21:00:48 INFO - 'MAIL': '/var/mail/cltbld', 21:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:48 INFO - 'MOZ_NO_REMOTE': '1', 21:00:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:48 INFO - 'NO_EM_RESTART': '1', 21:00:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:48 INFO - 'PWD': '/builds/slave/test', 21:00:48 INFO - 'SHELL': '/bin/bash', 21:00:48 INFO - 'SHLVL': '1', 21:00:48 INFO - 'TERM': 'linux', 21:00:48 INFO - 'TMOUT': '86400', 21:00:48 INFO - 'USER': 'cltbld', 21:00:48 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:48 INFO - '_': '/tools/buildbot/bin/python'} 21:00:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 21:00:49 INFO - Downloading/unpacking pip>=1.5 21:00:49 INFO - Running setup.py egg_info for package pip 21:00:49 INFO - warning: no files found matching 'pip/cacert.pem' 21:00:49 INFO - warning: no files found matching '*.html' under directory 'docs' 21:00:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:00:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:00:49 INFO - Installing collected packages: pip 21:00:49 INFO - Found existing installation: pip 1.1 21:00:49 INFO - Uninstalling pip: 21:00:49 INFO - Successfully uninstalled pip 21:00:49 INFO - Running setup.py install for pip 21:00:49 INFO - warning: no files found matching 'pip/cacert.pem' 21:00:49 INFO - warning: no files found matching '*.html' under directory 'docs' 21:00:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:00:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:00:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 21:00:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 21:00:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 21:00:49 INFO - Successfully installed pip 21:00:49 INFO - Cleaning up... 21:00:49 INFO - Return code: 0 21:00:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:00:49 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')]} 21:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:49 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')]} 21:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:49 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 0x161d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1989a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d090>, '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 0x198dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1987f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', '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 21:00:49 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 21:00:49 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 21:00:49 INFO - Using env: {'DISPLAY': ':0', 21:00:49 INFO - 'HOME': '/home/cltbld', 21:00:49 INFO - 'LANG': 'en_US.UTF-8', 21:00:49 INFO - 'LANGUAGE': 'en_US:en', 21:00:49 INFO - 'LOGNAME': 'cltbld', 21:00:49 INFO - 'MAIL': '/var/mail/cltbld', 21:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:49 INFO - 'MOZ_NO_REMOTE': '1', 21:00:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:49 INFO - 'NO_EM_RESTART': '1', 21:00:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:49 INFO - 'PWD': '/builds/slave/test', 21:00:49 INFO - 'SHELL': '/bin/bash', 21:00:49 INFO - 'SHLVL': '1', 21:00:49 INFO - 'TERM': 'linux', 21:00:49 INFO - 'TMOUT': '86400', 21:00:49 INFO - 'USER': 'cltbld', 21:00:49 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:49 INFO - '_': '/tools/buildbot/bin/python'} 21:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:49 INFO - Downloading/unpacking psutil>=3.1.1 21:00: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 21:00: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 21:00: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 21:00: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 21:00:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:00:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:00:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:00:51 INFO - Installing collected packages: psutil 21:00:51 INFO - Running setup.py install for psutil 21:00:51 INFO - building 'psutil._psutil_linux' extension 21:00:51 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 21:00:51 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 21:00:51 INFO - building 'psutil._psutil_posix' extension 21:00:51 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 21:00:51 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 21:00:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:00:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:00:51 INFO - Successfully installed psutil 21:00:51 INFO - Cleaning up... 21:00:51 INFO - Return code: 0 21:00:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:00:51 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')]} 21:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:51 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')]} 21:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:51 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 0x161d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1989a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d090>, '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 0x198dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1987f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', '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 21:00:51 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 21:00:51 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 21:00:51 INFO - Using env: {'DISPLAY': ':0', 21:00:51 INFO - 'HOME': '/home/cltbld', 21:00:51 INFO - 'LANG': 'en_US.UTF-8', 21:00:51 INFO - 'LANGUAGE': 'en_US:en', 21:00:51 INFO - 'LOGNAME': 'cltbld', 21:00:51 INFO - 'MAIL': '/var/mail/cltbld', 21:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:51 INFO - 'MOZ_NO_REMOTE': '1', 21:00:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:51 INFO - 'NO_EM_RESTART': '1', 21:00:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:51 INFO - 'PWD': '/builds/slave/test', 21:00:51 INFO - 'SHELL': '/bin/bash', 21:00:51 INFO - 'SHLVL': '1', 21:00:51 INFO - 'TERM': 'linux', 21:00:51 INFO - 'TMOUT': '86400', 21:00:51 INFO - 'USER': 'cltbld', 21:00:51 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:51 INFO - '_': '/tools/buildbot/bin/python'} 21:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:00: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 21:00: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 21:00: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 21:00: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 21:00:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:00:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:00:53 INFO - Installing collected packages: mozsystemmonitor 21:00:53 INFO - Running setup.py install for mozsystemmonitor 21:00:53 INFO - Successfully installed mozsystemmonitor 21:00:53 INFO - Cleaning up... 21:00:54 INFO - Return code: 0 21:00:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:54 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 0x161d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1989a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d090>, '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 0x198dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1987f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', '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 21:00:54 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 21:00:54 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 21:00:54 INFO - Using env: {'DISPLAY': ':0', 21:00:54 INFO - 'HOME': '/home/cltbld', 21:00:54 INFO - 'LANG': 'en_US.UTF-8', 21:00:54 INFO - 'LANGUAGE': 'en_US:en', 21:00:54 INFO - 'LOGNAME': 'cltbld', 21:00:54 INFO - 'MAIL': '/var/mail/cltbld', 21:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:54 INFO - 'MOZ_NO_REMOTE': '1', 21:00:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:54 INFO - 'NO_EM_RESTART': '1', 21:00:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:54 INFO - 'PWD': '/builds/slave/test', 21:00:54 INFO - 'SHELL': '/bin/bash', 21:00:54 INFO - 'SHLVL': '1', 21:00:54 INFO - 'TERM': 'linux', 21:00:54 INFO - 'TMOUT': '86400', 21:00:54 INFO - 'USER': 'cltbld', 21:00:54 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:54 INFO - '_': '/tools/buildbot/bin/python'} 21:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:54 INFO - Downloading/unpacking jsonschema==2.5.1 21:00:54 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 21:00:54 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 21:00:54 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 21:00:54 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 21:00:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:00:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:00:55 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 21:00:55 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 21:00:55 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 21:00:55 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 21:00:55 INFO - Downloading functools32-3.2.3-2.tar.gz 21:00:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:00:55 INFO - warning: no files found matching '*.txt' 21:00:55 INFO - no previously-included directories found matching 'build' 21:00:55 INFO - no previously-included directories found matching 'dist' 21:00:55 INFO - no previously-included directories found matching '.git*' 21:00:55 INFO - Installing collected packages: jsonschema, functools32 21:00:55 INFO - Running setup.py install for functools32 21:00:56 INFO - warning: no files found matching '*.txt' 21:00:56 INFO - no previously-included directories found matching 'build' 21:00:56 INFO - no previously-included directories found matching 'dist' 21:00:56 INFO - no previously-included directories found matching '.git*' 21:00:56 INFO - Successfully installed jsonschema functools32 21:00:56 INFO - Cleaning up... 21:00:56 INFO - Return code: 0 21:00:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:00: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')]} 21:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00: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')]} 21:00:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:56 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 0x161d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1989a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d090>, '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 0x198dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1987f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', '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 21:00:56 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 21:00:56 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 21:00:56 INFO - Using env: {'DISPLAY': ':0', 21:00:56 INFO - 'HOME': '/home/cltbld', 21:00:56 INFO - 'LANG': 'en_US.UTF-8', 21:00:56 INFO - 'LANGUAGE': 'en_US:en', 21:00:56 INFO - 'LOGNAME': 'cltbld', 21:00:56 INFO - 'MAIL': '/var/mail/cltbld', 21:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:56 INFO - 'MOZ_NO_REMOTE': '1', 21:00:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:56 INFO - 'NO_EM_RESTART': '1', 21:00:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:56 INFO - 'PWD': '/builds/slave/test', 21:00:56 INFO - 'SHELL': '/bin/bash', 21:00:56 INFO - 'SHLVL': '1', 21:00:56 INFO - 'TERM': 'linux', 21:00:56 INFO - 'TMOUT': '86400', 21:00:56 INFO - 'USER': 'cltbld', 21:00:56 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:56 INFO - '_': '/tools/buildbot/bin/python'} 21:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:00:56 INFO - Cleaning up... 21:00:56 INFO - Return code: 0 21:00:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:00: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')]} 21:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00: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')]} 21:00:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:56 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 0x161d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1989a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d090>, '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 0x198dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1987f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', '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 21:00:56 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 21:00:56 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 21:00:56 INFO - Using env: {'DISPLAY': ':0', 21:00:56 INFO - 'HOME': '/home/cltbld', 21:00:56 INFO - 'LANG': 'en_US.UTF-8', 21:00:56 INFO - 'LANGUAGE': 'en_US:en', 21:00:56 INFO - 'LOGNAME': 'cltbld', 21:00:56 INFO - 'MAIL': '/var/mail/cltbld', 21:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:56 INFO - 'MOZ_NO_REMOTE': '1', 21:00:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:56 INFO - 'NO_EM_RESTART': '1', 21:00:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:56 INFO - 'PWD': '/builds/slave/test', 21:00:56 INFO - 'SHELL': '/bin/bash', 21:00:56 INFO - 'SHLVL': '1', 21:00:56 INFO - 'TERM': 'linux', 21:00:56 INFO - 'TMOUT': '86400', 21:00:56 INFO - 'USER': 'cltbld', 21:00:56 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:56 INFO - '_': '/tools/buildbot/bin/python'} 21:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:56 INFO - Downloading/unpacking blobuploader==1.2.4 21:00:56 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 21:00:56 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 21:00:56 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 21:00:56 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 21:00:57 INFO - Downloading blobuploader-1.2.4.tar.gz 21:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:00:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:00: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 21:00: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 21:00: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 21:00: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 21:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:00:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:00:58 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 21:00:58 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 21:00:58 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 21:00:58 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 21:00:58 INFO - Downloading docopt-0.6.1.tar.gz 21:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:00:58 INFO - Installing collected packages: blobuploader, requests, docopt 21:00:58 INFO - Running setup.py install for blobuploader 21:00:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:00:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:00:58 INFO - Running setup.py install for requests 21:00:58 INFO - Running setup.py install for docopt 21:00:58 INFO - Successfully installed blobuploader requests docopt 21:00:58 INFO - Cleaning up... 21:00:58 INFO - Return code: 0 21:00:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:00:58 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')]} 21:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:58 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')]} 21:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:58 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 0x161d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1989a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d090>, '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 0x198dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1987f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', '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 21:00:58 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 21:00:58 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 21:00:58 INFO - Using env: {'DISPLAY': ':0', 21:00:58 INFO - 'HOME': '/home/cltbld', 21:00:58 INFO - 'LANG': 'en_US.UTF-8', 21:00:58 INFO - 'LANGUAGE': 'en_US:en', 21:00:58 INFO - 'LOGNAME': 'cltbld', 21:00:58 INFO - 'MAIL': '/var/mail/cltbld', 21:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:58 INFO - 'MOZ_NO_REMOTE': '1', 21:00:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:58 INFO - 'NO_EM_RESTART': '1', 21:00:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:58 INFO - 'PWD': '/builds/slave/test', 21:00:58 INFO - 'SHELL': '/bin/bash', 21:00:58 INFO - 'SHLVL': '1', 21:00:58 INFO - 'TERM': 'linux', 21:00:58 INFO - 'TMOUT': '86400', 21:00:58 INFO - 'USER': 'cltbld', 21:00:58 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:58 INFO - '_': '/tools/buildbot/bin/python'} 21:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:00:59 INFO - Running setup.py (path:/tmp/pip-i27xNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:00:59 INFO - Running setup.py (path:/tmp/pip-xJGfOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:00:59 INFO - Running setup.py (path:/tmp/pip-tDmeJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:00:59 INFO - Running setup.py (path:/tmp/pip-fdfD6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:00:59 INFO - Running setup.py (path:/tmp/pip-J_upuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:00:59 INFO - Running setup.py (path:/tmp/pip-702zZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:00:59 INFO - Running setup.py (path:/tmp/pip-qNDpPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:00:59 INFO - Running setup.py (path:/tmp/pip-WMgflU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:00:59 INFO - Running setup.py (path:/tmp/pip-rVCo5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:00:59 INFO - Running setup.py (path:/tmp/pip-zNM86z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:00:59 INFO - Running setup.py (path:/tmp/pip-C3U_1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:00:59 INFO - Running setup.py (path:/tmp/pip-oLsdHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:01:00 INFO - Running setup.py (path:/tmp/pip-T7uggB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:01:00 INFO - Running setup.py (path:/tmp/pip-HvCMvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:00 INFO - Running setup.py (path:/tmp/pip-uVhwHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:01:00 INFO - Running setup.py (path:/tmp/pip-2YyQz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:01:00 INFO - Running setup.py (path:/tmp/pip-cLr3dj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:01:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:01:00 INFO - Running setup.py install for manifestparser 21:01:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:01:00 INFO - Running setup.py install for mozcrash 21:01:00 INFO - Running setup.py install for mozdebug 21:01:00 INFO - Running setup.py install for mozdevice 21:01:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:01:00 INFO - Running setup.py install for mozfile 21:01:00 INFO - Running setup.py install for mozhttpd 21:01:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:01:00 INFO - Running setup.py install for mozinfo 21:01:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Running setup.py install for mozInstall 21:01:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Running setup.py install for mozleak 21:01:01 INFO - Running setup.py install for mozlog 21:01:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Running setup.py install for moznetwork 21:01:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Running setup.py install for mozprocess 21:01:01 INFO - Running setup.py install for mozprofile 21:01:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Running setup.py install for mozrunner 21:01:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Running setup.py install for mozscreenshot 21:01:01 INFO - Running setup.py install for moztest 21:01:02 INFO - Running setup.py install for mozversion 21:01:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:01:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:01:02 INFO - Cleaning up... 21:01:02 INFO - Return code: 0 21:01:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:01:02 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')]} 21:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:02 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')]} 21:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:02 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 0x161d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1989a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d090>, '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 0x198dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1987f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', '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 21:01:02 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 21:01:02 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 21:01:02 INFO - Using env: {'DISPLAY': ':0', 21:01:02 INFO - 'HOME': '/home/cltbld', 21:01:02 INFO - 'LANG': 'en_US.UTF-8', 21:01:02 INFO - 'LANGUAGE': 'en_US:en', 21:01:02 INFO - 'LOGNAME': 'cltbld', 21:01:02 INFO - 'MAIL': '/var/mail/cltbld', 21:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:02 INFO - 'MOZ_NO_REMOTE': '1', 21:01:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:02 INFO - 'NO_EM_RESTART': '1', 21:01:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:02 INFO - 'PWD': '/builds/slave/test', 21:01:02 INFO - 'SHELL': '/bin/bash', 21:01:02 INFO - 'SHLVL': '1', 21:01:02 INFO - 'TERM': 'linux', 21:01:02 INFO - 'TMOUT': '86400', 21:01:02 INFO - 'USER': 'cltbld', 21:01:02 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:02 INFO - '_': '/tools/buildbot/bin/python'} 21:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:01:02 INFO - Running setup.py (path:/tmp/pip-kireQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:01:02 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)) 21:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:01:02 INFO - Running setup.py (path:/tmp/pip-_wrTBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:01:02 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)) 21:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:01:02 INFO - Running setup.py (path:/tmp/pip-V_iTKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:01:02 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)) 21:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:01:02 INFO - Running setup.py (path:/tmp/pip-6I0Phy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:01:02 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)) 21:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:01:02 INFO - Running setup.py (path:/tmp/pip-Lwx9s1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:01:02 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)) 21:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:01:02 INFO - Running setup.py (path:/tmp/pip-NTvgCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:01:02 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)) 21:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:01:02 INFO - Running setup.py (path:/tmp/pip-tEtSKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:01:02 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)) 21:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:01:02 INFO - Running setup.py (path:/tmp/pip-g1RDuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:01:02 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)) 21:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:01:02 INFO - Running setup.py (path:/tmp/pip-hAQesm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:01:02 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)) 21:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:01:02 INFO - Running setup.py (path:/tmp/pip-haOXzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:01:03 INFO - Running setup.py (path:/tmp/pip-xKy3C6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:01:03 INFO - Running setup.py (path:/tmp/pip-QeZ1o0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:01:03 INFO - Running setup.py (path:/tmp/pip-fkFGt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:01:03 INFO - Running setup.py (path:/tmp/pip-2ELftw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:03 INFO - Running setup.py (path:/tmp/pip-67HfcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:01:03 INFO - Running setup.py (path:/tmp/pip-PN6C83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:01:03 INFO - Running setup.py (path:/tmp/pip-UYiLBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:01:03 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 21:01:03 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 21:01:03 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 21:01:03 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 21:01:04 INFO - Downloading blessings-1.6.tar.gz 21:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:01:04 INFO - Installing collected packages: blessings 21:01:04 INFO - Running setup.py install for blessings 21:01:04 INFO - Successfully installed blessings 21:01:04 INFO - Cleaning up... 21:01:04 INFO - Return code: 0 21:01:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:01:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:01:05 INFO - Reading from file tmpfile_stdout 21:01:05 INFO - Current package versions: 21:01:05 INFO - argparse == 1.2.1 21:01:05 INFO - blessings == 1.6 21:01:05 INFO - blobuploader == 1.2.4 21:01:05 INFO - docopt == 0.6.1 21:01:05 INFO - functools32 == 3.2.3-2 21:01:05 INFO - jsonschema == 2.5.1 21:01:05 INFO - manifestparser == 1.1 21:01:05 INFO - mozInstall == 1.13 21:01:05 INFO - mozcrash == 1.0 21:01:05 INFO - mozdebug == 0.1 21:01:05 INFO - mozdevice == 0.51 21:01:05 INFO - mozfile == 1.2 21:01:05 INFO - mozhttpd == 0.7 21:01:05 INFO - mozinfo == 0.10 21:01:05 INFO - mozleak == 0.1 21:01:05 INFO - mozlog == 3.5 21:01:05 INFO - moznetwork == 0.27 21:01:05 INFO - mozprocess == 0.25 21:01:05 INFO - mozprofile == 0.28 21:01:05 INFO - mozrunner == 6.13 21:01:05 INFO - mozscreenshot == 0.1 21:01:05 INFO - mozsystemmonitor == 0.3 21:01:05 INFO - moztest == 0.8 21:01:05 INFO - mozversion == 1.4 21:01:05 INFO - psutil == 3.1.1 21:01:05 INFO - requests == 1.2.3 21:01:05 INFO - wsgiref == 0.1.2 21:01:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:05 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 0x161d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1989a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d090>, '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 0x198dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1987f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', '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 21:01:05 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 21:01:05 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 21:01:05 INFO - Using env: {'DISPLAY': ':0', 21:01:05 INFO - 'HOME': '/home/cltbld', 21:01:05 INFO - 'LANG': 'en_US.UTF-8', 21:01:05 INFO - 'LANGUAGE': 'en_US:en', 21:01:05 INFO - 'LOGNAME': 'cltbld', 21:01:05 INFO - 'MAIL': '/var/mail/cltbld', 21:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:05 INFO - 'MOZ_NO_REMOTE': '1', 21:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:05 INFO - 'NO_EM_RESTART': '1', 21:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:05 INFO - 'PWD': '/builds/slave/test', 21:01:05 INFO - 'SHELL': '/bin/bash', 21:01:05 INFO - 'SHLVL': '1', 21:01:05 INFO - 'TERM': 'linux', 21:01:05 INFO - 'TMOUT': '86400', 21:01:05 INFO - 'USER': 'cltbld', 21:01:05 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:05 INFO - '_': '/tools/buildbot/bin/python'} 21:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:05 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)) 21:01:05 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)) 21:01:05 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)) 21:01:05 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)) 21:01:05 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)) 21:01:05 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)) 21:01:05 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)) 21:01:05 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)) 21:01:05 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)) 21:01:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:01:05 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 21:01:05 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 21:01:05 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 21:01:05 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 21:01:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 21:01:06 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)) 21:01:06 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)) 21:01:06 INFO - Installing collected packages: simplejson 21:01:06 INFO - Running setup.py install for simplejson 21:01:06 INFO - building 'simplejson._speedups' extension 21:01:06 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 21:01:07 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 21:01:07 INFO - Successfully installed simplejson 21:01:07 INFO - Cleaning up... 21:01:07 INFO - Return code: 0 21:01:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x161d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1989a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d090>, '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 0x198dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1987f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', '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 21:01:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:01:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:01:07 INFO - Using env: {'DISPLAY': ':0', 21:01:07 INFO - 'HOME': '/home/cltbld', 21:01:07 INFO - 'LANG': 'en_US.UTF-8', 21:01:07 INFO - 'LANGUAGE': 'en_US:en', 21:01:07 INFO - 'LOGNAME': 'cltbld', 21:01:07 INFO - 'MAIL': '/var/mail/cltbld', 21:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:07 INFO - 'MOZ_NO_REMOTE': '1', 21:01:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:07 INFO - 'NO_EM_RESTART': '1', 21:01:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:07 INFO - 'PWD': '/builds/slave/test', 21:01:07 INFO - 'SHELL': '/bin/bash', 21:01:07 INFO - 'SHLVL': '1', 21:01:07 INFO - 'TERM': 'linux', 21:01:07 INFO - 'TMOUT': '86400', 21:01:07 INFO - 'USER': 'cltbld', 21:01:07 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:07 INFO - '_': '/tools/buildbot/bin/python'} 21:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:01:07 INFO - Cleaning up... 21:01:07 INFO - Return code: 0 21:01:07 INFO - Running post-action listener: _resource_record_post_action 21:01:07 INFO - Running post-action listener: _start_resource_monitoring 21:01:07 INFO - Starting resource monitoring. 21:01:07 INFO - [mozharness: 2017-09-17 04:01:07.837667Z] Finished create-virtualenv step (success) 21:01:07 INFO - [mozharness: 2017-09-17 04:01:07.838177Z] Running install step. 21:01:07 INFO - Running pre-action listener: _resource_record_pre_action 21:01:07 INFO - Running main action method: install 21:01:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:01:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:01:08 INFO - Reading from file tmpfile_stdout 21:01:08 INFO - Detecting whether we're running mozinstall >=1.0... 21:01:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:01:08 INFO - Reading from file tmpfile_stdout 21:01:08 INFO - Output received: 21:01:08 INFO - Usage: mozinstall [options] installer 21:01:08 INFO - Options: 21:01:08 INFO - -h, --help show this help message and exit 21:01:08 INFO - -d DEST, --destination=DEST 21:01:08 INFO - Directory to install application into. [default: 21:01:08 INFO - "/builds/slave/test"] 21:01:08 INFO - --app=APP Application being installed. [default: firefox] 21:01:08 INFO - mkdir: /builds/slave/test/build/application 21:01:08 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'] 21:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:01:26 INFO - Reading from file tmpfile_stdout 21:01:26 INFO - Output received: 21:01:26 INFO - /builds/slave/test/build/application/firefox/firefox 21:01:26 INFO - Running post-action listener: _resource_record_post_action 21:01:26 INFO - [mozharness: 2017-09-17 04:01:26.090712Z] Finished install step (success) 21:01:26 INFO - [mozharness: 2017-09-17 04:01:26.090855Z] Running setup-mitmproxy step. 21:01:26 INFO - Running pre-action listener: _resource_record_pre_action 21:01:26 INFO - Running main action method: setup_mitmproxy 21:01:26 INFO - Skipping: mitmproxy is not required 21:01:26 INFO - Running post-action listener: _resource_record_post_action 21:01:26 INFO - [mozharness: 2017-09-17 04:01:26.091380Z] Finished setup-mitmproxy step (success) 21:01:26 INFO - [mozharness: 2017-09-17 04:01:26.091482Z] Running run-tests step. 21:01:26 INFO - Running pre-action listener: _resource_record_pre_action 21:01:26 INFO - Running pre-action listener: _set_gcov_prefix 21:01:26 INFO - Running main action method: run_tests 21:01:26 WARNING - Try message not found. 21:01:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:01:26 INFO - Python 2.7.3 21:01:26 INFO - Return code: 0 21:01:26 INFO - grabbing minidump binary from tooltool 21:01:26 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')]} 21:01:26 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 0x198d090>, '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 0x198dd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1987f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:01:26 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 21:01:26 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 21:01:26 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 21:01:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:01:26 INFO - Return code: 0 21:01:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:01:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:01:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:01:26 INFO - ENV: RUST_BACKTRACE is now full 21:01:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:01:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:01:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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 21:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-088 --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 21:01:26 INFO - Using env: {'DISPLAY': ':0', 21:01:26 INFO - 'HOME': '/home/cltbld', 21:01:26 INFO - 'LANG': 'en_US.UTF-8', 21:01:26 INFO - 'LANGUAGE': 'en_US:en', 21:01:26 INFO - 'LOGNAME': 'cltbld', 21:01:26 INFO - 'MAIL': '/var/mail/cltbld', 21:01:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:01:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:26 INFO - 'MOZ_NO_REMOTE': '1', 21:01:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:01:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:26 INFO - 'NO_EM_RESTART': '1', 21:01:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:26 INFO - 'PWD': '/builds/slave/test', 21:01:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:01:26 INFO - 'RUST_BACKTRACE': 'full', 21:01:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:01:26 INFO - 'SHELL': '/bin/bash', 21:01:26 INFO - 'SHLVL': '1', 21:01:26 INFO - 'TERM': 'linux', 21:01:26 INFO - 'TMOUT': '86400', 21:01:26 INFO - 'USER': 'cltbld', 21:01:26 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:26 INFO - '_': '/tools/buildbot/bin/python'} 21:01:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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 21:01:26 INFO - mozversion application_buildid: 20170917030923 21:01:26 INFO - mozversion application_changeset: c079f3f1d24a9797205ba210e7f6a5d21d8c609b 21:01:26 INFO - mozversion application_display_name: Nightly 21:01:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:01:26 INFO - mozversion application_name: Firefox 21:01:26 INFO - mozversion application_remotingname: firefox 21:01:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:01:26 INFO - mozversion application_vendor: Mozilla 21:01:26 INFO - mozversion application_version: 57.0a1 21:01:26 INFO - mozversion platform_buildid: 20170917030923 21:01:26 INFO - mozversion platform_changeset: c079f3f1d24a9797205ba210e7f6a5d21d8c609b 21:01:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:01:26 INFO - mozversion platform_version: 57.0a1 21:01:26 INFO - using testdate: 1505620886 21:01:26 INFO - actual date: 1505620886 21:01:26 INFO - starting webserver on 'localhost:54159' 21:01:26 INFO - SUITE-START | Running 2 tests 21:01:26 INFO - TEST-START | dromaeo_css 21:01:26 INFO - Initialising browser for dromaeo_css test... 21:01:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54159/getInfo.html -profile /tmp/tmpIfxsi4/profile 21:01:26 INFO - TEST-INFO | started process 15720 (/builds/slave/test/build/application/firefox/firefox http://localhost:54159/getInfo.html) 21:01:31 INFO - TEST-INFO | 15720: exit 0 21:01:31 INFO - Browser initialized. 21:01:31 INFO - Running cycle 1/1 for dromaeo_css test... 21:01:31 INFO - Using env: {'DISPLAY': ':0', 21:01:31 INFO - 'HOME': '/home/cltbld', 21:01:31 INFO - 'JSGC_DISABLE_POISONING': '1', 21:01:31 INFO - 'LANG': 'en_US.UTF-8', 21:01:31 INFO - 'LANGUAGE': 'en_US:en', 21:01:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:01:31 INFO - 'LOGNAME': 'cltbld', 21:01:31 INFO - 'MAIL': '/var/mail/cltbld', 21:01:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:01:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:01:31 INFO - 'MOZ_CRASHREPORTER': '1', 21:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:01:31 INFO - 'MOZ_NO_REMOTE': '1', 21:01:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:01:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:31 INFO - 'NO_EM_RESTART': '1', 21:01:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:31 INFO - 'PWD': '/builds/slave/test', 21:01:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:01:31 INFO - 'RUST_BACKTRACE': 'full', 21:01:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:01:31 INFO - 'SHELL': '/bin/bash', 21:01:31 INFO - 'SHLVL': '1', 21:01:31 INFO - 'TERM': 'linux', 21:01:31 INFO - 'TMOUT': '86400', 21:01:31 INFO - 'USER': 'cltbld', 21:01:31 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:31 INFO - '_': '/tools/buildbot/bin/python'} 21:01:31 INFO - TEST-INFO | started process 15952 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIfxsi4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:01:32 INFO - PID 15952 | 21:01:32 INFO - PID 15952 | (/builds/slave/test/build/application/firefox/firefox:16001): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:01:32 INFO - PID 15952 | 21:01:43 INFO - PID 15952 | RSS: Main: 156934144 21:01:43 INFO - PID 15952 | 21:04:40 INFO - PID 15952 | Cycle 1(1): loaded http://localhost:54159/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54159/tests/dromaeo/cssquery-ext.html) 21:04:41 INFO - PID 15952 | RSS: Main: 156254208 21:04:41 INFO - PID 15952 | 21:07:43 INFO - PID 15952 | Cycle 1(1): loaded http://localhost:54159/tests/dromaeo/cssquery-ext.html (next: http://localhost:54159/tests/dromaeo/cssquery-jquery.html) 21:07:44 INFO - PID 15952 | RSS: Main: 156045312 21:07:44 INFO - PID 15952 | 21:10:46 INFO - PID 15952 | Cycle 1(1): loaded http://localhost:54159/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54159/tests/dromaeo/cssquery-mootools.html) 21:10:46 INFO - PID 15952 | RSS: Main: 156155904 21:10:46 INFO - PID 15952 | 21:13:48 INFO - PID 15952 | Cycle 1(1): loaded http://localhost:54159/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54159/tests/dromaeo/cssquery-prototype.html) 21:13:48 INFO - PID 15952 | RSS: Main: 156741632 21:13:48 INFO - PID 15952 | 21:16:52 INFO - PID 15952 | Cycle 1(1): loaded http://localhost:54159/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54159/tests/dromaeo/cssquery-yui.html) 21:16:52 INFO - PID 15952 | RSS: Main: 156762112 21:16:52 INFO - PID 15952 | 21:19:54 INFO - PID 15952 | Cycle 1(1): loaded http://localhost:54159/tests/dromaeo/cssquery-yui.html (next: http://localhost:54159/tests/dromaeo/cssquery-dojo.html) 21:19:54 INFO - PID 15952 | RSS: Main: 160686080 21:19:54 INFO - PID 15952 | 21:19:54 INFO - PID 15952 | __start_tp_report 21:19:54 INFO - PID 15952 | _x_x_mozilla_page_load 21:19:54 INFO - PID 15952 | _x_x_mozilla_page_load_details 21:19:54 INFO - PID 15952 | |i|pagename|runs| 21:19:54 INFO - PID 15952 | |0;dojo.html;3145.4809956357203;3168.4930411134224;3191;3193.9619623622316;3195.4567723487003;6212.65830379329;6273.5294852886045;6277.843053923654;6334.208223972004;6378.840528986778;6281.08924205991;6347.2700639426475;6385.233771947361;6386.329435409285;6391.808245752623;5750.02249617566;5766.711664416784;5768.942310576904;5864.208331875185;5916.08300713388;5692.117721753135;5724.169995350671;5742.196092547047;5792.478676919077;5806.941930580682;5746.425357464262;5752.36723960364;5763.4812866842185;5767.538596912237;5778.479936805666;5665.3768085510565;5758.942410575883;5760.481556659921;5769.682667453289;5779.393163717833;5684.488396044376;5701.942980570183;5754.482096611325;5763.942360576382;5775.335836378828;6094.146819445237;6220.377962203788;6290.905636415458;6308.968455157695;6364.554481186319;6605.801825945229;6611.570247933869;6623.801285961429;6630.038644396535;6637.1703537057865;6557.5081868860025;6704.899426508607;6713.462922966151;6713.865722685568;6718.529702920798;6193.566450348478;6275.780347687854;6314.5895516791725;6320.873582528324;6329.05064240363;6889.379955803902;6909;6918.238993710688;6967.651617419108;6988.056612357368;6586.37429444202;6590.143281373391;6662.6668666566475;6676.933230667728;6706.463482921355;6523.804285871432;6640.634765087966;6661.433778128817;6664.700088496029;6666.600003999775;6587;6609.537332386687;6631.535792494479;6667.33326667339;6670.433013193933;6714.429273511806;6741.0899528564;6800.727970881208;6801.897971530431;6808.795736127924;5260.868478288074;5300.1254792958725;5335.599830012765;5342.492463215988;5349.652272602346;4494.977525112419;4569.109023740339;4628.3288923105965;4650.325702773079;4668.929966050512;5868.972929737279;5881.558883083786;5883.558733095035;5884.617499862496;5893;6768.492363072789;6824.829379265558;6846.8972965405555;6854.725810967605;6873.312668733187;5839.416058394127;5856.648601083949;5928.940710592925;5931.466168044896;5938.28740551128;5636.859078522988;5746.827595172152;5778.27771528559;5790.160426738184;5867.589268751147;6507.34926507355;6510.9023364649565;6525.64108974011;6531.314212007671;6594.373534514213;6713.932860671331;6734.629595372302;6769.661516924232;6778.559393639398;6796.864062718817;6544.44372228366;6544.574602650812;6559.344065593403;6649.368310010541;6650.102236198051;6386.425221730066;6566.9014964774615;6636.568623039487;6648.1357423536565;6683.298253683293;6642.468602511691;6656.201255849329;6731.596104233761;6736.360045795642;6736.898946515708;6505.447037001908;6538.967305163444;6556.508261880474;6588.07766912623;6602.735890564323;6517.771877984248;6553.705083271359;6565.934340656532;6588.8352791180605;6614.867702646016;6245.375462453719;6365.936340636535;6442.405708460791;6447.097406363207;6536.0522724205675;6526.24948130971;6557.278699343164;6581.967090164519;6598.439132673777;6636.701348439428;6339.714712837841;6340.0489926509545;6353.3011368748475;6363.204599425072;6441.5490915635455 21:19:54 INFO - PID 15952 | |1;ext.html;823.5264722784399;824.678375433581;836.5608055770723;837.317586167274;837.9832403351933;38067.238655226916;41808.79095604522;41899.162016759685;41924.37113443301;41930.79034604823;37078.814605927;37127;37166.814165929136;37329;37457.81271093641;6928.237893831674;7037.0499982502215;7040.436765058808;7042.295770422942;7057.576545407266;6884.931150688479;6974.197967233757;6997.195322537897;6999.720011199546;7002;6884.0706504621985;6961.547499412547;6967.303269673018;6987.301269873023;7013.579185248901;6948.5830850148905;6979.790606281819;6980.232174460831;6982.615956122411;6990.056342393763;6256.906146407808;6259.029850373154;6292.968535157295;6309.116723658692;6313.558050936436;34225.14437139066;34422.82788586066;34527.136821579414;34585.30829383398;34634;3407.4037043517533;3421.9315613687836;3432.2277487565348;3441.5525981622477;3444.293919746471;28107.437851243067;28110.859445702845;28164.859175704194;28221.294467638472;28222.71777282242;4481.260592002316;4502.774861256957;4526.275795872678;4552.203364411248;4595.03504264105;192517.03741481344;192984;193631;193986;194575.0271248649;19266.32567860146;19465.318713844947;19530.902345488183;40616.79691601523;41157.38263926043;42903.785481072395;50868.74565627222;51483.48516514787;51669.48330516722;51683.741581291855;15135.318910649048;15168.772468412983;15173.393064277305;15289.464868729541;15301;12337.383130843422;12381.504739810487;12384.938075309747;12385.93807030959;12387.62837114888;12952.676183095498;13062.804057939138;13105.344732763515;13105.737885242243;13188.076834621485;5386.569074474032;5443.537299329522;5484.369297530835;5503.614746967673;5609.186667933209;995.9253056020827;1016.9847452288221;1032.5198782566142;1033.7570670892335;1039.4231201683137;22950.770492294865;23226.883865580563;23406.64890026651;23421;23464.17875374388;1130.1015692524463;1133.2237417369129;1133.5749094089717;1137.7781332640056;1143.54258296682;12114.394280285951;12306.953908917663;12316.014718822473;12410.81383779244;12467.438965246585;215006;215399.84600154113;215529;215710;216009;51449.742751286;51599.74200128976;51626.74186629043;51674.74162629163;51710.482895170564;1429.171080773149;1451.8112645356036;1469.559132260339;1474.0345073976562;1474.167095591009;21756.89121554382;22379.552408952055;22486.662700059183;22508.662370064136;22885.771142288362;36879.44680829736;36939.63060369362;36958.63041369552;36963.26073478461;37018.8149059253;198908;200239;200704;200773;200904;34148.31703365968;34173.14567135742;34491.13772155716;34934.65065349314;34960.825195873855;42894.35658465063;44671.55328446674;44722.3291650632;44798.776006121065;45024;33068.503972439954;33192.1701957453;33602.83198583991;33719.15702107467;34052.65947340495;32669;32786.67213327836;32824.50763238506;33000.834995824865;33231.833840830644;35870.641293587774;36862.63137368699;36945.63054369422;37031.44452833156;37107.443388348656;32480.51279230766;32558.83720581382;32830.507542387364;32851.835740821145;32961.34077318488;23954.104941507572;24088.87955560211;24619.87690061538;24630;24854.627180591942 21:19:54 INFO - PID 15952 | |2;jquery.html;533.485186794743;543.8178210299554;552.215853488047;573.2175580332847;574.46861652971;22203.44491387716;22915.42711432213;22994.195203167892;22995.885020574893;23091.769082309173;23360.182393616225;23769.643455348185;24032.87983560084;24051.75948240517;25108.74891251087;6565.343465653445;6787.592744435349;6807.319268073203;6817.386435220829;6824.454043676519;6643.800685979429;6771.525993180479;6776.457883369319;6783.592984420926;6788.08360871281;6291.842703932392;6549.869002619945;6554.180727409074;6558.1474408327085;6712.4965627577885;6544.509161812859;6581.407673309377;6582.670866456706;6582.9670851645915;6588.407043366096;5905.261842269716;6070.757169713206;6079.300880398767;6091.5431342649035;6120.296165940885;28952.565711514344;29423.41153176946;29460.410791784045;29469.557956630666;29486.852565737034;2031.7968203179564;2046.7339245898088;2055.6710926251876;2060.6084843879617;2066.3077868913933;18952.81047189524;19133.13900874464;19555.80444195554;19576.119074641676;19717;2422.39440139965;2499.9375015624573;2514.3085651446;2526.924192274235;2529.709083455408;118188.40905795417;124657;135676.32161839228;136217;141581;13901;14218.217998010208;14282.35729392159;14691.265436728121;14785.926070369795;23151.88424057869;23193.53612927732;23202.1879234226;23212.3036308911;23225.651615225786;9413.67052153185;9646.469444180637;9728.854067188999;9801.460919649488;9823.950880245697;8942.284617230605;8948.955255223813;8986.55067246635;9016.09839016093;9017.774555636162;8883.73348799537;8957.372983891066;8989;9118.544072796334;9349;2533.8479691218586;2624.73752624736;2654.495645827287;2671.1185308848035;2761.2782142536084;953.7043516509904;962.6341990043738;963.710886733977;965.0783501755807;976.7216343342113;19476.51308717265;19534.413967580997;19645.50886227856;19959.60080798376;19977.700334494995;1020.8519764634086;1026.7381817636456;1031.5564307347763;1044.195969103801;1052.6999805055523;10495.895041049493;11028.338299702044;11099.556017759362;11115.388653623966;11236.943815281034;452879;456569;460767;461913;471781;112018;113038.43480782544;113200;116173.4191329038;116968.41515792366;1021.1065317846884;1132.314949455584;1135.2450620337383;1187.619961612293;1195.0618764270007;20354.694679579523;20457.2839950601;20772.1691132353;21200.469988250417;21214.15143394247;52288.7385563085;52300.73849630728;52698;53119.20321195108;53180.73409632927;64853.67573162293;66805.33194667991;67301;67384.32615673977;68585.31414685989;14329.21189334555;14604.853951460775;14675.339609717801;14686.63283417923;14738.852611474176;23447.296581102593;23686.697231651735;23872;23916;24198.75801241965;18433.723494147867;18445.16996735177;18451.538711532317;18452.985085820408;18766;16340.264688088826;16893.155342232592;17267.3956411525;17357.913210433868;17495.21271542809;18170.81829181691;19280;19396.709049363988;19584.02079896028;19588;14382.208978506282;14433.134011959317;14579.92710036443;14600.926995364955;14622.12267263925;18374.908125459286;18508.1671324788;18574.814251857308;18668.813311867252;18704.345347912757 21:19:54 INFO - PID 15952 | |3;mootools.html;1328.3291937571526;1371.2800779590714;1388.548721665459;1390.9513167039156;1406.9929650351753;14868.702625947488;15487.767683484743;15500.612484687874;15504.147271900069;15511.301991410386;15127.84872151278;15155.54533363998;15155.848441515582;15215.315310811013;15249.542513724606;2669.3726974161054;2700.9459810803774;2750.2436829871745;2756.9724302756917;2760.7653349465318;2663.5205662980657;2713.81003329767;2717.442924200544;2723.0741547873718;2723.251105945861;2660.5344064788583;2746.217328061503;2763.8065335426527;2767.3635063935308;2767.5571908494644;2549.8470091794366;2563.115725074846;2586.379268975451;2589.0420544398694;2589.3785491482004;2616.1105224223666;2639.1027050802627;2658.587918872578;2673.3717576369536;2680.584509401046;14904.254787260594;15070.472533461387;15164.848351516453;15226.695466090727;15272.16003119828;8019.398545109082;8288.254057134887;8290.751277461686;8299.875501867476;8328.08391076981;5312.096943519611;5501.367342755594;5538.252335934638;5659.60382773206;5697.202391665171;1898.250191174492;1916.5400303926958;1917.731517587539;1953.677643188872;1960.3824795189469;25882.482350352886;25961.221163365128;26016;26050.73949260521;26122.73877261222;6692.631905245258;6754.3921047106;6862.965685171542;6977.651117444209;6996.230414654384;16979.49061528156;17013.149342532823;17021.23404446778;17105.315787368614;17120;7479.588622625808;7633.503822251647;7651.1583725790115;7882.881756773653;8046.959765201136;6640.302768209269;6749.392554670103;6790.185177778698;6796.422304104207;6825.283345248777;5193.454687257784;5307.761150748225;5349.438308977579;5354.330708661418;5362.490563396471;1368.4868174434587;1488.4269556220934;1514.6592016796133;1516.1282262698992;1518.7494063479403;700.5936556797045;862.719616124762;868.8653258744905;874.711345256064;885.4333226734894;8545.529995850287;8610.827783444422;8623.568821559022;8694.391392602458;8749.518776467228;984.6061575369766;996.7109538233976;999.8350272205005;1002.2382988928321;1023.0434543701663;5109.514596113363;5113.948860511347;5132.178851383761;5160.845174644766;5191.818286360035;20250.89874550618;21033.15867365288;21607.45981350479;21961.78038219629;22029.559408811732;21511.78488215098;21556;21570.245041423474;21620.45948851291;21680.674789878165;10076.899231007743;11292.435378231055;11297.661070167907;11325.093992480583;11533.538658453735;7828.725994590162;8220.424570280262;8281.917180828355;8340.332773377993;8382.784496248132;18210.36263730763;18626.534336641693;18858.811411885705;19115.426537203904;19215.807841921403;18776.15507302202;18846.3403780867;18958.715619265997;18982.335618253295;19050.61898761989;10730.409827459565;10967.451627418437;10987.835182472429;10990;10998.450077496253;12494.937525312314;12584.937075314565;12636.115471917245;12642.30467324306;12708.110432269652;11514.078873690252;11685.532578696757;11771.11716621274;11827.467763950815;11866;11232.831507527557;11286;11344.489497972447;11489.54041838317;11641.941790290994;9219.44683319003;9364.484953327632;9367.718968430958;9474.52627368615;9481.051894810464;5976.790812321722;6040.063790112539;6058.697065146813;6080.847978800565;6199.814005579839;6559.311272316529;6595.967020165061;6646.83382915431;6653.035309880137;6744.270607631869 21:19:54 INFO - PID 15952 | |4;prototype.html;499.2973958800491;501.99749001254986;526.3026489900882;527.6280222443178;529.4573062610823;6031.366706495815;6150.323464418911;6177.351378105302;6212.78255261066;6306.59007164534;6121.306625084971;6151.216147317273;6156.907646385308;6190.659513726743;6199.597026193294;5117.795288188468;5134.589232861381;5138.820141295056;5150.2532132840715;5165.121929272023;4871.7076975381415;5122.462141475147;5138.126518491847;5153.252778347138;5161.509656582619;4948.134076536592;4952.133376659088;4967.4287456942375;4976.601871850261;4988.127077761395;4866.635002374836;4895.388076490439;4956.330895329121;4957.529034741694;4994.925076123861;4413.492448368414;4419.469663640384;4427.114577084564;4429.756363400012;4434.889127771799;6017.48851347636;6085.178500902366;6087.269527656709;6165.876682466371;6179.81460556184;5676.602637815355;5690;5703.942960570383;5710.600257981943;5728.112142617901;6385.201849768779;6402.071699603578;6461.709223084972;6480.935190648081;6510.7721229756735;4807.687500312469;4813.566778989873;4832.395950506187;4844.128056949748;4844.467108618049;6768.187817461887;6874.450043996519;6889.173299204077;6906.37842594169;6925;4918.491569299611;4946.5407704727195;4993.201087825931;5010.847505073853;5015.51421761872;5003.924941125886;5006.674566153189;5009.098362294749;5025.195968644999;5040.39515258164;5624.21261023457;5642.576806739511;5655.632383895034;5683.715814209273;5684.971575142098;6505.251896032017;6588;6607.405333520006;6615.437687796495;6639.236487803868;1637.7880368527203;1648.620817212048;1677.5973766296001;1686.4434736536914;1727.369510128794;1202.555054629775;1221.5541327415428;1222.6759908624222;1225.9509619615294;1227.9324637145044;5673.744681489343;5727.828165155052;5729.939961107209;5744.511716504144;5748.827535173951;6537.280899101095;6557.639329836809;6560.737570497222;6561.2782593914635;6574;5624.184493248538;5660.490555849993;5704.543636509069;5706.233885635461;5740.598158128891;3474.722022238215;3499.8775042873376;3527.0829584308262;3532.2691880978136;3547.492315765789;6409.391107844747;6470.773522926675;6513.478921686254;6524.445422139172;6527.118838956775;6742.797716068428;6760.65347652915;6810.353016463428;6821.93178068213;6832.419244364285;6427.614343139239;6505.674716264262;6509.576877502947;6513.185851768529;6527.934720652827;6382.585131966407;6487.740490380332;6533.836654083686;6549.377809108127;6587.901181482377;6471.935280647133;6484.221893372825;6506.284308725942;6520.282768895323;6587.299207324973;6399.872002560016;6439;6444.806655800334;6458.870822583428;6484.708188131451;4873.537013983666;4915.4592994771265;4928.162212423825;4945.925811112908;4956.851294461172;6502.479801615954;6514.804555863142;6524.151860258136;6619.768308109193;6654.334566543307;6211.472024877936;6310.6213627182515;6318.08387783778;6384.201974753156;6385.68071596409;6171;6286.622802631857;6287.96856015717;6293;6333.90499142504;5649.096144616841;6088.543359247986;6130.50955923534;6235.690504994002;6292.559520833498;6357.23713154406;6432.710528026157;6440.935590644033;6451.41937225652;6454.225492940877;6046.969765151146;6076.240469941257;6085.269767627913;6111.938880611315;6176.7838125665385 21:19:54 INFO - PID 15952 | |5;yui.html;909.381620498061;912.9406588571742;942.3544871762844;943.377370935183;944.8677185194074;18823.152958116883;19663.70504442433;19668.606627867433;19696.409107726755;19787.50531236718;16130.516084517454;16454.7531787023;16490.835091649078;18341.358052468167;18364.632707345878;913.1872633356302;930.186087173723;931.180561106229;932.1563984593953;932.9393589416666;972.7665360313545;973.2505970402785;978.1050338939862;983.7737320416309;984.1634610580985;992.2062350119911;1014.1329163565156;1020.515255253756;1021.7445638590352;1022.4172221833558;996.621283912116;996.6910257820099;1007.2445665750687;1015.5227043289678;1023.754298968246;748.5284270909347;748.8165399477163;749.9362554182901;750.4409215134759;752.259024860512;19960.201591936304;20092.296769613138;20178.29375971834;20193.8990305049;20201.494962625904;21369.67945480819;21570.676439853414;21732;21847.563048738935;21874.562508749896;16075.196240187945;16274.75587866183;16926.407575734913;16958.067306298148;17161;3664.1572438339063;3700.7409481336317;3702.315071711742;3705.0366904604725;3707.703383729322;135319.32340338334;136466.31766841104;137090.31454842762;137845;138265.30867345698;16740.916295418447;16807;16915.407960721317;16935;17000.234989425506;31330.68669313278;31399.529007064913;31553.52669709956;31904.680953190637;32210.19474513156;8261.256486916207;8287.295579875657;8316.875246871301;8346.540940248224;8388.035375931724;3988.3219852625125;4009;4091.529474110456;4124.072083781125;4124.443200167939;5353.2773075634295;5488.121900495901;5488.957098151339;5490.094134467848;5493.587980901449;968.4237878462303;995.233670074039;997.3567049253191;1002.6791426743519;1007.3351587952063;682.0962225051727;700.9579425234502;712.4976891291627;712.8182313510022;719.5934297122213;11271.605493807678;11353.091752659933;11548.538058477734;12076.094292928066;12170.208936419105;679.3817625960353;692.4979389942251;705.7141857547701;707.1973310292752;709.0959027240207;5098.439171691185;5218.321618189611;5281.683099014072;5354.089804733205;5395.298611180521;116349;123267.38366308111;124066;124231;124652;108460;109885;111398.44300778444;112149;112739.43630281797;91967.54016229876;92871;96980.51509742407;97755;99130;10000.44997525114;10040.196784257389;10065.043820837009;10272.486375681336;10283.84574231402;42545.361819572114;43424;44173;45262;45385.092298154515;57226.14160787675;58368.708156458946;58568;59340.406595935216;59504.40495595162;15550.533483995498;15641.29614167388;15696.136712480924;15728.370865165723;15730.9213453932;17107.401240956508;17225.655486890442;17251.309947601956;17252.65494690074;17322.307107716053;14333;14478.058926169766;14628.488002919847;14820.333085011416;14827.036242644625;14300.35648395804;14382.640433989234;14393.352299146354;14447;14692.41230350774;13648.93175534116;13673.658158546115;13822.516211932534;13979.231142286864;13990.790138148139;10680.62617808373;10880.401577913291;11054.723631908944;11125.221234513507;11183.049440797624;10434.217433692655;10453.111485523816;10464.215183861088;10534.473276336306;10697.732556686145 21:19:54 INFO - PID 15952 | __end_tp_report 21:19:54 INFO - PID 15952 | __start_cc_report 21:19:54 INFO - PID 15952 | _x_x_mozilla_cycle_collect,271 21:19:54 INFO - PID 15952 | __end_cc_report 21:19:54 INFO - PID 15952 | __startTimestamp1505621994648__endTimestamp 21:19:54 INFO - PID 15952 | 21:19:54 INFO - PID 15952 | ------- Summary: start ------- 21:19:54 INFO - PID 15952 | Number of tests: 6 21:19:54 INFO - PID 15952 | 21:19:54 INFO - PID 15952 | [#0] dojo.html Cycles:165 Average:6183.41 Median:6441.55 stddev:733.10 (11.4%) stddev-sans-first:695.54 21:19:54 INFO - PID 15952 | Values: 3145.5 3168.5 3191.0 3194.0 3195.5 6212.7 6273.5 6277.8 6334.2 6378.8 6281.1 6347.3 6385.2 6386.3 6391.8 5750.0 5766.7 5768.9 5864.2 5916.1 5692.1 5724.2 5742.2 5792.5 5806.9 5746.4 5752.4 5763.5 5767.5 5778.5 5665.4 5758.9 5760.5 5769.7 5779.4 5684.5 5701.9 5754.5 5763.9 5775.3 6094.1 6220.4 6290.9 6309.0 6364.6 6605.8 6611.6 6623.8 6630.0 6637.2 6557.5 6704.9 6713.5 6713.9 6718.5 6193.6 6275.8 6314.6 6320.9 6329.1 6889.4 6909.0 6918.2 6967.7 6988.1 6586.4 6590.1 6662.7 6676.9 6706.5 6523.8 6640.6 6661.4 6664.7 6666.6 6587.0 6609.5 6631.5 6667.3 6670.4 6714.4 6741.1 6800.7 6801.9 6808.8 5260.9 5300.1 5335.6 5342.5 5349.7 4495.0 4569.1 4628.3 4650.3 4668.9 5869.0 5881.6 5883.6 5884.6 5893.0 6768.5 6824.8 6846.9 6854.7 6873.3 5839.4 5856.6 5928.9 5931.5 5938.3 5636.9 5746.8 5778.3 5790.2 5867.6 6507.3 6510.9 6525.6 6531.3 6594.4 6713.9 6734.6 6769.7 6778.6 6796.9 6544.4 6544.6 6559.3 6649.4 6650.1 6386.4 6566.9 6636.6 6648.1 6683.3 6642.5 6656.2 6731.6 6736.4 6736.9 6505.4 6539.0 6556.5 6588.1 6602.7 6517.8 6553.7 6565.9 6588.8 6614.9 6245.4 6365.9 6442.4 6447.1 6536.1 6526.2 6557.3 6582.0 6598.4 6636.7 6339.7 6340.0 6353.3 6363.2 6441.5 21:19:54 INFO - PID 15952 | 21:19:54 INFO - PID 15952 | [#1] ext.html Cycles:180 Average:36300.05 Median:18823.54 stddev:52723.70 (280.1%) stddev-sans-first:52804.31 21:19:54 INFO - PID 15952 | Values: 823.5 824.7 836.6 837.3 838.0 38067.2 41808.8 41899.2 41924.4 41930.8 37078.8 37127.0 37166.8 37329.0 37457.8 6928.2 7037.0 7040.4 7042.3 7057.6 6884.9 6974.2 6997.2 6999.7 7002.0 6884.1 6961.5 6967.3 6987.3 7013.6 6948.6 6979.8 6980.2 6982.6 6990.1 6256.9 6259.0 6293.0 6309.1 6313.6 34225.1 34422.8 34527.1 34585.3 34634.0 3407.4 3421.9 3432.2 3441.6 3444.3 28107.4 28110.9 28164.9 28221.3 28222.7 4481.3 4502.8 4526.3 4552.2 4595.0 192517.0 192984.0 193631.0 193986.0 194575.0 19266.3 19465.3 19530.9 40616.8 41157.4 42903.8 50868.7 51483.5 51669.5 51683.7 15135.3 15168.8 15173.4 15289.5 15301.0 12337.4 12381.5 12384.9 12385.9 12387.6 12952.7 13062.8 13105.3 13105.7 13188.1 5386.6 5443.5 5484.4 5503.6 5609.2 995.9 1017.0 1032.5 1033.8 1039.4 22950.8 23226.9 23406.6 23421.0 23464.2 1130.1 1133.2 1133.6 1137.8 1143.5 12114.4 12307.0 12316.0 12410.8 12467.4 215006.0 215399.8 215529.0 215710.0 216009.0 51449.7 51599.7 51626.7 51674.7 51710.5 1429.2 1451.8 1469.6 1474.0 1474.2 21756.9 22379.6 22486.7 22508.7 22885.8 36879.4 36939.6 36958.6 36963.3 37018.8 198908.0 200239.0 200704.0 200773.0 200904.0 34148.3 34173.1 34491.1 34934.7 34960.8 42894.4 44671.6 44722.3 44798.8 45024.0 33068.5 33192.2 33602.8 33719.2 34052.7 32669.0 32786.7 32824.5 33000.8 33231.8 35870.6 36862.6 36945.6 37031.4 37107.4 32480.5 32558.8 32830.5 32851.8 32961.3 23954.1 24088.9 24619.9 24630.0 24854.6 21:19:54 INFO - PID 15952 | 21:19:54 INFO - PID 15952 | [#2] jquery.html Cycles:180 Average:33565.59 Median:23403.74 stddev:77728.55 (332.1%) stddev-sans-first:77907.03 21:19:54 INFO - PID 15952 | Values: 533.5 543.8 552.2 573.2 574.5 22203.4 22915.4 22994.2 22995.9 23091.8 23360.2 23769.6 24032.9 24051.8 25108.7 6565.3 6787.6 6807.3 6817.4 6824.5 6643.8 6771.5 6776.5 6783.6 6788.1 6291.8 6549.9 6554.2 6558.1 6712.5 6544.5 6581.4 6582.7 6583.0 6588.4 5905.3 6070.8 6079.3 6091.5 6120.3 28952.6 29423.4 29460.4 29469.6 29486.9 2031.8 2046.7 2055.7 2060.6 2066.3 18952.8 19133.1 19555.8 19576.1 19717.0 2422.4 2499.9 2514.3 2526.9 2529.7 118188.4 124657.0 135676.3 136217.0 141581.0 13901.0 14218.2 14282.4 14691.3 14785.9 23151.9 23193.5 23202.2 23212.3 23225.7 9413.7 9646.5 9728.9 9801.5 9824.0 8942.3 8949.0 8986.6 9016.1 9017.8 8883.7 8957.4 8989.0 9118.5 9349.0 2533.8 2624.7 2654.5 2671.1 2761.3 953.7 962.6 963.7 965.1 976.7 19476.5 19534.4 19645.5 19959.6 19977.7 1020.9 1026.7 1031.6 1044.2 1052.7 10495.9 11028.3 11099.6 11115.4 11236.9 452879.0 456569.0 460767.0 461913.0 471781.0 112018.0 113038.4 113200.0 116173.4 116968.4 1021.1 1132.3 1135.2 1187.6 1195.1 20354.7 20457.3 20772.2 21200.5 21214.2 52288.7 52300.7 52698.0 53119.2 53180.7 64853.7 66805.3 67301.0 67384.3 68585.3 14329.2 14604.9 14675.3 14686.6 14738.9 23447.3 23686.7 23872.0 23916.0 24198.8 18433.7 18445.2 18451.5 18453.0 18766.0 16340.3 16893.2 17267.4 17357.9 17495.2 18170.8 19280.0 19396.7 19584.0 19588.0 14382.2 14433.1 14579.9 14600.9 14622.1 18374.9 18508.2 18574.8 18668.8 18704.3 21:19:54 INFO - PID 15952 | 21:19:54 INFO - PID 15952 | [#3] mootools.html Cycles:180 Average:9216.53 Median:26086.74 stddev:6627.96 (25.4%) stddev-sans-first:6620.05 21:19:54 INFO - PID 15952 | Values: 1328.3 1371.3 1388.5 1391.0 1407.0 14868.7 15487.8 15500.6 15504.1 15511.3 15127.8 15155.5 15155.8 15215.3 15249.5 2669.4 2700.9 2750.2 2757.0 2760.8 2663.5 2713.8 2717.4 2723.1 2723.3 2660.5 2746.2 2763.8 2767.4 2767.6 2549.8 2563.1 2586.4 2589.0 2589.4 2616.1 2639.1 2658.6 2673.4 2680.6 14904.3 15070.5 15164.8 15226.7 15272.2 8019.4 8288.3 8290.8 8299.9 8328.1 5312.1 5501.4 5538.3 5659.6 5697.2 1898.3 1916.5 1917.7 1953.7 1960.4 25882.5 25961.2 26016.0 26050.7 26122.7 6692.6 6754.4 6863.0 6977.7 6996.2 16979.5 17013.1 17021.2 17105.3 17120.0 7479.6 7633.5 7651.2 7882.9 8047.0 6640.3 6749.4 6790.2 6796.4 6825.3 5193.5 5307.8 5349.4 5354.3 5362.5 1368.5 1488.4 1514.7 1516.1 1518.7 700.6 862.7 868.9 874.7 885.4 8545.5 8610.8 8623.6 8694.4 8749.5 984.6 996.7 999.8 1002.2 1023.0 5109.5 5113.9 5132.2 5160.8 5191.8 20250.9 21033.2 21607.5 21961.8 22029.6 21511.8 21556.0 21570.2 21620.5 21680.7 10076.9 11292.4 11297.7 11325.1 11533.5 7828.7 8220.4 8281.9 8340.3 8382.8 18210.4 18626.5 18858.8 19115.4 19215.8 18776.2 18846.3 18958.7 18982.3 19050.6 10730.4 10967.5 10987.8 10990.0 10998.5 12494.9 12584.9 12636.1 12642.3 12708.1 11514.1 11685.5 11771.1 11827.5 11866.0 11232.8 11286.0 11344.5 11489.5 11641.9 9219.4 9364.5 9367.7 9474.5 9481.1 5976.8 6040.1 6058.7 6080.8 6199.8 6559.3 6596.0 6646.8 6653.0 6744.3 21:19:54 INFO - PID 15952 | 21:19:54 INFO - PID 15952 | [#4] prototype.html Cycles:180 Average:5442.60 Median:6085.22 stddev:1514.49 (24.9%) stddev-sans-first:1472.59 21:19:54 INFO - PID 15952 | Values: 499.3 502.0 526.3 527.6 529.5 6031.4 6150.3 6177.4 6212.8 6306.6 6121.3 6151.2 6156.9 6190.7 6199.6 5117.8 5134.6 5138.8 5150.3 5165.1 4871.7 5122.5 5138.1 5153.3 5161.5 4948.1 4952.1 4967.4 4976.6 4988.1 4866.6 4895.4 4956.3 4957.5 4994.9 4413.5 4419.5 4427.1 4429.8 4434.9 6017.5 6085.2 6087.3 6165.9 6179.8 5676.6 5690.0 5703.9 5710.6 5728.1 6385.2 6402.1 6461.7 6480.9 6510.8 4807.7 4813.6 4832.4 4844.1 4844.5 6768.2 6874.5 6889.2 6906.4 6925.0 4918.5 4946.5 4993.2 5010.8 5015.5 5003.9 5006.7 5009.1 5025.2 5040.4 5624.2 5642.6 5655.6 5683.7 5685.0 6505.3 6588.0 6607.4 6615.4 6639.2 1637.8 1648.6 1677.6 1686.4 1727.4 1202.6 1221.6 1222.7 1226.0 1227.9 5673.7 5727.8 5729.9 5744.5 5748.8 6537.3 6557.6 6560.7 6561.3 6574.0 5624.2 5660.5 5704.5 5706.2 5740.6 3474.7 3499.9 3527.1 3532.3 3547.5 6409.4 6470.8 6513.5 6524.4 6527.1 6742.8 6760.7 6810.4 6821.9 6832.4 6427.6 6505.7 6509.6 6513.2 6527.9 6382.6 6487.7 6533.8 6549.4 6587.9 6471.9 6484.2 6506.3 6520.3 6587.3 6399.9 6439.0 6444.8 6458.9 6484.7 4873.5 4915.5 4928.2 4945.9 4956.9 6502.5 6514.8 6524.2 6619.8 6654.3 6211.5 6310.6 6318.1 6384.2 6385.7 6171.0 6286.6 6288.0 6293.0 6333.9 5649.1 6088.5 6130.5 6235.7 6292.6 6357.2 6432.7 6440.9 6451.4 6454.2 6047.0 6076.2 6085.3 6111.9 6176.8 21:19:54 INFO - PID 15952 | 21:19:54 INFO - PID 15952 | [#5] yui.html Cycles:180 Average:24071.95 Median:20135.30 stddev:35337.51 (175.5%) stddev-sans-first:35393.85 21:19:54 INFO - PID 15952 | Values: 909.4 912.9 942.4 943.4 944.9 18823.2 19663.7 19668.6 19696.4 19787.5 16130.5 16454.8 16490.8 18341.4 18364.6 913.2 930.2 931.2 932.2 932.9 972.8 973.3 978.1 983.8 984.2 992.2 1014.1 1020.5 1021.7 1022.4 996.6 996.7 1007.2 1015.5 1023.8 748.5 748.8 749.9 750.4 752.3 19960.2 20092.3 20178.3 20193.9 20201.5 21369.7 21570.7 21732.0 21847.6 21874.6 16075.2 16274.8 16926.4 16958.1 17161.0 3664.2 3700.7 3702.3 3705.0 3707.7 135319.3 136466.3 137090.3 137845.0 138265.3 16740.9 16807.0 16915.4 16935.0 17000.2 31330.7 31399.5 31553.5 31904.7 32210.2 8261.3 8287.3 8316.9 8346.5 8388.0 3988.3 4009.0 4091.5 4124.1 4124.4 5353.3 5488.1 5489.0 5490.1 5493.6 968.4 995.2 997.4 1002.7 1007.3 682.1 701.0 712.5 712.8 719.6 11271.6 11353.1 11548.5 12076.1 12170.2 679.4 692.5 705.7 707.2 709.1 5098.4 5218.3 5281.7 5354.1 5395.3 116349.0 123267.4 124066.0 124231.0 124652.0 108460.0 109885.0 111398.4 112149.0 112739.4 91967.5 92871.0 96980.5 97755.0 99130.0 10000.4 10040.2 10065.0 10272.5 10283.8 42545.4 43424.0 44173.0 45262.0 45385.1 57226.1 58368.7 58568.0 59340.4 59504.4 15550.5 15641.3 15696.1 15728.4 15730.9 17107.4 17225.7 17251.3 17252.7 17322.3 14333.0 14478.1 14628.5 14820.3 14827.0 14300.4 14382.6 14393.4 14447.0 14692.4 13648.9 13673.7 13822.5 13979.2 13990.8 10680.6 10880.4 11054.7 11125.2 11183.0 10434.2 10453.1 10464.2 10534.5 10697.7 21:19:54 INFO - PID 15952 | -------- Summary: end -------- 21:19:54 INFO - PID 15952 | 21:19:55 INFO - TEST-INFO | 15952: exit 0 21:19:55 INFO - TEST-OK | dromaeo_css | took 1108878ms 21:19:55 INFO - TEST-START | kraken 21:19:55 INFO - Initialising browser for kraken test... 21:19:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54159/getInfo.html -profile /tmp/tmpRzVwNf/profile 21:19:55 INFO - TEST-INFO | started process 16327 (/builds/slave/test/build/application/firefox/firefox http://localhost:54159/getInfo.html) 21:19:59 INFO - TEST-INFO | 16327: exit 0 21:20:00 INFO - Browser initialized. 21:20:00 INFO - Running cycle 1/1 for kraken test... 21:20:00 INFO - Using env: {'DISPLAY': ':0', 21:20:00 INFO - 'HOME': '/home/cltbld', 21:20:00 INFO - 'JSGC_DISABLE_POISONING': '1', 21:20:00 INFO - 'LANG': 'en_US.UTF-8', 21:20:00 INFO - 'LANGUAGE': 'en_US:en', 21:20:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:20:00 INFO - 'LOGNAME': 'cltbld', 21:20:00 INFO - 'MAIL': '/var/mail/cltbld', 21:20:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:20:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:20:00 INFO - 'MOZ_CRASHREPORTER': '1', 21:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:20:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:20:00 INFO - 'MOZ_NO_REMOTE': '1', 21:20:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:20:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:20:00 INFO - 'NO_EM_RESTART': '1', 21:20:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:20:00 INFO - 'PWD': '/builds/slave/test', 21:20:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:20:00 INFO - 'RUST_BACKTRACE': 'full', 21:20:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:20:00 INFO - 'SHELL': '/bin/bash', 21:20:00 INFO - 'SHLVL': '1', 21:20:00 INFO - 'TERM': 'linux', 21:20:00 INFO - 'TMOUT': '86400', 21:20:00 INFO - 'USER': 'cltbld', 21:20:00 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252', 21:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:20:00 INFO - '_': '/tools/buildbot/bin/python'} 21:20:00 INFO - TEST-INFO | started process 16559 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRzVwNf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:20:00 INFO - PID 16559 | 21:20:00 INFO - PID 16559 | (/builds/slave/test/build/application/firefox/firefox:16609): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:20:00 INFO - PID 16559 | 21:20:11 INFO - PID 16559 | RSS: Main: 156495872 21:20:11 INFO - PID 16559 | 21:20:13 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54159/tests/kraken/driver.html?testName=audio-beat-detection) 21:20:14 INFO - PID 16559 | RSS: Main: 161632256 21:20:14 INFO - PID 16559 | 21:20:17 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54159/tests/kraken/driver.html?testName=audio-dft) 21:20:18 INFO - PID 16559 | RSS: Main: 161804288 21:20:18 INFO - PID 16559 | 21:20:21 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54159/tests/kraken/driver.html?testName=audio-fft) 21:20:21 INFO - PID 16559 | RSS: Main: 158527488 21:20:21 INFO - PID 16559 | 21:20:23 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54159/tests/kraken/driver.html?testName=audio-oscillator) 21:20:24 INFO - PID 16559 | RSS: Main: 159088640 21:20:24 INFO - PID 16559 | 21:20:26 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54159/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:20:27 INFO - PID 16559 | RSS: Main: 159469568 21:20:27 INFO - PID 16559 | 21:20:30 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54159/tests/kraken/driver.html?testName=imaging-darkroom) 21:20:31 INFO - PID 16559 | RSS: Main: 161685504 21:20:31 INFO - PID 16559 | 21:20:34 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54159/tests/kraken/driver.html?testName=imaging-desaturate) 21:20:35 INFO - PID 16559 | RSS: Main: 161550336 21:20:35 INFO - PID 16559 | 21:20:38 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54159/tests/kraken/driver.html?testName=json-parse-financial) 21:20:38 INFO - PID 16559 | RSS: Main: 161980416 21:20:38 INFO - PID 16559 | 21:20:40 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54159/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:20:41 INFO - PID 16559 | RSS: Main: 161521664 21:20:41 INFO - PID 16559 | 21:20:42 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54159/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:20:43 INFO - PID 16559 | RSS: Main: 162144256 21:20:43 INFO - PID 16559 | 21:20:45 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54159/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:20:46 INFO - PID 16559 | RSS: Main: 159027200 21:20:46 INFO - PID 16559 | 21:20:48 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54159/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:20:49 INFO - PID 16559 | RSS: Main: 159498240 21:20:49 INFO - PID 16559 | 21:20:52 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54159/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:20:52 INFO - PID 16559 | RSS: Main: 159916032 21:20:52 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | Cycle 1(1): loaded http://localhost:54159/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54159/tests/kraken/driver.html?testName=ai-astar) 21:20:54 INFO - PID 16559 | RSS: Main: 161189888 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | __start_tp_report 21:20:54 INFO - PID 16559 | _x_x_mozilla_page_load 21:20:54 INFO - PID 16559 | _x_x_mozilla_page_load_details 21:20:54 INFO - PID 16559 | |i|pagename|runs| 21:20:54 INFO - PID 16559 | |0;ai-astar;107;108;105;107;108;110;108;106;108;106 21:20:54 INFO - PID 16559 | |1;audio-beat-detection;122;118;115;117;119;117;120;117;115;147 21:20:54 INFO - PID 16559 | |2;audio-dft;164;162;162;162;161;162;163;172;166;164 21:20:54 INFO - PID 16559 | |3;audio-fft;73;74;86;74;75;73;80;73;76;75 21:20:54 INFO - PID 16559 | |4;audio-oscillator;79;81;80;78;79;80;80;79;79;79 21:20:54 INFO - PID 16559 | |5;imaging-gaussian-blur;113;113;113;114;114;114;114;114;114;114 21:20:54 INFO - PID 16559 | |6;imaging-darkroom;152;147;148;147;149;148;151;149;148;147 21:20:54 INFO - PID 16559 | |7;imaging-desaturate;94;94;94;95;95;94;94;95;95;95 21:20:54 INFO - PID 16559 | |8;json-parse-financial;59;54;54;55;55;57;55;55;55;56 21:20:54 INFO - PID 16559 | |9;json-stringify-tinderbox;37;37;37;37;37;38;37;37;37;37 21:20:54 INFO - PID 16559 | |10;stanford-crypto-aes;74;73;76;74;74;77;74;76;79;77 21:20:54 INFO - PID 16559 | |11;stanford-crypto-ccm;115;114;117;118;115;122;114;110;113;113 21:20:54 INFO - PID 16559 | |12;stanford-crypto-pbkdf2;172;168;174;168;177;167;169;172;173;165 21:20:54 INFO - PID 16559 | |13;stanford-crypto-sha256-iterative;66;67;66;67;69;62;64;69;67;64 21:20:54 INFO - PID 16559 | __end_tp_report 21:20:54 INFO - PID 16559 | __start_cc_report 21:20:54 INFO - PID 16559 | _x_x_mozilla_cycle_collect,764 21:20:54 INFO - PID 16559 | __end_cc_report 21:20:54 INFO - PID 16559 | __startTimestamp1505622054871__endTimestamp 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | ------- Summary: start ------- 21:20:54 INFO - PID 16559 | Number of tests: 14 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#0] ai-astar Cycles:10 Average:107.30 Median:108.00 stddev:1.42 (1.3%) stddev-sans-first:1.50 21:20:54 INFO - PID 16559 | Values: 107.0 108.0 105.0 107.0 108.0 110.0 108.0 106.0 108.0 106.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:118.50 stddev:9.49 (8.0%) stddev-sans-first:10.05 21:20:54 INFO - PID 16559 | Values: 122.0 118.0 115.0 117.0 119.0 117.0 120.0 117.0 115.0 147.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.50 stddev:3.22 (2.0%) stddev-sans-first:3.42 21:20:54 INFO - PID 16559 | Values: 164.0 162.0 162.0 162.0 161.0 162.0 163.0 172.0 166.0 164.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#3] audio-fft Cycles:10 Average:75.90 Median:75.00 stddev:4.12 (5.5%) stddev-sans-first:4.24 21:20:54 INFO - PID 16559 | Values: 73.0 74.0 86.0 74.0 75.0 73.0 80.0 73.0 76.0 75.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#4] audio-oscillator Cycles:10 Average:79.40 Median:79.50 stddev:0.84 (1.1%) stddev-sans-first:0.88 21:20:54 INFO - PID 16559 | Values: 79.0 81.0 80.0 78.0 79.0 80.0 80.0 79.0 79.0 79.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 21:20:54 INFO - PID 16559 | Values: 113.0 113.0 113.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#6] imaging-darkroom Cycles:10 Average:148.60 Median:148.50 stddev:1.71 (1.2%) stddev-sans-first:1.30 21:20:54 INFO - PID 16559 | Values: 152.0 147.0 148.0 147.0 149.0 148.0 151.0 149.0 148.0 147.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 21:20:54 INFO - PID 16559 | Values: 94.0 94.0 94.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.00 stddev:1.51 (2.7%) stddev-sans-first:0.93 21:20:54 INFO - PID 16559 | Values: 59.0 54.0 54.0 55.0 55.0 57.0 55.0 55.0 55.0 56.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#9] json-stringify-tinderbox Cycles:10 Average:37.10 Median:37.00 stddev:0.32 (0.9%) stddev-sans-first:0.33 21:20:54 INFO - PID 16559 | Values: 37.0 37.0 37.0 37.0 37.0 38.0 37.0 37.0 37.0 37.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:76.00 stddev:1.90 (2.5%) stddev-sans-first:1.94 21:20:54 INFO - PID 16559 | Values: 74.0 73.0 76.0 74.0 74.0 77.0 74.0 76.0 79.0 77.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#11] stanford-crypto-ccm Cycles:10 Average:115.10 Median:115.00 stddev:3.28 (2.9%) stddev-sans-first:3.48 21:20:54 INFO - PID 16559 | Values: 115.0 114.0 117.0 118.0 115.0 122.0 114.0 110.0 113.0 113.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:172.00 stddev:3.69 (2.1%) stddev-sans-first:3.87 21:20:54 INFO - PID 16559 | Values: 172.0 168.0 174.0 168.0 177.0 167.0 169.0 172.0 173.0 165.0 21:20:54 INFO - PID 16559 | 21:20:54 INFO - PID 16559 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:67.00 stddev:2.23 (3.3%) stddev-sans-first:2.37 21:20:54 INFO - PID 16559 | Values: 66.0 67.0 66.0 67.0 69.0 62.0 64.0 69.0 67.0 64.0 21:20:54 INFO - PID 16559 | -------- Summary: end -------- 21:20:54 INFO - PID 16559 | 21:20:55 INFO - PID 16559 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:20:55 INFO - TEST-INFO | 16559: exit 0 21:20:55 INFO - TEST-OK | kraken | took 60245ms 21:20:55 INFO - SUITE-END | took 1169s 21:20:56 INFO - Completed test suite (00:19:29) 21:20:56 INFO - javascript benchmark 21:20:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8362.702997118604, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3145.4809956357203, 3168.4930411134224, 3191.0, 3193.9619623622316, 3195.4567723487003, 6212.65830379329, 6273.5294852886045, 6277.843053923654, 6334.208223972004, 6378.840528986778, 6281.08924205991, 6347.2700639426475, 6385.233771947361, 6386.329435409285, 6391.808245752623, 5750.02249617566, 5766.711664416784, 5768.942310576904, 5864.208331875185, 5916.08300713388, 5692.117721753135, 5724.169995350671, 5742.196092547047, 5792.478676919077, 5806.941930580682, 5746.425357464262, 5752.36723960364, 5763.4812866842185, 5767.538596912237, 5778.479936805666, 5665.3768085510565, 5758.942410575883, 5760.481556659921, 5769.682667453289, 5779.393163717833, 5684.488396044376, 5701.942980570183, 5754.482096611325, 5763.942360576382, 5775.335836378828, 6094.146819445237, 6220.377962203788, 6290.905636415458, 6308.968455157695, 6364.554481186319, 6605.801825945229, 6611.570247933869, 6623.801285961429, 6630.038644396535, 6637.1703537057865, 6557.5081868860025, 6704.899426508607, 6713.462922966151, 6713.865722685568, 6718.529702920798, 6193.566450348478, 6275.780347687854, 6314.5895516791725, 6320.873582528324, 6329.05064240363, 6889.379955803902, 6909.0, 6918.238993710688, 6967.651617419108, 6988.056612357368, 6586.37429444202, 6590.143281373391, 6662.6668666566475, 6676.933230667728, 6706.463482921355, 6523.804285871432, 6640.634765087966, 6661.433778128817, 6664.700088496029, 6666.600003999775, 6587.0, 6609.537332386687, 6631.535792494479, 6667.33326667339, 6670.433013193933, 6714.429273511806, 6741.0899528564, 6800.727970881208, 6801.897971530431, 6808.795736127924, 5260.868478288074, 5300.1254792958725, 5335.599830012765, 5342.492463215988, 5349.652272602346, 4494.977525112419, 4569.109023740339, 4628.3288923105965, 4650.325702773079, 4668.929966050512, 5868.972929737279, 5881.558883083786, 5883.558733095035, 5884.617499862496, 5893.0, 6768.492363072789, 6824.829379265558, 6846.8972965405555, 6854.725810967605, 6873.312668733187, 5839.416058394127, 5856.648601083949, 5928.940710592925, 5931.466168044896, 5938.28740551128, 5636.859078522988, 5746.827595172152, 5778.27771528559, 5790.160426738184, 5867.589268751147, 6507.34926507355, 6510.9023364649565, 6525.64108974011, 6531.314212007671, 6594.373534514213, 6713.932860671331, 6734.629595372302, 6769.661516924232, 6778.559393639398, 6796.864062718817, 6544.44372228366, 6544.574602650812, 6559.344065593403, 6649.368310010541, 6650.102236198051, 6386.425221730066, 6566.9014964774615, 6636.568623039487, 6648.1357423536565, 6683.298253683293, 6642.468602511691, 6656.201255849329, 6731.596104233761, 6736.360045795642, 6736.898946515708, 6505.447037001908, 6538.967305163444, 6556.508261880474, 6588.07766912623, 6602.735890564323, 6517.771877984248, 6553.705083271359, 6565.934340656532, 6588.8352791180605, 6614.867702646016, 6245.375462453719, 6365.936340636535, 6442.405708460791, 6447.097406363207, 6536.0522724205675, 6526.24948130971, 6557.278699343164, 6581.967090164519, 6598.439132673777, 6636.701348439428, 6339.714712837841, 6340.0489926509545, 6353.3011368748475, 6363.204599425072, 6441.5490915635455], "value": 6128.07141171981, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.5264722784399, 824.678375433581, 836.5608055770723, 837.317586167274, 837.9832403351933, 38067.238655226916, 41808.79095604522, 41899.162016759685, 41924.37113443301, 41930.79034604823, 37078.814605927, 37127.0, 37166.814165929136, 37329.0, 37457.81271093641, 6928.237893831674, 7037.0499982502215, 7040.436765058808, 7042.295770422942, 7057.576545407266, 6884.931150688479, 6974.197967233757, 6997.195322537897, 6999.720011199546, 7002.0, 6884.0706504621985, 6961.547499412547, 6967.303269673018, 6987.301269873023, 7013.579185248901, 6948.5830850148905, 6979.790606281819, 6980.232174460831, 6982.615956122411, 6990.056342393763, 6256.906146407808, 6259.029850373154, 6292.968535157295, 6309.116723658692, 6313.558050936436, 34225.14437139066, 34422.82788586066, 34527.136821579414, 34585.30829383398, 34634.0, 3407.4037043517533, 3421.9315613687836, 3432.2277487565348, 3441.5525981622477, 3444.293919746471, 28107.437851243067, 28110.859445702845, 28164.859175704194, 28221.294467638472, 28222.71777282242, 4481.260592002316, 4502.774861256957, 4526.275795872678, 4552.203364411248, 4595.03504264105, 192517.03741481344, 192984.0, 193631.0, 193986.0, 194575.0271248649, 19266.32567860146, 19465.318713844947, 19530.902345488183, 40616.79691601523, 41157.38263926043, 42903.785481072395, 50868.74565627222, 51483.48516514787, 51669.48330516722, 51683.741581291855, 15135.318910649048, 15168.772468412983, 15173.393064277305, 15289.464868729541, 15301.0, 12337.383130843422, 12381.504739810487, 12384.938075309747, 12385.93807030959, 12387.62837114888, 12952.676183095498, 13062.804057939138, 13105.344732763515, 13105.737885242243, 13188.076834621485, 5386.569074474032, 5443.537299329522, 5484.369297530835, 5503.614746967673, 5609.186667933209, 995.9253056020827, 1016.9847452288221, 1032.5198782566142, 1033.7570670892335, 1039.4231201683137, 22950.770492294865, 23226.883865580563, 23406.64890026651, 23421.0, 23464.17875374388, 1130.1015692524463, 1133.2237417369129, 1133.5749094089717, 1137.7781332640056, 1143.54258296682, 12114.394280285951, 12306.953908917663, 12316.014718822473, 12410.81383779244, 12467.438965246585, 215006.0, 215399.84600154113, 215529.0, 215710.0, 216009.0, 51449.742751286, 51599.74200128976, 51626.74186629043, 51674.74162629163, 51710.482895170564, 1429.171080773149, 1451.8112645356036, 1469.559132260339, 1474.0345073976562, 1474.167095591009, 21756.89121554382, 22379.552408952055, 22486.662700059183, 22508.662370064136, 22885.771142288362, 36879.44680829736, 36939.63060369362, 36958.63041369552, 36963.26073478461, 37018.8149059253, 198908.0, 200239.0, 200704.0, 200773.0, 200904.0, 34148.31703365968, 34173.14567135742, 34491.13772155716, 34934.65065349314, 34960.825195873855, 42894.35658465063, 44671.55328446674, 44722.3291650632, 44798.776006121065, 45024.0, 33068.503972439954, 33192.1701957453, 33602.83198583991, 33719.15702107467, 34052.65947340495, 32669.0, 32786.67213327836, 32824.50763238506, 33000.834995824865, 33231.833840830644, 35870.641293587774, 36862.63137368699, 36945.63054369422, 37031.44452833156, 37107.443388348656, 32480.51279230766, 32558.83720581382, 32830.507542387364, 32851.835740821145, 32961.34077318488, 23954.104941507572, 24088.87955560211, 24619.87690061538, 24630.0, 24854.627180591942], "value": 16334.593118570121, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [533.485186794743, 543.8178210299554, 552.215853488047, 573.2175580332847, 574.46861652971, 22203.44491387716, 22915.42711432213, 22994.195203167892, 22995.885020574893, 23091.769082309173, 23360.182393616225, 23769.643455348185, 24032.87983560084, 24051.75948240517, 25108.74891251087, 6565.343465653445, 6787.592744435349, 6807.319268073203, 6817.386435220829, 6824.454043676519, 6643.800685979429, 6771.525993180479, 6776.457883369319, 6783.592984420926, 6788.08360871281, 6291.842703932392, 6549.869002619945, 6554.180727409074, 6558.1474408327085, 6712.4965627577885, 6544.509161812859, 6581.407673309377, 6582.670866456706, 6582.9670851645915, 6588.407043366096, 5905.261842269716, 6070.757169713206, 6079.300880398767, 6091.5431342649035, 6120.296165940885, 28952.565711514344, 29423.41153176946, 29460.410791784045, 29469.557956630666, 29486.852565737034, 2031.7968203179564, 2046.7339245898088, 2055.6710926251876, 2060.6084843879617, 2066.3077868913933, 18952.81047189524, 19133.13900874464, 19555.80444195554, 19576.119074641676, 19717.0, 2422.39440139965, 2499.9375015624573, 2514.3085651446, 2526.924192274235, 2529.709083455408, 118188.40905795417, 124657.0, 135676.32161839228, 136217.0, 141581.0, 13901.0, 14218.217998010208, 14282.35729392159, 14691.265436728121, 14785.926070369795, 23151.88424057869, 23193.53612927732, 23202.1879234226, 23212.3036308911, 23225.651615225786, 9413.67052153185, 9646.469444180637, 9728.854067188999, 9801.460919649488, 9823.950880245697, 8942.284617230605, 8948.955255223813, 8986.55067246635, 9016.09839016093, 9017.774555636162, 8883.73348799537, 8957.372983891066, 8989.0, 9118.544072796334, 9349.0, 2533.8479691218586, 2624.73752624736, 2654.495645827287, 2671.1185308848035, 2761.2782142536084, 953.7043516509904, 962.6341990043738, 963.710886733977, 965.0783501755807, 976.7216343342113, 19476.51308717265, 19534.413967580997, 19645.50886227856, 19959.60080798376, 19977.700334494995, 1020.8519764634086, 1026.7381817636456, 1031.5564307347763, 1044.195969103801, 1052.6999805055523, 10495.895041049493, 11028.338299702044, 11099.556017759362, 11115.388653623966, 11236.943815281034, 452879.0, 456569.0, 460767.0, 461913.0, 471781.0, 112018.0, 113038.43480782544, 113200.0, 116173.4191329038, 116968.41515792366, 1021.1065317846884, 1132.314949455584, 1135.2450620337383, 1187.619961612293, 1195.0618764270007, 20354.694679579523, 20457.2839950601, 20772.1691132353, 21200.469988250417, 21214.15143394247, 52288.7385563085, 52300.73849630728, 52698.0, 53119.20321195108, 53180.73409632927, 64853.67573162293, 66805.33194667991, 67301.0, 67384.32615673977, 68585.31414685989, 14329.21189334555, 14604.853951460775, 14675.339609717801, 14686.63283417923, 14738.852611474176, 23447.296581102593, 23686.697231651735, 23872.0, 23916.0, 24198.75801241965, 18433.723494147867, 18445.16996735177, 18451.538711532317, 18452.985085820408, 18766.0, 16340.264688088826, 16893.155342232592, 17267.3956411525, 17357.913210433868, 17495.21271542809, 18170.81829181691, 19280.0, 19396.709049363988, 19584.02079896028, 19588.0, 14382.208978506282, 14433.134011959317, 14579.92710036443, 14600.926995364955, 14622.12267263925, 18374.908125459286, 18508.1671324788, 18574.814251857308, 18668.813311867252, 18704.345347912757], "value": 11900.498512105298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.3291937571526, 1371.2800779590714, 1388.548721665459, 1390.9513167039156, 1406.9929650351753, 14868.702625947488, 15487.767683484743, 15500.612484687874, 15504.147271900069, 15511.301991410386, 15127.84872151278, 15155.54533363998, 15155.848441515582, 15215.315310811013, 15249.542513724606, 2669.3726974161054, 2700.9459810803774, 2750.2436829871745, 2756.9724302756917, 2760.7653349465318, 2663.5205662980657, 2713.81003329767, 2717.442924200544, 2723.0741547873718, 2723.251105945861, 2660.5344064788583, 2746.217328061503, 2763.8065335426527, 2767.3635063935308, 2767.5571908494644, 2549.8470091794366, 2563.115725074846, 2586.379268975451, 2589.0420544398694, 2589.3785491482004, 2616.1105224223666, 2639.1027050802627, 2658.587918872578, 2673.3717576369536, 2680.584509401046, 14904.254787260594, 15070.472533461387, 15164.848351516453, 15226.695466090727, 15272.16003119828, 8019.398545109082, 8288.254057134887, 8290.751277461686, 8299.875501867476, 8328.08391076981, 5312.096943519611, 5501.367342755594, 5538.252335934638, 5659.60382773206, 5697.202391665171, 1898.250191174492, 1916.5400303926958, 1917.731517587539, 1953.677643188872, 1960.3824795189469, 25882.482350352886, 25961.221163365128, 26016.0, 26050.73949260521, 26122.73877261222, 6692.631905245258, 6754.3921047106, 6862.965685171542, 6977.651117444209, 6996.230414654384, 16979.49061528156, 17013.149342532823, 17021.23404446778, 17105.315787368614, 17120.0, 7479.588622625808, 7633.503822251647, 7651.1583725790115, 7882.881756773653, 8046.959765201136, 6640.302768209269, 6749.392554670103, 6790.185177778698, 6796.422304104207, 6825.283345248777, 5193.454687257784, 5307.761150748225, 5349.438308977579, 5354.330708661418, 5362.490563396471, 1368.4868174434587, 1488.4269556220934, 1514.6592016796133, 1516.1282262698992, 1518.7494063479403, 700.5936556797045, 862.719616124762, 868.8653258744905, 874.711345256064, 885.4333226734894, 8545.529995850287, 8610.827783444422, 8623.568821559022, 8694.391392602458, 8749.518776467228, 984.6061575369766, 996.7109538233976, 999.8350272205005, 1002.2382988928321, 1023.0434543701663, 5109.514596113363, 5113.948860511347, 5132.178851383761, 5160.845174644766, 5191.818286360035, 20250.89874550618, 21033.15867365288, 21607.45981350479, 21961.78038219629, 22029.559408811732, 21511.78488215098, 21556.0, 21570.245041423474, 21620.45948851291, 21680.674789878165, 10076.899231007743, 11292.435378231055, 11297.661070167907, 11325.093992480583, 11533.538658453735, 7828.725994590162, 8220.424570280262, 8281.917180828355, 8340.332773377993, 8382.784496248132, 18210.36263730763, 18626.534336641693, 18858.811411885705, 19115.426537203904, 19215.807841921403, 18776.15507302202, 18846.3403780867, 18958.715619265997, 18982.335618253295, 19050.61898761989, 10730.409827459565, 10967.451627418437, 10987.835182472429, 10990.0, 10998.450077496253, 12494.937525312314, 12584.937075314565, 12636.115471917245, 12642.30467324306, 12708.110432269652, 11514.078873690252, 11685.532578696757, 11771.11716621274, 11827.467763950815, 11866.0, 11232.831507527557, 11286.0, 11344.489497972447, 11489.54041838317, 11641.941790290994, 9219.44683319003, 9364.484953327632, 9367.718968430958, 9474.52627368615, 9481.051894810464, 5976.790812321722, 6040.063790112539, 6058.697065146813, 6080.847978800565, 6199.814005579839, 6559.311272316529, 6595.967020165061, 6646.83382915431, 6653.035309880137, 6744.270607631869], "value": 6582.886164587191, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.2973958800491, 501.99749001254986, 526.3026489900882, 527.6280222443178, 529.4573062610823, 6031.366706495815, 6150.323464418911, 6177.351378105302, 6212.78255261066, 6306.59007164534, 6121.306625084971, 6151.216147317273, 6156.907646385308, 6190.659513726743, 6199.597026193294, 5117.795288188468, 5134.589232861381, 5138.820141295056, 5150.2532132840715, 5165.121929272023, 4871.7076975381415, 5122.462141475147, 5138.126518491847, 5153.252778347138, 5161.509656582619, 4948.134076536592, 4952.133376659088, 4967.4287456942375, 4976.601871850261, 4988.127077761395, 4866.635002374836, 4895.388076490439, 4956.330895329121, 4957.529034741694, 4994.925076123861, 4413.492448368414, 4419.469663640384, 4427.114577084564, 4429.756363400012, 4434.889127771799, 6017.48851347636, 6085.178500902366, 6087.269527656709, 6165.876682466371, 6179.81460556184, 5676.602637815355, 5690.0, 5703.942960570383, 5710.600257981943, 5728.112142617901, 6385.201849768779, 6402.071699603578, 6461.709223084972, 6480.935190648081, 6510.7721229756735, 4807.687500312469, 4813.566778989873, 4832.395950506187, 4844.128056949748, 4844.467108618049, 6768.187817461887, 6874.450043996519, 6889.173299204077, 6906.37842594169, 6925.0, 4918.491569299611, 4946.5407704727195, 4993.201087825931, 5010.847505073853, 5015.51421761872, 5003.924941125886, 5006.674566153189, 5009.098362294749, 5025.195968644999, 5040.39515258164, 5624.21261023457, 5642.576806739511, 5655.632383895034, 5683.715814209273, 5684.971575142098, 6505.251896032017, 6588.0, 6607.405333520006, 6615.437687796495, 6639.236487803868, 1637.7880368527203, 1648.620817212048, 1677.5973766296001, 1686.4434736536914, 1727.369510128794, 1202.555054629775, 1221.5541327415428, 1222.6759908624222, 1225.9509619615294, 1227.9324637145044, 5673.744681489343, 5727.828165155052, 5729.939961107209, 5744.511716504144, 5748.827535173951, 6537.280899101095, 6557.639329836809, 6560.737570497222, 6561.2782593914635, 6574.0, 5624.184493248538, 5660.490555849993, 5704.543636509069, 5706.233885635461, 5740.598158128891, 3474.722022238215, 3499.8775042873376, 3527.0829584308262, 3532.2691880978136, 3547.492315765789, 6409.391107844747, 6470.773522926675, 6513.478921686254, 6524.445422139172, 6527.118838956775, 6742.797716068428, 6760.65347652915, 6810.353016463428, 6821.93178068213, 6832.419244364285, 6427.614343139239, 6505.674716264262, 6509.576877502947, 6513.185851768529, 6527.934720652827, 6382.585131966407, 6487.740490380332, 6533.836654083686, 6549.377809108127, 6587.901181482377, 6471.935280647133, 6484.221893372825, 6506.284308725942, 6520.282768895323, 6587.299207324973, 6399.872002560016, 6439.0, 6444.806655800334, 6458.870822583428, 6484.708188131451, 4873.537013983666, 4915.4592994771265, 4928.162212423825, 4945.925811112908, 4956.851294461172, 6502.479801615954, 6514.804555863142, 6524.151860258136, 6619.768308109193, 6654.334566543307, 6211.472024877936, 6310.6213627182515, 6318.08387783778, 6384.201974753156, 6385.68071596409, 6171.0, 6286.622802631857, 6287.96856015717, 6293.0, 6333.90499142504, 5649.096144616841, 6088.543359247986, 6130.50955923534, 6235.690504994002, 6292.559520833498, 6357.23713154406, 6432.710528026157, 6440.935590644033, 6451.41937225652, 6454.225492940877, 6046.969765151146, 6076.240469941257, 6085.269767627913, 6111.938880611315, 6176.7838125665385], "value": 4997.0472262863, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [909.381620498061, 912.9406588571742, 942.3544871762844, 943.377370935183, 944.8677185194074, 18823.152958116883, 19663.70504442433, 19668.606627867433, 19696.409107726755, 19787.50531236718, 16130.516084517454, 16454.7531787023, 16490.835091649078, 18341.358052468167, 18364.632707345878, 913.1872633356302, 930.186087173723, 931.180561106229, 932.1563984593953, 932.9393589416666, 972.7665360313545, 973.2505970402785, 978.1050338939862, 983.7737320416309, 984.1634610580985, 992.2062350119911, 1014.1329163565156, 1020.515255253756, 1021.7445638590352, 1022.4172221833558, 996.621283912116, 996.6910257820099, 1007.2445665750687, 1015.5227043289678, 1023.754298968246, 748.5284270909347, 748.8165399477163, 749.9362554182901, 750.4409215134759, 752.259024860512, 19960.201591936304, 20092.296769613138, 20178.29375971834, 20193.8990305049, 20201.494962625904, 21369.67945480819, 21570.676439853414, 21732.0, 21847.563048738935, 21874.562508749896, 16075.196240187945, 16274.75587866183, 16926.407575734913, 16958.067306298148, 17161.0, 3664.1572438339063, 3700.7409481336317, 3702.315071711742, 3705.0366904604725, 3707.703383729322, 135319.32340338334, 136466.31766841104, 137090.31454842762, 137845.0, 138265.30867345698, 16740.916295418447, 16807.0, 16915.407960721317, 16935.0, 17000.234989425506, 31330.68669313278, 31399.529007064913, 31553.52669709956, 31904.680953190637, 32210.19474513156, 8261.256486916207, 8287.295579875657, 8316.875246871301, 8346.540940248224, 8388.035375931724, 3988.3219852625125, 4009.0, 4091.529474110456, 4124.072083781125, 4124.443200167939, 5353.2773075634295, 5488.121900495901, 5488.957098151339, 5490.094134467848, 5493.587980901449, 968.4237878462303, 995.233670074039, 997.3567049253191, 1002.6791426743519, 1007.3351587952063, 682.0962225051727, 700.9579425234502, 712.4976891291627, 712.8182313510022, 719.5934297122213, 11271.605493807678, 11353.091752659933, 11548.538058477734, 12076.094292928066, 12170.208936419105, 679.3817625960353, 692.4979389942251, 705.7141857547701, 707.1973310292752, 709.0959027240207, 5098.439171691185, 5218.321618189611, 5281.683099014072, 5354.089804733205, 5395.298611180521, 116349.0, 123267.38366308111, 124066.0, 124231.0, 124652.0, 108460.0, 109885.0, 111398.44300778444, 112149.0, 112739.43630281797, 91967.54016229876, 92871.0, 96980.51509742407, 97755.0, 99130.0, 10000.44997525114, 10040.196784257389, 10065.043820837009, 10272.486375681336, 10283.84574231402, 42545.361819572114, 43424.0, 44173.0, 45262.0, 45385.092298154515, 57226.14160787675, 58368.708156458946, 58568.0, 59340.406595935216, 59504.40495595162, 15550.533483995498, 15641.29614167388, 15696.136712480924, 15728.370865165723, 15730.9213453932, 17107.401240956508, 17225.655486890442, 17251.309947601956, 17252.65494690074, 17322.307107716053, 14333.0, 14478.058926169766, 14628.488002919847, 14820.333085011416, 14827.036242644625, 14300.35648395804, 14382.640433989234, 14393.352299146354, 14447.0, 14692.41230350774, 13648.93175534116, 13673.658158546115, 13822.516211932534, 13979.231142286864, 13990.790138148139, 10680.62617808373, 10880.401577913291, 11054.723631908944, 11125.221234513507, 11183.049440797624, 10434.217433692655, 10453.111485523816, 10464.215183861088, 10534.473276336306, 10697.732556686145], "value": 8728.265622143063, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1423.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 108.0, 105.0, 107.0, 108.0, 110.0, 108.0, 106.0, 108.0, 106.0], "value": 107.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 118.0, 115.0, 117.0, 119.0, 117.0, 120.0, 117.0, 115.0, 147.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 162.0, 162.0, 161.0, 162.0, 163.0, 172.0, 166.0, 164.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 86.0, 74.0, 75.0, 73.0, 80.0, 73.0, 76.0, 75.0], "value": 75.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 80.0, 78.0, 79.0, 80.0, 80.0, 79.0, 79.0, 79.0], "value": 79.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 147.0, 148.0, 147.0, 149.0, 148.0, 151.0, 149.0, 148.0, 147.0], "value": 148.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 54.0, 54.0, 55.0, 55.0, 57.0, 55.0, 55.0, 55.0, 56.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 37.0, 37.0, 38.0, 37.0, 37.0, 37.0, 37.0], "value": 37.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 76.0, 74.0, 74.0, 77.0, 74.0, 76.0, 79.0, 77.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 117.0, 118.0, 115.0, 122.0, 114.0, 110.0, 113.0, 113.0], "value": 115.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 168.0, 174.0, 168.0, 177.0, 167.0, 169.0, 172.0, 173.0, 165.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 69.0, 62.0, 64.0, 69.0, 67.0, 64.0], "value": 66.1, "unit": "score"}]}]} 21:20:56 INFO - Return code: 0 21:20:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:20:56 INFO - # TBPL SUCCESS # 21:20:56 INFO - Running post-action listener: _package_coverage_data 21:20:56 INFO - Running post-action listener: _resource_record_post_action 21:20:56 INFO - [mozharness: 2017-09-17 04:20:56.175144Z] Finished run-tests step (success) 21:20:56 INFO - Running post-run listener: _resource_record_post_run 21:20:56 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 147456; Write bytes: 341848064; Read time: 180; Write time: 190908 21:20:56 INFO - TinderboxPrint: CPU usage
13.1% 21:20:56 INFO - TinderboxPrint: I/O read bytes / time
147,456 / 180 21:20:56 INFO - TinderboxPrint: I/O write bytes / time
341,848,064 / 190,908 21:20:56 INFO - TinderboxPrint: CPU idle
8,232.0 (86.9%) 21:20:56 INFO - TinderboxPrint: CPU user
1,192.2 (12.6%) 21:20:56 INFO - TinderboxPrint: Swap in / out
0 / 0 21:20:56 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13389824; Read time: 0; Write time: 1944 21:20:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:20:56 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 143360; Write bytes: 328458240; Read time: 168; Write time: 188964 21:20:56 INFO - Running post-run listener: _upload_blobber_files 21:20:56 INFO - Blob upload gear active. 21:20:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:20:56 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 21:20:56 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:20:56 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:20:57 INFO - (blobuploader) - INFO - Open directory for files ... 21:20:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:20:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:20:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:20:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:20:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:20:58 INFO - (blobuploader) - INFO - Done attempting. 21:20:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:20:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:20:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:21:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:05 INFO - (blobuploader) - INFO - Done attempting. 21:21:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:21:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:21:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:06 INFO - (blobuploader) - INFO - Done attempting. 21:21:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:21:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:21:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:07 INFO - (blobuploader) - INFO - Done attempting. 21:21:07 INFO - (blobuploader) - INFO - Iteration through files over. 21:21:07 INFO - Return code: 0 21:21:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:21:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:21:07 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fcdcc39814268696517ae5b90a9deaa7f9db0d0033cab539e50cebcf9e60593743a19b21118e2a260ec5037b206c0ba3d1e161990e50c8dca3489b1f8eb96d49", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63fe2ce7382e6fe1424fdd5c1ddb12d40f38c590ad12a48afe8a0f86900a02a494476a5b26e20a6589f8fb715b065a6aeef76e683aa5f4245a399544c264cd39", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f632643e21c245ab1bf0965afdd2a5924ca6e10719ff841be805294ef28a9bb16a75b7cb089a112858732e1752a7ea3c2f40ab63804466babeca5f5bd5a1f54f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b923e89ffeb175c405a494b23d933771c42e87b82746d0642b85992d81169af5f2dddc9dc0e7ccb600c341057ec2040e2effbbe864fad171c98c9ea38fdc666b"} 21:21:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:21:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:21:07 INFO - Contents: 21:21:07 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fcdcc39814268696517ae5b90a9deaa7f9db0d0033cab539e50cebcf9e60593743a19b21118e2a260ec5037b206c0ba3d1e161990e50c8dca3489b1f8eb96d49", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63fe2ce7382e6fe1424fdd5c1ddb12d40f38c590ad12a48afe8a0f86900a02a494476a5b26e20a6589f8fb715b065a6aeef76e683aa5f4245a399544c264cd39", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f632643e21c245ab1bf0965afdd2a5924ca6e10719ff841be805294ef28a9bb16a75b7cb089a112858732e1752a7ea3c2f40ab63804466babeca5f5bd5a1f54f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b923e89ffeb175c405a494b23d933771c42e87b82746d0642b85992d81169af5f2dddc9dc0e7ccb600c341057ec2040e2effbbe864fad171c98c9ea38fdc666b"} 21:21:07 INFO - Running post-run listener: copy_logs_to_upload_dir 21:21:07 INFO - Copying logs to upload dir... 21:21:07 INFO - mkdir: /builds/slave/test/build/upload/logs 21:21:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.079247 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-16 21:21:07.591053) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-16 21:21:07.598828) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252 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-Non-PGO/sha512/fcdcc39814268696517ae5b90a9deaa7f9db0d0033cab539e50cebcf9e60593743a19b21118e2a260ec5037b206c0ba3d1e161990e50c8dca3489b1f8eb96d49", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63fe2ce7382e6fe1424fdd5c1ddb12d40f38c590ad12a48afe8a0f86900a02a494476a5b26e20a6589f8fb715b065a6aeef76e683aa5f4245a399544c264cd39", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f632643e21c245ab1bf0965afdd2a5924ca6e10719ff841be805294ef28a9bb16a75b7cb089a112858732e1752a7ea3c2f40ab63804466babeca5f5bd5a1f54f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b923e89ffeb175c405a494b23d933771c42e87b82746d0642b85992d81169af5f2dddc9dc0e7ccb600c341057ec2040e2effbbe864fad171c98c9ea38fdc666b"} build_url:https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fcdcc39814268696517ae5b90a9deaa7f9db0d0033cab539e50cebcf9e60593743a19b21118e2a260ec5037b206c0ba3d1e161990e50c8dca3489b1f8eb96d49", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/63fe2ce7382e6fe1424fdd5c1ddb12d40f38c590ad12a48afe8a0f86900a02a494476a5b26e20a6589f8fb715b065a6aeef76e683aa5f4245a399544c264cd39", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f632643e21c245ab1bf0965afdd2a5924ca6e10719ff841be805294ef28a9bb16a75b7cb089a112858732e1752a7ea3c2f40ab63804466babeca5f5bd5a1f54f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b923e89ffeb175c405a494b23d933771c42e87b82746d0642b85992d81169af5f2dddc9dc0e7ccb600c341057ec2040e2effbbe864fad171c98c9ea38fdc666b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-16 21:21:07.629900) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:21:07.630245) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505602588.595595-2010041252 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004563 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:21:07.711489) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-16 21:21:07.711796) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-16 21:21:07.712143) ========= ========= Total master_lag: 0.31 =========