builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-005 starttime: 1504607979.41 results: success (0) revision: 3ecda4678c49ca255c38b1697142b9118cdd27e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:39.405600) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:39.406051) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:39.406321) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:39.422887) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:39.423196) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-05 03:39:39-- 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% 46.0M=0s 2017-09-05 03:39:39 (46.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146151 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:39.585934) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:39.586239) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015461 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:39.635647) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-05 03:39:39.635991) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3ecda4678c49ca255c38b1697142b9118cdd27e7 --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 3ecda4678c49ca255c38b1697142b9118cdd27e7 --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=7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-05 03:39:39,690 truncating revision to first 12 chars 2017-09-05 03:39:39,690 Setting DEBUG logging. 2017-09-05 03:39:39,690 attempt 1/10 2017-09-05 03:39:39,690 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3ecda4678c49?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-05 03:39:40,986 unpacking tar archive at: mozilla-central-3ecda4678c49/testing/mozharness/ program finished with exit code 0 elapsedTime=1.597450 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-05 03:39:41.249540) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:41.249871) ========= script_repo_revision: 3ecda4678c49ca255c38b1697142b9118cdd27e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:41.250239) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:41.250501) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 03:39:41.263387) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-09-05 03:39:41.263745) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:39:41 INFO - MultiFileLogger online at 20170905 03:39:41 in /builds/slave/test 03:39:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:39:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:39:41 INFO - {'append_to_log': False, 03:39:41 INFO - 'base_work_dir': '/builds/slave/test', 03:39:41 INFO - 'blob_upload_branch': 'Firefox', 03:39:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:39:41 INFO - 'branch': 'Firefox', 03:39:41 INFO - 'buildbot_json_path': 'buildprops.json', 03:39:41 INFO - 'code_coverage': False, 03:39:41 INFO - 'config_files': ('talos/linux_config.py',), 03:39:41 INFO - 'default_actions': ('clobber', 03:39:41 INFO - 'read-buildbot-config', 03:39:41 INFO - 'download-and-extract', 03:39:41 INFO - 'populate-webroot', 03:39:41 INFO - 'create-virtualenv', 03:39:41 INFO - 'install', 03:39:41 INFO - 'setup-mitmproxy', 03:39:41 INFO - 'run-tests'), 03:39:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:39:41 INFO - 'disable_ccov_upload': False, 03:39:41 INFO - 'disable_stylo': False, 03:39:41 INFO - 'download_minidump_stackwalk': True, 03:39:41 INFO - 'download_symbols': 'ondemand', 03:39:41 INFO - 'enable_stylo': False, 03:39:41 INFO - 'enable_webrender': False, 03:39:41 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:39:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:39:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:39:41 INFO - 'gecko_profile': False, 03:39:41 INFO - 'gecko_profile_interval': 0, 03:39:41 INFO - 'installer_path': 'installer.exe', 03:39:41 INFO - 'log_level': 'info', 03:39:41 INFO - 'log_name': 'talos', 03:39:41 INFO - 'log_to_console': True, 03:39:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:39:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:39:41 INFO - 'opt_config_files': (), 03:39:41 INFO - 'pip_index': False, 03:39:41 INFO - 'suite': 'dromaeojs-e10s', 03:39:41 INFO - 'system_bits': '32', 03:39:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:39:41 INFO - 'title': 'talos-linux64-ix-005', 03:39:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:39:41 INFO - 'use_talos_json': True, 03:39:41 INFO - 'verify': 'False', 03:39:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:39:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:39:41 INFO - 'work_dir': 'build'} 03:39:41 INFO - [mozharness: 2017-09-05 10:39:41.456998Z] Running clobber step. 03:39:41 INFO - Running pre-action listener: _resource_record_pre_action 03:39:41 INFO - Running main action method: clobber 03:39:41 INFO - rmtree: /builds/slave/test/build 03:39:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:39:42 INFO - Running post-action listener: _resource_record_post_action 03:39:42 INFO - [mozharness: 2017-09-05 10:39:42.507825Z] Finished clobber step (success) 03:39:42 INFO - [mozharness: 2017-09-05 10:39:42.507924Z] Running read-buildbot-config step. 03:39:42 INFO - Running pre-action listener: _resource_record_pre_action 03:39:42 INFO - Running main action method: read_buildbot_config 03:39:42 INFO - Using buildbot properties: 03:39:42 INFO - { 03:39:42 INFO - "project": "", 03:39:42 INFO - "product": "firefox", 03:39:42 INFO - "who": "archaeopteryx@coole-files.de", 03:39:42 INFO - "installer_path": "public/build/target.tar.bz2", 03:39:42 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 03:39:42 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:39:42 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 03:39:42 INFO - "stage_platform": "linux64-qr", 03:39:42 INFO - "basedir": "/builds/slave/test", 03:39:42 INFO - "buildnumber": 31, 03:39:42 INFO - "platform": "ubuntu64_hw_qr", 03:39:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:39:42 INFO - "slavebuilddir": "test", 03:39:42 INFO - "taskId": "IgyRnYdMS96wpoS32UTSyA", 03:39:42 INFO - "branch": "mozilla-central", 03:39:42 INFO - "script_repo_revision": "production", 03:39:42 INFO - "revision": "3ecda4678c49ca255c38b1697142b9118cdd27e7", 03:39:42 INFO - "slavename": "talos-linux64-ix-005", 03:39:42 INFO - "repo_path": "mozilla-central" 03:39:42 INFO - } 03:39:42 INFO - Finding installer, test and symbols from parent task. 03:39:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IgyRnYdMS96wpoS32UTSyA'}, attempt #1 03:39:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IgyRnYdMS96wpoS32UTSyA'}, attempt #1 03:39:43 INFO - Task dependencies: Oz77JHY8R46lR-xvwFHp5Q 03:39:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q'}, attempt #1 03:39:43 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 03:39:43 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.test_packages.json 03:39:43 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.crashreporter-symbols.zip 03:39:43 INFO - Running post-action listener: _resource_record_post_action 03:39:43 INFO - [mozharness: 2017-09-05 10:39:43.857362Z] Finished read-buildbot-config step (success) 03:39:43 INFO - [mozharness: 2017-09-05 10:39:43.857579Z] Running download-and-extract step. 03:39:43 INFO - Running pre-action listener: _resource_record_pre_action 03:39:43 INFO - Running main action method: download_and_extract 03:39:43 INFO - mkdir: /builds/slave/test/build/tests 03:39:43 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')]} 03:39:43 INFO - trying https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.test_packages.json 03:39:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:39:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:39:44 INFO - Downloaded 989 bytes. 03:39:44 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:39:44 INFO - Using the following test package requirements: 03:39:44 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:39:44 INFO - u'common': [u'target.common.tests.zip'], 03:39:44 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:39:44 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:39:44 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:39:44 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:39:44 INFO - u'mozbase': [u'target.common.tests.zip'], 03:39:44 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:39:44 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:39:44 INFO - u'web-platform': [u'target.common.tests.zip', 03:39:44 INFO - u'target.web-platform.tests.tar.gz'], 03:39:44 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:39:44 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:39:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip 03:39:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:39:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip into memory 03:39:45 INFO - Content-Length response header: 55022230 03:39:45 INFO - Bytes received: 55022230 03:39:56 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:39:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip 03:39:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:39:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip into memory 03:39:57 INFO - Content-Length response header: 55022230 03:39:57 INFO - Bytes received: 55022230 03:40:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.talos.tests.zip 03:40:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:40:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.talos.tests.zip into memory 03:40:10 INFO - Content-Length response header: 14496650 03:40:10 INFO - Bytes received: 14496650 03:40:10 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')]} 03:40:10 INFO - trying https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 03:40:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:40:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:40:11 INFO - Downloaded 62687364 bytes. 03:40:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 03:40:11 INFO - mkdir: /builds/slave/test/properties 03:40:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:40:11 INFO - Writing to file /builds/slave/test/properties/build_url 03:40:11 INFO - Contents: 03:40:11 INFO - build_url:https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 03:40:11 INFO - Running post-action listener: _resource_record_post_action 03:40:11 INFO - Running post-action listener: find_tests_for_verification 03:40:11 INFO - Running post-action listener: set_extra_try_arguments 03:40:11 INFO - [mozharness: 2017-09-05 10:40:11.538166Z] Finished download-and-extract step (success) 03:40:11 INFO - [mozharness: 2017-09-05 10:40:11.538294Z] Running populate-webroot step. 03:40:11 INFO - Running pre-action listener: _resource_record_pre_action 03:40:11 INFO - Running main action method: populate_webroot 03:40:11 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:40:11 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:40:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:40:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:40:11 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:40:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:40:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:40:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:40:11 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:40:11 INFO - 'talos_options': ['--enable-stylo'], 03:40:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:40:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:40:11 INFO - 'tests': ['damp', 'tps']}, 03:40:11 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:40:11 INFO - 'talos_options': ['--enable-stylo'], 03:40:11 INFO - 'tests': ['damp', 'tps']}, 03:40:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:40:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:40:11 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:40:11 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:40:11 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:40:11 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:40:11 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:40:11 INFO - 'talos_options': ['--enable-stylo'], 03:40:11 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:40:11 INFO - 'other-e10s': {'tests': ['a11yr', 03:40:11 INFO - 'ts_paint', 03:40:11 INFO - 'tpaint', 03:40:11 INFO - 'sessionrestore', 03:40:11 INFO - 'sessionrestore_many_windows', 03:40:11 INFO - 'sessionrestore_no_auto_restore', 03:40:11 INFO - 'tabpaint']}, 03:40:11 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:40:11 INFO - 'tests': ['a11yr', 03:40:11 INFO - 'ts_paint', 03:40:11 INFO - 'tpaint', 03:40:11 INFO - 'sessionrestore', 03:40:11 INFO - 'sessionrestore_many_windows', 03:40:11 INFO - 'sessionrestore_no_auto_restore', 03:40:11 INFO - 'tabpaint']}, 03:40:11 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:40:11 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:40:11 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:40:11 INFO - 'tests': ['bloom_basic_singleton']}, 03:40:11 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:40:11 INFO - 'tests': ['bloom_basic']}, 03:40:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:40:11 INFO - 'tsvgr_opacity', 03:40:11 INFO - 'tart', 03:40:11 INFO - 'tscrollx', 03:40:11 INFO - 'tsvg_static']}, 03:40:11 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:40:11 INFO - 'tests': ['tsvgx', 03:40:11 INFO - 'tsvgr_opacity', 03:40:11 INFO - 'tart', 03:40:11 INFO - 'tscrollx', 03:40:11 INFO - 'tsvg_static']}, 03:40:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:40:11 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:40:11 INFO - 'talos_options': ['--enable-stylo'], 03:40:11 INFO - 'tests': ['tp5o']}, 03:40:11 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:40:11 INFO - 'talos_options': ['--mitmproxy', 03:40:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:40:11 INFO - '--firstNonBlankPaint'], 03:40:11 INFO - 'tests': ['tp6_google', 03:40:11 INFO - 'tp6_youtube', 03:40:11 INFO - 'tp6_amazon', 03:40:11 INFO - 'tp6_facebook']}, 03:40:11 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:40:11 INFO - 'talos_options': ['--enable-stylo', 03:40:11 INFO - '--mitmproxy', 03:40:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:40:11 INFO - '--firstNonBlankPaint'], 03:40:11 INFO - 'tests': ['tp6_google', 03:40:11 INFO - 'tp6_youtube', 03:40:11 INFO - 'tp6_amazon', 03:40:11 INFO - 'tp6_facebook']}, 03:40:11 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:40:11 INFO - 'talos_options': ['--enable-stylo', 03:40:11 INFO - '--stylo-threads=1', 03:40:11 INFO - '--mitmproxy', 03:40:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:40:11 INFO - '--firstNonBlankPaint'], 03:40:11 INFO - 'tests': ['tp6_google', 03:40:11 INFO - 'tp6_youtube', 03:40:11 INFO - 'tp6_amazon', 03:40:11 INFO - 'tp6_facebook']}, 03:40:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:40:11 INFO - 'talos_options': ['--xperf_path', 03:40:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:40:11 INFO - 'tests': ['tp5n']}, 03:40:11 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:40:11 INFO - 'talos_options': ['--enable-stylo', 03:40:11 INFO - '--xperf_path', 03:40:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:40:11 INFO - 'tests': ['tp5n']}}} 03:40:11 INFO - Running post-action listener: _resource_record_post_action 03:40:11 INFO - [mozharness: 2017-09-05 10:40:11.552645Z] Finished populate-webroot step (success) 03:40:11 INFO - [mozharness: 2017-09-05 10:40:11.552734Z] Running create-virtualenv step. 03:40:11 INFO - Running pre-action listener: _resource_record_pre_action 03:40:11 INFO - Running main action method: create_virtualenv 03:40:11 INFO - Creating virtualenv /builds/slave/test/build/venv 03:40:11 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:40:11 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:40:11 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:40:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:40:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:40:12 INFO - Installing distribute.............................................................................................................................................................................................done. 03:40:12 INFO - Installing pip...............done. 03:40:12 INFO - Return code: 0 03:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:40:12 INFO - Reading from file tmpfile_stdout 03:40:12 INFO - Output received: 03:40:12 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:40:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:40:12 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')]} 03:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:12 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')]} 03:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:12 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 0x299c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d08a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d0c090>, '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 0x2d0cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d06f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', '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 03:40:12 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 03:40:12 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 03:40:12 INFO - Using env: {'DISPLAY': ':0', 03:40:12 INFO - 'HOME': '/home/cltbld', 03:40:12 INFO - 'LANG': 'en_US.UTF-8', 03:40:12 INFO - 'LANGUAGE': 'en_US:en', 03:40:12 INFO - 'LOGNAME': 'cltbld', 03:40:12 INFO - 'MAIL': '/var/mail/cltbld', 03:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:12 INFO - 'MOZ_NO_REMOTE': '1', 03:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:12 INFO - 'NO_EM_RESTART': '1', 03:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:12 INFO - 'PWD': '/builds/slave/test', 03:40:12 INFO - 'SHELL': '/bin/bash', 03:40:12 INFO - 'SHLVL': '1', 03:40:12 INFO - 'TERM': 'linux', 03:40:12 INFO - 'TMOUT': '86400', 03:40:12 INFO - 'USER': 'cltbld', 03:40:12 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:12 INFO - '_': '/tools/buildbot/bin/python'} 03:40:13 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:40:13 INFO - Downloading/unpacking pip>=1.5 03:40:13 INFO - Running setup.py egg_info for package pip 03:40:13 INFO - warning: no files found matching 'pip/cacert.pem' 03:40:13 INFO - warning: no files found matching '*.html' under directory 'docs' 03:40:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:40:13 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:40:13 INFO - Installing collected packages: pip 03:40:13 INFO - Found existing installation: pip 1.1 03:40:13 INFO - Uninstalling pip: 03:40:13 INFO - Successfully uninstalled pip 03:40:13 INFO - Running setup.py install for pip 03:40:13 INFO - warning: no files found matching 'pip/cacert.pem' 03:40:13 INFO - warning: no files found matching '*.html' under directory 'docs' 03:40:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:40:13 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:40:13 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:40:13 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:40:13 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:40:13 INFO - Successfully installed pip 03:40:13 INFO - Cleaning up... 03:40:13 INFO - Return code: 0 03:40:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d08a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d0c090>, '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 0x2d0cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d06f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', '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 03:40:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:40:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:40:13 INFO - Using env: {'DISPLAY': ':0', 03:40:13 INFO - 'HOME': '/home/cltbld', 03:40:13 INFO - 'LANG': 'en_US.UTF-8', 03:40:13 INFO - 'LANGUAGE': 'en_US:en', 03:40:13 INFO - 'LOGNAME': 'cltbld', 03:40:13 INFO - 'MAIL': '/var/mail/cltbld', 03:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:13 INFO - 'MOZ_NO_REMOTE': '1', 03:40:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:13 INFO - 'NO_EM_RESTART': '1', 03:40:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:13 INFO - 'PWD': '/builds/slave/test', 03:40:13 INFO - 'SHELL': '/bin/bash', 03:40:13 INFO - 'SHLVL': '1', 03:40:13 INFO - 'TERM': 'linux', 03:40:13 INFO - 'TMOUT': '86400', 03:40:13 INFO - 'USER': 'cltbld', 03:40:13 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:13 INFO - '_': '/tools/buildbot/bin/python'} 03:40:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:13 INFO - Downloading/unpacking psutil>=3.1.1 03:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:40:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:40:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:40:15 INFO - Installing collected packages: psutil 03:40:15 INFO - Running setup.py install for psutil 03:40:15 INFO - building 'psutil._psutil_linux' extension 03:40:15 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 03:40:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:40:15 INFO - building 'psutil._psutil_posix' extension 03:40:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:40:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:40:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:40:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:40:15 INFO - Successfully installed psutil 03:40:15 INFO - Cleaning up... 03:40:15 INFO - Return code: 0 03:40:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d08a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d0c090>, '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 0x2d0cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d06f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', '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 03:40:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:40:15 INFO - Using env: {'DISPLAY': ':0', 03:40:15 INFO - 'HOME': '/home/cltbld', 03:40:15 INFO - 'LANG': 'en_US.UTF-8', 03:40:15 INFO - 'LANGUAGE': 'en_US:en', 03:40:15 INFO - 'LOGNAME': 'cltbld', 03:40:15 INFO - 'MAIL': '/var/mail/cltbld', 03:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:15 INFO - 'MOZ_NO_REMOTE': '1', 03:40:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:15 INFO - 'NO_EM_RESTART': '1', 03:40:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:15 INFO - 'PWD': '/builds/slave/test', 03:40:15 INFO - 'SHELL': '/bin/bash', 03:40:15 INFO - 'SHLVL': '1', 03:40:15 INFO - 'TERM': 'linux', 03:40:15 INFO - 'TMOUT': '86400', 03:40:15 INFO - 'USER': 'cltbld', 03:40:15 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:15 INFO - '_': '/tools/buildbot/bin/python'} 03:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:40:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:40:17 INFO - Installing collected packages: mozsystemmonitor 03:40:17 INFO - Running setup.py install for mozsystemmonitor 03:40:17 INFO - Successfully installed mozsystemmonitor 03:40:17 INFO - Cleaning up... 03:40:17 INFO - Return code: 0 03:40:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x299c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d08a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d0c090>, '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 0x2d0cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d06f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', '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 03:40:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:40:17 INFO - Using env: {'DISPLAY': ':0', 03:40:17 INFO - 'HOME': '/home/cltbld', 03:40:17 INFO - 'LANG': 'en_US.UTF-8', 03:40:17 INFO - 'LANGUAGE': 'en_US:en', 03:40:17 INFO - 'LOGNAME': 'cltbld', 03:40:17 INFO - 'MAIL': '/var/mail/cltbld', 03:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:17 INFO - 'MOZ_NO_REMOTE': '1', 03:40:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:17 INFO - 'NO_EM_RESTART': '1', 03:40:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:17 INFO - 'PWD': '/builds/slave/test', 03:40:17 INFO - 'SHELL': '/bin/bash', 03:40:17 INFO - 'SHLVL': '1', 03:40:17 INFO - 'TERM': 'linux', 03:40:17 INFO - 'TMOUT': '86400', 03:40:17 INFO - 'USER': 'cltbld', 03:40:17 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:17 INFO - '_': '/tools/buildbot/bin/python'} 03:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:17 INFO - Downloading/unpacking jsonschema==2.5.1 03:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:40:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:18 INFO - Downloading functools32-3.2.3-2.tar.gz 03:40:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:40:18 INFO - warning: no files found matching '*.txt' 03:40:18 INFO - no previously-included directories found matching 'build' 03:40:18 INFO - no previously-included directories found matching 'dist' 03:40:18 INFO - no previously-included directories found matching '.git*' 03:40:18 INFO - Installing collected packages: jsonschema, functools32 03:40:18 INFO - Running setup.py install for functools32 03:40:18 INFO - warning: no files found matching '*.txt' 03:40:18 INFO - no previously-included directories found matching 'build' 03:40:18 INFO - no previously-included directories found matching 'dist' 03:40:18 INFO - no previously-included directories found matching '.git*' 03:40:18 INFO - Successfully installed jsonschema functools32 03:40:18 INFO - Cleaning up... 03:40:18 INFO - Return code: 0 03:40:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d08a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d0c090>, '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 0x2d0cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d06f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', '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 03:40:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:40:18 INFO - Using env: {'DISPLAY': ':0', 03:40:18 INFO - 'HOME': '/home/cltbld', 03:40:18 INFO - 'LANG': 'en_US.UTF-8', 03:40:18 INFO - 'LANGUAGE': 'en_US:en', 03:40:18 INFO - 'LOGNAME': 'cltbld', 03:40:18 INFO - 'MAIL': '/var/mail/cltbld', 03:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:18 INFO - 'MOZ_NO_REMOTE': '1', 03:40:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:18 INFO - 'NO_EM_RESTART': '1', 03:40:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:18 INFO - 'PWD': '/builds/slave/test', 03:40:18 INFO - 'SHELL': '/bin/bash', 03:40:18 INFO - 'SHLVL': '1', 03:40:18 INFO - 'TERM': 'linux', 03:40:18 INFO - 'TMOUT': '86400', 03:40:18 INFO - 'USER': 'cltbld', 03:40:18 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:18 INFO - '_': '/tools/buildbot/bin/python'} 03:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:40:19 INFO - Cleaning up... 03:40:19 INFO - Return code: 0 03:40:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:19 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 0x299c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d08a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d0c090>, '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 0x2d0cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d06f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', '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 03:40:19 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 03:40:19 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 03:40:19 INFO - Using env: {'DISPLAY': ':0', 03:40:19 INFO - 'HOME': '/home/cltbld', 03:40:19 INFO - 'LANG': 'en_US.UTF-8', 03:40:19 INFO - 'LANGUAGE': 'en_US:en', 03:40:19 INFO - 'LOGNAME': 'cltbld', 03:40:19 INFO - 'MAIL': '/var/mail/cltbld', 03:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:19 INFO - 'MOZ_NO_REMOTE': '1', 03:40:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:19 INFO - 'NO_EM_RESTART': '1', 03:40:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:19 INFO - 'PWD': '/builds/slave/test', 03:40:19 INFO - 'SHELL': '/bin/bash', 03:40:19 INFO - 'SHLVL': '1', 03:40:19 INFO - 'TERM': 'linux', 03:40:19 INFO - 'TMOUT': '86400', 03:40:19 INFO - 'USER': 'cltbld', 03:40:19 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:19 INFO - '_': '/tools/buildbot/bin/python'} 03:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:19 INFO - Downloading/unpacking blobuploader==1.2.4 03:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:20 INFO - Downloading blobuploader-1.2.4.tar.gz 03:40:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:40:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:40:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:20 INFO - Downloading docopt-0.6.1.tar.gz 03:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:40:21 INFO - Installing collected packages: blobuploader, requests, docopt 03:40:21 INFO - Running setup.py install for blobuploader 03:40:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:40:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:40:21 INFO - Running setup.py install for requests 03:40:21 INFO - Running setup.py install for docopt 03:40:21 INFO - Successfully installed blobuploader requests docopt 03:40:21 INFO - Cleaning up... 03:40:21 INFO - Return code: 0 03:40:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d08a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d0c090>, '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 0x2d0cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d06f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', '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 03:40:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:40:21 INFO - Using env: {'DISPLAY': ':0', 03:40:21 INFO - 'HOME': '/home/cltbld', 03:40:21 INFO - 'LANG': 'en_US.UTF-8', 03:40:21 INFO - 'LANGUAGE': 'en_US:en', 03:40:21 INFO - 'LOGNAME': 'cltbld', 03:40:21 INFO - 'MAIL': '/var/mail/cltbld', 03:40:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:21 INFO - 'MOZ_NO_REMOTE': '1', 03:40:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:21 INFO - 'NO_EM_RESTART': '1', 03:40:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:21 INFO - 'PWD': '/builds/slave/test', 03:40:21 INFO - 'SHELL': '/bin/bash', 03:40:21 INFO - 'SHLVL': '1', 03:40:21 INFO - 'TERM': 'linux', 03:40:21 INFO - 'TMOUT': '86400', 03:40:21 INFO - 'USER': 'cltbld', 03:40:21 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:21 INFO - '_': '/tools/buildbot/bin/python'} 03:40:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:40:21 INFO - Running setup.py (path:/tmp/pip-U_EL2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:40:21 INFO - Running setup.py (path:/tmp/pip-MBAw73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:40:22 INFO - Running setup.py (path:/tmp/pip-50HEi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:40:22 INFO - Running setup.py (path:/tmp/pip-iPYmCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:40:22 INFO - Running setup.py (path:/tmp/pip-rjkDzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:40:22 INFO - Running setup.py (path:/tmp/pip-jX_UxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:40:22 INFO - Running setup.py (path:/tmp/pip-mfBkwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:40:22 INFO - Running setup.py (path:/tmp/pip-N31bQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:40:22 INFO - Running setup.py (path:/tmp/pip-yot_T9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:40:22 INFO - Running setup.py (path:/tmp/pip-6Cw_y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:40:22 INFO - Running setup.py (path:/tmp/pip-u6264P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:40:22 INFO - Running setup.py (path:/tmp/pip-now6tA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:40:22 INFO - Running setup.py (path:/tmp/pip-GC6Ynx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:40:22 INFO - Running setup.py (path:/tmp/pip-I8tB6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:22 INFO - Running setup.py (path:/tmp/pip-AkezW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:40:22 INFO - Running setup.py (path:/tmp/pip-IEgDNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:40:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:40:23 INFO - Running setup.py (path:/tmp/pip-v32Vqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:40:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:40:23 INFO - Running setup.py install for manifestparser 03:40:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:40:23 INFO - Running setup.py install for mozcrash 03:40:23 INFO - Running setup.py install for mozdebug 03:40:23 INFO - Running setup.py install for mozdevice 03:40:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:40:23 INFO - Running setup.py install for mozfile 03:40:23 INFO - Running setup.py install for mozhttpd 03:40:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:40:23 INFO - Running setup.py install for mozinfo 03:40:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:40:23 INFO - Running setup.py install for mozInstall 03:40:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:40:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:40:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:40:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:40:23 INFO - Running setup.py install for mozleak 03:40:23 INFO - Running setup.py install for mozlog 03:40:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:40:24 INFO - Running setup.py install for moznetwork 03:40:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:40:24 INFO - Running setup.py install for mozprocess 03:40:24 INFO - Running setup.py install for mozprofile 03:40:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:40:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:40:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:40:24 INFO - Running setup.py install for mozrunner 03:40:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:40:24 INFO - Running setup.py install for mozscreenshot 03:40:24 INFO - Running setup.py install for moztest 03:40:24 INFO - Running setup.py install for mozversion 03:40:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:40:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:40:24 INFO - Cleaning up... 03:40:24 INFO - Return code: 0 03:40:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d08a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d0c090>, '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 0x2d0cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d06f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', '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 03:40:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:40:24 INFO - Using env: {'DISPLAY': ':0', 03:40:24 INFO - 'HOME': '/home/cltbld', 03:40:24 INFO - 'LANG': 'en_US.UTF-8', 03:40:24 INFO - 'LANGUAGE': 'en_US:en', 03:40:24 INFO - 'LOGNAME': 'cltbld', 03:40:24 INFO - 'MAIL': '/var/mail/cltbld', 03:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:24 INFO - 'MOZ_NO_REMOTE': '1', 03:40:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:24 INFO - 'NO_EM_RESTART': '1', 03:40:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:24 INFO - 'PWD': '/builds/slave/test', 03:40:24 INFO - 'SHELL': '/bin/bash', 03:40:24 INFO - 'SHLVL': '1', 03:40:24 INFO - 'TERM': 'linux', 03:40:24 INFO - 'TMOUT': '86400', 03:40:24 INFO - 'USER': 'cltbld', 03:40:24 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:24 INFO - '_': '/tools/buildbot/bin/python'} 03:40:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:40:25 INFO - Running setup.py (path:/tmp/pip-AAfiUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:40:25 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)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:40:25 INFO - Running setup.py (path:/tmp/pip-uvPE3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:40:25 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)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:40:25 INFO - Running setup.py (path:/tmp/pip-Oh1eHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:40:25 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)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:40:25 INFO - Running setup.py (path:/tmp/pip-K1CQB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:40:25 INFO - Running setup.py (path:/tmp/pip-KS0vbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:40:25 INFO - Running setup.py (path:/tmp/pip-4RHjVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:40:25 INFO - Running setup.py (path:/tmp/pip-JQiBX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:40:25 INFO - Running setup.py (path:/tmp/pip-iV9PYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:40:25 INFO - Running setup.py (path:/tmp/pip-8sPG2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:40:25 INFO - Running setup.py (path:/tmp/pip-krymXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:40:25 INFO - Running setup.py (path:/tmp/pip-mvZ1B8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:40:25 INFO - Running setup.py (path:/tmp/pip-wHwJo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:40:25 INFO - Running setup.py (path:/tmp/pip-sB0urM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:40:26 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)) 03:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:40:26 INFO - Running setup.py (path:/tmp/pip-7eK8H_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:40:26 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)) 03:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:26 INFO - Running setup.py (path:/tmp/pip-oIhaJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:26 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)) 03:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:40:26 INFO - Running setup.py (path:/tmp/pip-1qsNvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:40:26 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)) 03:40:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:40:26 INFO - Running setup.py (path:/tmp/pip-QnRKUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:40:26 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)) 03:40:26 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)) 03:40:26 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)) 03:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:40:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:27 INFO - Downloading blessings-1.6.tar.gz 03:40:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:40:27 INFO - Installing collected packages: blessings 03:40:27 INFO - Running setup.py install for blessings 03:40:27 INFO - Successfully installed blessings 03:40:27 INFO - Cleaning up... 03:40:27 INFO - Return code: 0 03:40:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:40:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:40:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:40:27 INFO - Reading from file tmpfile_stdout 03:40:27 INFO - Current package versions: 03:40:27 INFO - argparse == 1.2.1 03:40:27 INFO - blessings == 1.6 03:40:27 INFO - blobuploader == 1.2.4 03:40:27 INFO - docopt == 0.6.1 03:40:27 INFO - functools32 == 3.2.3-2 03:40:27 INFO - jsonschema == 2.5.1 03:40:27 INFO - manifestparser == 1.1 03:40:27 INFO - mozInstall == 1.13 03:40:27 INFO - mozcrash == 1.0 03:40:27 INFO - mozdebug == 0.1 03:40:27 INFO - mozdevice == 0.51 03:40:27 INFO - mozfile == 1.2 03:40:27 INFO - mozhttpd == 0.7 03:40:27 INFO - mozinfo == 0.10 03:40:27 INFO - mozleak == 0.1 03:40:27 INFO - mozlog == 3.5 03:40:27 INFO - moznetwork == 0.27 03:40:27 INFO - mozprocess == 0.25 03:40:27 INFO - mozprofile == 0.28 03:40:27 INFO - mozrunner == 6.13 03:40:27 INFO - mozscreenshot == 0.1 03:40:27 INFO - mozsystemmonitor == 0.3 03:40:27 INFO - moztest == 0.8 03:40:27 INFO - mozversion == 1.4 03:40:27 INFO - psutil == 3.1.1 03:40:27 INFO - requests == 1.2.3 03:40:27 INFO - wsgiref == 0.1.2 03:40:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x299c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d08a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d0c090>, '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 0x2d0cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d06f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', '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 03:40:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:40:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:40:27 INFO - Using env: {'DISPLAY': ':0', 03:40:27 INFO - 'HOME': '/home/cltbld', 03:40:27 INFO - 'LANG': 'en_US.UTF-8', 03:40:27 INFO - 'LANGUAGE': 'en_US:en', 03:40:27 INFO - 'LOGNAME': 'cltbld', 03:40:27 INFO - 'MAIL': '/var/mail/cltbld', 03:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:27 INFO - 'MOZ_NO_REMOTE': '1', 03:40:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:27 INFO - 'NO_EM_RESTART': '1', 03:40:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:27 INFO - 'PWD': '/builds/slave/test', 03:40:27 INFO - 'SHELL': '/bin/bash', 03:40:27 INFO - 'SHLVL': '1', 03:40:27 INFO - 'TERM': 'linux', 03:40:27 INFO - 'TMOUT': '86400', 03:40:27 INFO - 'USER': 'cltbld', 03:40:27 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:27 INFO - '_': '/tools/buildbot/bin/python'} 03:40:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:28 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)) 03:40:28 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)) 03:40:28 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)) 03:40:28 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)) 03:40:28 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)) 03:40:28 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)) 03:40:28 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)) 03:40:28 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)) 03:40:28 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)) 03:40:28 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:40:28 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 03:40:28 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 03:40:28 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 03:40:28 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 03:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:40:29 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)) 03:40:29 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)) 03:40:29 INFO - Installing collected packages: simplejson 03:40:29 INFO - Running setup.py install for simplejson 03:40:29 INFO - building 'simplejson._speedups' extension 03:40:29 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:40:30 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 03:40:30 INFO - Successfully installed simplejson 03:40:30 INFO - Cleaning up... 03:40:30 INFO - Return code: 0 03:40:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:40:30 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')]} 03:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:30 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')]} 03:40:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:30 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 0x299c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b65a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d08a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d0c090>, '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 0x2d0cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d06f90>, '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': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', '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 03:40:30 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 03:40:30 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 03:40:30 INFO - Using env: {'DISPLAY': ':0', 03:40:30 INFO - 'HOME': '/home/cltbld', 03:40:30 INFO - 'LANG': 'en_US.UTF-8', 03:40:30 INFO - 'LANGUAGE': 'en_US:en', 03:40:30 INFO - 'LOGNAME': 'cltbld', 03:40:30 INFO - 'MAIL': '/var/mail/cltbld', 03:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:30 INFO - 'MOZ_NO_REMOTE': '1', 03:40:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:30 INFO - 'NO_EM_RESTART': '1', 03:40:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:30 INFO - 'PWD': '/builds/slave/test', 03:40:30 INFO - 'SHELL': '/bin/bash', 03:40:30 INFO - 'SHLVL': '1', 03:40:30 INFO - 'TERM': 'linux', 03:40:30 INFO - 'TMOUT': '86400', 03:40:30 INFO - 'USER': 'cltbld', 03:40:30 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:30 INFO - '_': '/tools/buildbot/bin/python'} 03:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:40:30 INFO - Cleaning up... 03:40:30 INFO - Return code: 0 03:40:30 INFO - Running post-action listener: _resource_record_post_action 03:40:30 INFO - Running post-action listener: _start_resource_monitoring 03:40:30 INFO - Starting resource monitoring. 03:40:30 INFO - [mozharness: 2017-09-05 10:40:30.555549Z] Finished create-virtualenv step (success) 03:40:30 INFO - [mozharness: 2017-09-05 10:40:30.556071Z] Running install step. 03:40:30 INFO - Running pre-action listener: _resource_record_pre_action 03:40:30 INFO - Running main action method: install 03:40:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:40:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:40:30 INFO - Reading from file tmpfile_stdout 03:40:30 INFO - Detecting whether we're running mozinstall >=1.0... 03:40:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:40:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:40:30 INFO - Reading from file tmpfile_stdout 03:40:30 INFO - Output received: 03:40:30 INFO - Usage: mozinstall [options] installer 03:40:30 INFO - Options: 03:40:30 INFO - -h, --help show this help message and exit 03:40:30 INFO - -d DEST, --destination=DEST 03:40:30 INFO - Directory to install application into. [default: 03:40:30 INFO - "/builds/slave/test"] 03:40:30 INFO - --app=APP Application being installed. [default: firefox] 03:40:30 INFO - mkdir: /builds/slave/test/build/application 03:40:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:40:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:40:47 INFO - Reading from file tmpfile_stdout 03:40:47 INFO - Output received: 03:40:47 INFO - /builds/slave/test/build/application/firefox/firefox 03:40:47 INFO - Running post-action listener: _resource_record_post_action 03:40:47 INFO - [mozharness: 2017-09-05 10:40:47.631669Z] Finished install step (success) 03:40:47 INFO - [mozharness: 2017-09-05 10:40:47.631816Z] Running setup-mitmproxy step. 03:40:47 INFO - Running pre-action listener: _resource_record_pre_action 03:40:47 INFO - Running main action method: setup_mitmproxy 03:40:47 INFO - Skipping: mitmproxy is not required 03:40:47 INFO - Running post-action listener: _resource_record_post_action 03:40:47 INFO - [mozharness: 2017-09-05 10:40:47.632346Z] Finished setup-mitmproxy step (success) 03:40:47 INFO - [mozharness: 2017-09-05 10:40:47.632448Z] Running run-tests step. 03:40:47 INFO - Running pre-action listener: _resource_record_pre_action 03:40:47 INFO - Running pre-action listener: _set_gcov_prefix 03:40:47 INFO - Running main action method: run_tests 03:40:47 WARNING - Try message not found. 03:40:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:40:47 INFO - Python 2.7.3 03:40:47 INFO - Return code: 0 03:40:47 INFO - grabbing minidump binary from tooltool 03:40:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:40:47 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d0c090>, '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 0x2d0cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d06f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:40:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:40:47 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:40:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:40:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:40:47 INFO - Return code: 0 03:40:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:40:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:40:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:40:47 INFO - ENV: RUST_BACKTRACE is now full 03:40:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:40:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:40:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 03:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 03:40:47 INFO - Using env: {'DISPLAY': ':0', 03:40:47 INFO - 'HOME': '/home/cltbld', 03:40:47 INFO - 'LANG': 'en_US.UTF-8', 03:40:47 INFO - 'LANGUAGE': 'en_US:en', 03:40:47 INFO - 'LOGNAME': 'cltbld', 03:40:47 INFO - 'MAIL': '/var/mail/cltbld', 03:40:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:40:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:40:47 INFO - 'MOZ_ACCELERATED': '1', 03:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:47 INFO - 'MOZ_NO_REMOTE': '1', 03:40:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:40:47 INFO - 'MOZ_WEBRENDER': '1', 03:40:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:47 INFO - 'NO_EM_RESTART': '1', 03:40:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:47 INFO - 'PWD': '/builds/slave/test', 03:40:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:40:47 INFO - 'RUST_BACKTRACE': 'full', 03:40:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:40:47 INFO - 'SHELL': '/bin/bash', 03:40:47 INFO - 'SHLVL': '1', 03:40:47 INFO - 'TERM': 'linux', 03:40:47 INFO - 'TMOUT': '86400', 03:40:47 INFO - 'USER': 'cltbld', 03:40:47 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:47 INFO - '_': '/tools/buildbot/bin/python'} 03:40:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 03:40:48 INFO - mozversion application_buildid: 20170905094120 03:40:48 INFO - mozversion application_changeset: 3ecda4678c49ca255c38b1697142b9118cdd27e7 03:40:48 INFO - mozversion application_display_name: Nightly 03:40:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:40:48 INFO - mozversion application_name: Firefox 03:40:48 INFO - mozversion application_remotingname: firefox 03:40:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:40:48 INFO - mozversion application_vendor: Mozilla 03:40:48 INFO - mozversion application_version: 57.0a1 03:40:48 INFO - mozversion platform_buildid: 20170905094120 03:40:48 INFO - mozversion platform_changeset: 3ecda4678c49ca255c38b1697142b9118cdd27e7 03:40:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:40:48 INFO - mozversion platform_version: 57.0a1 03:40:48 INFO - using testdate: 1504608048 03:40:48 INFO - actual date: 1504608048 03:40:48 INFO - starting webserver on 'localhost:45957' 03:40:48 INFO - SUITE-START | Running 2 tests 03:40:48 INFO - TEST-START | dromaeo_css 03:40:48 INFO - Initialising browser for dromaeo_css test... 03:40:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45957/getInfo.html -profile /tmp/tmpP81WMw/profile 03:40:48 INFO - TEST-INFO | started process 21744 (/builds/slave/test/build/application/firefox/firefox http://localhost:45957/getInfo.html) 03:40:54 INFO - TEST-INFO | 21744: exit 0 03:40:54 INFO - Browser initialized. 03:40:54 INFO - Running cycle 1/1 for dromaeo_css test... 03:40:54 INFO - Using env: {'DISPLAY': ':0', 03:40:54 INFO - 'HOME': '/home/cltbld', 03:40:54 INFO - 'JSGC_DISABLE_POISONING': '1', 03:40:54 INFO - 'LANG': 'en_US.UTF-8', 03:40:54 INFO - 'LANGUAGE': 'en_US:en', 03:40:54 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:40:54 INFO - 'LOGNAME': 'cltbld', 03:40:54 INFO - 'MAIL': '/var/mail/cltbld', 03:40:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:40:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:40:54 INFO - 'MOZ_ACCELERATED': '1', 03:40:54 INFO - 'MOZ_CRASHREPORTER': '1', 03:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:54 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:40:54 INFO - 'MOZ_NO_REMOTE': '1', 03:40:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:40:54 INFO - 'MOZ_WEBRENDER': '1', 03:40:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:54 INFO - 'NO_EM_RESTART': '1', 03:40:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:54 INFO - 'PWD': '/builds/slave/test', 03:40:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:40:54 INFO - 'RUST_BACKTRACE': 'full', 03:40:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:40:54 INFO - 'SHELL': '/bin/bash', 03:40:54 INFO - 'SHLVL': '1', 03:40:54 INFO - 'TERM': 'linux', 03:40:54 INFO - 'TMOUT': '86400', 03:40:54 INFO - 'USER': 'cltbld', 03:40:54 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:54 INFO - '_': '/tools/buildbot/bin/python'} 03:40:54 INFO - TEST-INFO | started process 21940 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP81WMw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:40:55 INFO - PID 21940 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:40:55 INFO - PID 21940 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:40:55 INFO - PID 21940 | 03:40:55 INFO - PID 21940 | (/builds/slave/test/build/application/firefox/firefox:22003): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:40:55 INFO - PID 21940 | 03:41:05 INFO - PID 21940 | RSS: Main: 200908800 03:41:05 INFO - PID 21940 | 03:41:09 INFO - PID 21940 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:44:05 INFO - PID 21940 | Cycle 1(1): loaded http://localhost:45957/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45957/tests/dromaeo/cssquery-ext.html) 03:44:07 INFO - PID 21940 | RSS: Main: 215920640 03:44:07 INFO - PID 21940 | 03:47:09 INFO - PID 21940 | Cycle 1(1): loaded http://localhost:45957/tests/dromaeo/cssquery-ext.html (next: http://localhost:45957/tests/dromaeo/cssquery-jquery.html) 03:47:09 INFO - PID 21940 | RSS: Main: 212963328 03:47:09 INFO - PID 21940 | 03:50:11 INFO - PID 21940 | Cycle 1(1): loaded http://localhost:45957/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45957/tests/dromaeo/cssquery-mootools.html) 03:50:12 INFO - PID 21940 | RSS: Main: 215949312 03:50:12 INFO - PID 21940 | 03:53:14 INFO - PID 21940 | Cycle 1(1): loaded http://localhost:45957/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45957/tests/dromaeo/cssquery-prototype.html) 03:53:15 INFO - PID 21940 | RSS: Main: 214315008 03:53:15 INFO - PID 21940 | 03:56:19 INFO - PID 21940 | Cycle 1(1): loaded http://localhost:45957/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45957/tests/dromaeo/cssquery-yui.html) 03:56:19 INFO - PID 21940 | RSS: Main: 218263552 03:56:19 INFO - PID 21940 | 03:59:21 INFO - PID 21940 | Cycle 1(1): loaded http://localhost:45957/tests/dromaeo/cssquery-yui.html (next: http://localhost:45957/tests/dromaeo/cssquery-dojo.html) 03:59:22 INFO - PID 21940 | RSS: Main: 218046464 03:59:22 INFO - PID 21940 | 03:59:22 INFO - PID 21940 | __start_tp_report 03:59:22 INFO - PID 21940 | _x_x_mozilla_page_load 03:59:22 INFO - PID 21940 | _x_x_mozilla_page_load_details 03:59:22 INFO - PID 21940 | |i|pagename|runs| 03:59:22 INFO - PID 21940 | |0;dojo.html;3306.8511916963744;3336.849841757121;3362.5124356968236;3371.6796904294097;3383.966160338399;6276.843078923029;6301.968490157555;6487.708053137609;6505.707243174057;6539.5095367847425;6427.775027874025;6433.067205255235;6443.355664433554;6457.806265812033;6463.127477790497;5876.294844618652;5885.558583106263;5892.734826932777;5925.466707996279;5937.495312898409;5952.255968003999;5984.04255319149;5990.940090599103;5993.430624090704;6007.309159446676;5961.791337303195;5972.492338151263;6012;6033.637981721111;6066.454019138277;5814.941850581482;5826.592138550303;5829.067349224104;5868.501177399926;5947.49446297061;5801.767929282823;5876.853078673024;5897.587168898179;5914.9112763308585;6014.458698717092;6174.84562885927;6249.312575616678;6375.362463753634;6447.935520644827;6460.903086453707;6888.4144847688;6915.861682766316;6966.79099627012;6977.441804655616;6981.3367730066075;7002.47481438889;7083.504154709172;7085.751998680022;7086.610236436994;7092.219855815856;6436.356364363526;6444.838879028015;6453.322401147906;6534.4445722113205;6563.884139696168;6862.828429289208;7020.403265722471;7061.32917372859;7069.787906362817;7149.285071492809;6670.166229221348;6695.397414232742;6709.36261055199;6734.326567343226;6735.629540375228;6779.898301525481;6833.692483838239;6834.384905358542;6837.042814006043;6840.760573379908;6798.93201067983;6800.6939687714175;6838;6840.589564626039;6846.212685541127;6778.559393639398;6789.864202715918;6800.6939687714175;6815.42068924147;6859.2454829968665;4836.395450568679;5299.5230429261555;5382.2464854920345;5395.136778115482;5407.540359069444;4753.786079626356;4815.807367705253;4826.6621336506105;4835.081334546425;4840.540148685869;5940.138679891392;5992.370801065913;5993.4905533029305;6072.605280656831;6082.330943596201;6783.559068660521;6869.381755641916;6877.346652068146;6915.446764258847;7012.333828286304;5895.734691938873;5897.085951677497;5914.0241860093465;5932.169496270526;5933;5822.621529600562;5860.677662728591;5863.325717542537;5936.5250779937505;5995;6570.375814297729;6650.501212409089;6663.233728121231;6770.1537307836525;6776.390124888783;6715.1941766988275;6886.724531018703;6916.20463646687;7006.439484841098;7038.718451261892;6589.934100658932;6673.83315417099;6789.694463749044;6792.286809884891;6794.3545363190415;6716.731330746716;6810.080639113854;6885.173779146535;6885.586864788129;6917.412019978157;6794;6918.619475928671;6932.93067069343;7014.228434872058;7090.7518236863525;6639.6680165992475;6695.4308883745425;6753.628550429774;6756.155480564929;6812.93187068123;6736.562123461959;6778.593284402951;6791.762288319885;6797.626130562669;6801.829954251183;6533.444657204191;6657.600543967377;6662.83342916431;6665.533412661067;6756;6594.0108983652835;6683.298253683488;6753.257141714505;6759.290274521104;6860.691268892812;6454.806355809334;6485.967570162119;6528.67356632176;6613.338666133348;6620.337966203341 03:59:22 INFO - PID 21940 | |1;ext.html;788.341734651566;829.1211316005039;847.894013248344;880.951547664879;883.7348795361391;40126;40769;40788.184236315254;41044.58955410445;41049;37326.25347493048;37598.62401375985;37652.81173594135;37670.8116459418;37686;6783.762568310122;6894.448444124458;6907.447404207676;6908.654567271641;6913.27410621885;6810.523263371566;6886.311368863098;6916.481263905227;6943.097397338339;6945.58326500409;6884.621345825977;6922.342377474157;6929.514933954625;7458.366038886674;7648.043994500687;7521.999999999973;7537.886931696029;7594.430417718638;7611.619419029082;7621.16167221608;7487.588182649952;7490.176080631126;7498.700051997914;7498.925010749932;7510.436717246173;37074.258514829824;37097.62902370969;37182.628173718185;37265.25469490622;37602.623973760186;3572.9464058039152;3578.481120237577;3594.0655429588496;3611.5305010348748;3622.800745958971;30434.391312173855;30536.389272214652;30635.234119146975;30762.84618576893;30779.076627700982;5063.493650634944;5151;5167.509086636764;5223.947760522384;5243.187305967581;182698;183210;183853;195651;199792;47666.285005724945;48195.277070843964;48199.5180048202;48241.27638085432;48318.758406207744;49839.25241121386;50135;50213.74893125584;50315;50685.74657126691;15985.280662370222;16183.757243641356;16188.59528511773;16203;16303.673926521404;13530.661733456742;13539.052266341458;13543.39054742539;13548.322583870768;13552.45790168402;13988.160710357412;14098.506552270826;14211.431542738175;14245.43018279278;14282.785758213635;5657.32112146545;5713.457221564028;5758.539316854725;5764.308283006066;5778.595498315078;984.8375018122073;1007.5415685862915;1008;1009.7980403919246;1018.4500369800224;21570.24504142379;22247.332580022303;22340.441488962904;22390;22425.66361504579;1109.362116782837;1115.263925808962;1115.9218854680257;1116.4585176189541;1133.167122165208;11497.827532587018;11981.820272695917;12005.699857503632;12080.154389192849;12127.272363658209;228442;229333;230517;230600;231980;50737.23894141591;50752.746236268584;50784.74607627012;50828.74585627122;50978.490215098114;1406.2827957741422;1449.8260208774805;1450.6663467402557;1455.0542147604142;1464.253230852273;21884.781152188276;22096.33710988673;22368.690629075674;22755.77244227603;22786.316410507712;33234.16914577155;33250.501242480896;33333.49999750054;33354;33410.49884251689;193330;193470;193880;194336;194437;29581;30170.3965920676;32992.340153197285;33212;33867.830660846535;41599;41853.58146418497;42314;42415.7879210602;42764.57235427606;31231.219219519695;31326.686733132377;31406.214844628154;31535;31629.8418507906;30916.536251956688;31070.689293106778;31147.532787008666;31183;31222.843885780425;34627;34853.30293394169;34925.65074349326;35167.12082197965;35261.29477410386;29880.85059574688;30006.24984375408;30419.847900760353;30516.237094072825;30524.084277471713;22592.661110083034;22873.88563057174;23235.186768463023;23301.18445854387;23429.648555272026 03:59:22 INFO - PID 21940 | |2;jquery.html;558.35231131887;568.6303902463401;569.3680015183146;572.4332910418684;579.1746760865767;22010.11959521619;22884.54230915383;22968.88515557422;23021.884890575566;23071.653925191134;24518;26640.200793976164;26696.065637702683;26785;26812.65936703167;6558.770443034495;6781.898271525931;6782.220044694874;6782.287859774727;6789.456843452536;6606.801795946105;6628.668566571676;6692.63190524521;6701.564398314118;6703.1956165260235;4048.8785336439805;4064.5325787534516;4874.268859671042;5972.6715030673295;6654.866902661944;6549.050387693782;6573.046908198308;6580.736770529173;6591;6619.4704423646235;6088.9391106088815;6090.695465226765;6101.633901965896;6102.786402475892;6118.112873633343;27833.443331133265;29374.412511749644;29773;29843;29870.253243668863;1977.1597071244664;2039.6328660841043;2041.1325186795552;2042.2647846775149;2048.3445297504804;18442.35451759182;18933.90533047326;18937.242510299573;18966.146523406478;18971.62056758871;2367.124164059309;2377.0848223434105;2422.958128004957;2434;2462.3105530451503;138598;138836;139574.30212848872;141602.99999999898;142292;14153.787693184611;14533.636659083608;14622.634434139232;14924.179170145746;15188.848111518964;23415.53168936612;23511.29466116019;23545.529089418116;23547.7645223549;23552.411189720395;10216.6424175155;10340.482975851177;10426.635067772591;10597;10702.785944281226;9653.276004299705;9785.559649815776;9808.705738827846;9948.253880958957;9953.900460995443;7458.179600243969;8678.783030424163;9003.504807235533;9031.909680903238;9203.217726493323;2763.226296636945;2774.3618967637153;2786.4009238013923;2801.2716693659386;2813.2404250852564;977.4623956823718;991.108002797488;997.9700608981597;998.0967224661548;1009.0615727373466;23451;23564.410889727893;24590.631140532907;24670.259892203263;24768.876155619106;1037.9896201038043;1046.7226185060965;1053.9262251642465;1069.315637991686;1075.349413604758;10720.035196832323;10920.126389888948;10968.122550196125;11144.164187685956;11151.609693660845;485124;487854;497000;497861;502777;123188;125775;125869;126817;129142;1230.698478872682;1251.1554700577074;1284.5825106840314;1287.6394609509352;1289.8968082553374;18074.548136296697;18161.909190453964;18165.36511713952;18362.724559131355;18485.72271415903;58393.4160658388;60023.69988150206;60854.39145608487;61298;61533.692331538055;75661.24338756542;78047.60976195309;78275.60862195652;78369.60815195888;79238.20761792308;14402.279886005448;14526.273686315431;14708.852911470749;14727.484538041117;14816.851831481546;22732.659010115192;22850.657240141743;23131.768682313636;23183.304500865;23217.535649287256;18406.079696015415;18499.445016649523;18645;18865.528361791065;18891.244350225836;16611.169441528116;16742.58143546421;17079.7438038432;17234.569135771708;17284.222210000327;18766.15552300177;18877.811221888154;18957.810421895603;19030.143643535797;19038.428847134608;14218.289085545475;14267;14311.427542898584;14316.284185790877;14486.420543178156;18212;18363.63272734511;18430.723539146657;18464.076796160407;18754.624907502046 03:59:22 INFO - PID 21940 | |3;mootools.html;1283.6405806374216;1352.4792954712436;1354.850966393697;1359.1776974930174;1359.354306704315;14313.78529322061;14831.62920926976;14907.92546037271;14944.327505262263;14994.700105997887;14731.48439804607;15717.921410392932;15784.210789460538;15825.683486330267;15826.05043697379;2768.1695491352616;2832.815866968651;2835.0927703135003;2837.588549660298;2840.5313123334727;2658.9335266618295;2753.1465245773875;2832.815866968651;2835.6030155778103;2866.4697031049323;2814.057290807582;2845.146456063183;2860.0847728726817;2863.026570965871;2870.1963450233966;2674.9197524074307;2705.47243287559;2710.132757517605;2759.0481283957;2778.7221277872254;2669.399385138348;2670.7462791034823;2673.625692403065;2683.0206974454377;2689.9865500672568;14974.101553906801;15214.391424343125;15278.465253716178;15296.847031529653;15303.846961530353;8063.798405039863;8292.460990035628;8314.210150035737;8331.83336333276;8336;5112.769925353368;5508.9724551377385;5510.071474983763;5543.279373681396;5670;1953.3358658056186;1957.168203513501;1984.6030793841146;2030.35028790787;2030.9086091125935;25572;25754.227373178834;25775.742242577522;25783.484330313288;25803.967841286325;6632.204135503771;6725.361090696376;6758.695858686371;6852.417544508673;6898.585789913056;17279.222434990203;17285.308587656353;17339.826601734072;17386.13069346553;19823.60352792965;8036.316913062338;8223.177682231848;8241.217084376975;8245.381596380295;8247.340212782963;6867.587944723333;7149.285071492913;7213.278672132745;7259.165196002527;7300.707971681073;5662.886742265132;5855.092460668603;5865.618734782225;5882.735276912549;5928.703564821742;1624.6750649870073;1666.8333166683476;1671.5236157694999;1715.828417158274;1748.1084646830209;864.8875646165958;880.9911900881037;889.3330002498126;891.4695756025154;905.5110240470073;7599.886001709979;7799.45403821727;8019.799505012421;8032.0763112241675;8087.919120808717;939.7180845746385;958.9568469418754;960.3373672166183;966.8936416994125;979.9510024498746;4236.830526778895;4327.221100201994;4428.977855110768;4994.250862370601;5084.847454576368;20581.794182058286;21264.78735212659;21517.246896358552;21846.781532184792;21946.670799938012;20752.584948300948;20954.895225523775;21045.684314735292;21309.46726331854;21512.892435537724;11269.380184089954;11343.773124537463;11429.257098288585;11436.542538298541;11548.942255288837;7469.178390377163;7795.766127016199;7825.4130940178575;7849.568273744999;7957.323627491728;17972.281108755502;18999;19453.708194376813;19477.61044779125;19491.70762438593;18472.168752405905;19011;19316.227350905807;19322.903385483543;19414.2234310626;11818.113641476752;12207.69480762988;12245.326507042199;12267.57063502773;12286.87713122893;13181.538646147339;13225.735485290434;13290.468381264642;13295;13363.331833408485;8546.957265213634;9952.402855828674;11068.944655276671;11275.267107637977;11353.148513861328;10728.785424291314;10892.7276818077;11028.28316159447;11101.50043248072;11275.605353812578;9702.805943881223;9943.25425593069;10021.39871607706;10021.849672254777;10030.598776048877;6721.697523611351;6847.794566162824;6884.311568843075;6885.862282754417;6940.271271516418;6980.441564674712;7001.929980700128;7006.614636194854;7017.789466316019;7067.434605231672 03:59:22 INFO - PID 21940 | |4;prototype.html;497.07718614546474;505.0051398744472;522.5427750718121;528.3738769558074;529.7854368980564;6434.581752186116;6472.255690595583;6499.512536559753;6544.836379090525;6553.934460655392;6143.723532441039;6336.112944187806;6354.68226588671;6360.886844802164;6362.963851567501;5099;5125.513076257768;5143.27994080828;5166.328377310944;5187.844364669066;5078.720670363128;5122.795088196468;5149.17613181891;5155.3040339554245;5198.246254293125;4842.806287748486;5021.447640759514;5050.898982020358;5085;5115.693058416488;5094.261332106824;5100.387953445573;5106.4382917879;5119.897602047939;5132.692038477703;4516.23224051912;4528.388667529907;4529.230030894756;4532.456105267389;4594.517575654541;6264.749410023594;6284.591501552384;6322.0200868865395;6351.2060992375955;6352.872942541123;5831.329397119343;5834.474897259224;5856.385079566627;5862;5869.765209391662;6699.866002679968;6747.460203183734;6761.797146085625;6779.559328643622;6801.4898882584;4928.1129396708575;4939.456659767423;4943.901121977577;4957.6033917286895;4970.6520543561965;6946.479014073965;7008.684609192598;7033.839416496272;7081.185663648644;7140.642967851587;5071.2139618359215;5167.405748338989;5168.664036837594;5181.948180518222;5199.610029247821;5167.560757335594;5189.922151167735;5191.169412893919;5203.479652034766;5244.931714946722;5615.9610472062795;5750.1949727038245;5773.7955434733285;5779.190913272145;5847;6585.604863708193;6668.199816022011;6709.463242940553;6776.356246156607;6778.76300820728;1769.6020144086285;1779.80422153563;1783.304511240617;1814.8729588928911;1835.3759721694687;1137.1357768096307;1145.5360578965533;1188.803847365175;1211.418519110814;1256.1897576063388;5921.851953701192;5952.791652292149;5956.344802071769;5959.4934430573885;6022.819315420545;6747.954067119605;6813.148356455443;6852.897206541906;6879.862402752017;6930.44556435484;5890.352061273256;5895.616784909053;5904.1734157218025;5941.465268125889;5963.479312775215;3589.515415418885;3644.2347107107803;3681.9631803682155;3682.1957240951742;3688.981555092207;6506.763714894155;6559.901601475982;6641.833954151185;6674.966625166843;6680.331966803281;6872.209695884937;7065.1521817382245;7068.151821781419;7104.538205016761;7149.785506434711;6590.373914478117;6602.141721576164;6614.536982411185;6638.402543771083;6735.865282694319;6812;6872.41584465326;6881.759138430131;6891.345322194384;6915.654217289216;6729.332513779904;6775.966120169368;6826.385625293548;6838.350356716104;6873.896891546731;6583;6647.235567909753;6670.132362938385;6683;6696.933030669826;5087.389513258433;5105.744712764273;5109.872253193551;5114.820981265638;5122.359705036871;6741.258461569126;6801.387875091068;6839.452843772586;6915.170179578483;6973.779588572081;6569.9343006569325;6588.439982601532;6670.666466676744;6673.1992160941;6674.83312917181;6522.28254891971;6621.735130594722;6653.667316634052;6669.566478178903;6720.563163394364;6059.394060593905;6504.349565043458;6553.705083271359;6583.572067815577;6593.571417857824;6700.6984685688285;6733.383987842784;6739.865202696017;6789.574189420079;6797.286284940208;6419.743210271537;6471;6484.0771333342645;6499.285078641251;6511.153550038464 03:59:22 INFO - PID 21940 | |5;yui.html;897.8877640294962;910.849709797883;924.7595625137462;924.796544760153;925.3753716241538;18901.905490472545;19676.901615491937;19687.606247875035;19721.21115155392;19723.21107155714;15620;16368.75446868295;18826.529336766554;19101.713474297896;19499.41501754946;1002.2733518199284;1027.7276521721749;1029.8867124616286;1029.9485025748684;1030.1141018723893;965.1554889471712;983.2527279267772;994.9452780097092;995.9253056020791;997.5162046407523;1058.4866339825178;1059.2055958031476;1061.7345663584103;1062.075993885321;1067.9466026698674;1053.257453495284;1056.5351245451977;1060.8833028366873;1063.2185343772321;1064.8296272596385;765.5674543882689;772.6870617399962;773.443120952913;773.5126870071875;774.2180397798264;20127.194912203493;20181.798182018138;20275;20469.5906081879;20929.162833486644;21296.574068518697;21527.138914443403;21825.563488730295;22067.558648826933;22108;17241.82758172415;17588.12059397038;17702.203400846993;17708.114594270235;17877.642447151113;3699.667029967316;3749.531308586427;3750.7374483786148;3754.1928485375774;3772;128303;130247;131004;131846.3407682965;132279;16897.91551042237;16903.577410564834;16918.661626767644;17098.743518847226;17308;29256.853715731286;29651.85174074116;29862.85068574643;29937.550936735966;30103.698963010527;8529.65881364751;8551.1448855114;8554.486730796172;8579.699710510227;8646.481211127353;4046.979765101156;4185.372194170899;4222.19778242133;4249.022724773257;4281.529031806499;4765.11845308619;4972.90054198914;5156.767945442464;5689.715514224272;5869.5010924071075;1094.3871431997948;1138.248755821154;1144.0790163918125;1144.6966553863233;1146.363768108691;668.7157957867887;689.4036658290543;712.9572225666373;715.8711431942196;719.7301012120455;11259.605913792979;11628.94185529084;11837.88162118385;12101.334426606445;12331.335269738649;670.6546128743701;695.8295217671601;699.1959246866043;707.844274259662;713.1940906775415;5175.870603234949;5234.581233501311;5239.397469291081;5315.01672190646;5360.383555891044;103522;110362.44818775855;111322;114993;115332;91337.7329929503;94849;95134.52432737792;97067.51466242624;101225.49387253165;82790.17209827945;87032.5648371754;87364;87884.56057719671;89161;11894.940525297317;11953.163278570417;12056.879431205576;12061.698457538632;12559.497620095459;38972.80513597414;39894;40530.59469405386;41417.58582414137;42359.78820106003;69540;72347.27652723405;72978;73329.6333518329;74577.25422745703;17280.135993200038;17352.739708904628;17582.38461653887;17707.822921770618;17966.640667186322;19763.505912352306;20109.49726256797;20172.79827201709;20394.184232630527;20761.377158685264;17867.195976180843;17877.553061173574;17954.551136221176;18336.358227461973;18545.165467554263;16436.096014719304;16584.66830663404;16750.916245418695;16756;16948.661026779642;17548.561285967953;17672.293108275524;17757.556061098578;18003.18985645623;18476.722849157006;11745.236559623598;11966.46150923228;12425.19236249641;12789.424475898793;12940;12990.610281691565;13320.666983325495;14036.78944815808;14130.78803817923;14352.784708229594 03:59:22 INFO - PID 21940 | __end_tp_report 03:59:22 INFO - PID 21940 | __start_cc_report 03:59:22 INFO - PID 21940 | _x_x_mozilla_cycle_collect,368 03:59:22 INFO - PID 21940 | __end_cc_report 03:59:22 INFO - PID 21940 | __startTimestamp1504609162083__endTimestamp 03:59:22 INFO - PID 21940 | 03:59:22 INFO - PID 21940 | ------- Summary: start ------- 03:59:22 INFO - PID 21940 | Number of tests: 6 03:59:22 INFO - PID 21940 | 03:59:22 INFO - PID 21940 | [#0] dojo.html Cycles:165 Average:6341.71 Median:6613.34 stddev:747.62 (11.3%) stddev-sans-first:711.00 03:59:22 INFO - PID 21940 | Values: 3306.9 3336.8 3362.5 3371.7 3384.0 6276.8 6302.0 6487.7 6505.7 6539.5 6427.8 6433.1 6443.4 6457.8 6463.1 5876.3 5885.6 5892.7 5925.5 5937.5 5952.3 5984.0 5990.9 5993.4 6007.3 5961.8 5972.5 6012.0 6033.6 6066.5 5814.9 5826.6 5829.1 5868.5 5947.5 5801.8 5876.9 5897.6 5914.9 6014.5 6174.8 6249.3 6375.4 6447.9 6460.9 6888.4 6915.9 6966.8 6977.4 6981.3 7002.5 7083.5 7085.8 7086.6 7092.2 6436.4 6444.8 6453.3 6534.4 6563.9 6862.8 7020.4 7061.3 7069.8 7149.3 6670.2 6695.4 6709.4 6734.3 6735.6 6779.9 6833.7 6834.4 6837.0 6840.8 6798.9 6800.7 6838.0 6840.6 6846.2 6778.6 6789.9 6800.7 6815.4 6859.2 4836.4 5299.5 5382.2 5395.1 5407.5 4753.8 4815.8 4826.7 4835.1 4840.5 5940.1 5992.4 5993.5 6072.6 6082.3 6783.6 6869.4 6877.3 6915.4 7012.3 5895.7 5897.1 5914.0 5932.2 5933.0 5822.6 5860.7 5863.3 5936.5 5995.0 6570.4 6650.5 6663.2 6770.2 6776.4 6715.2 6886.7 6916.2 7006.4 7038.7 6589.9 6673.8 6789.7 6792.3 6794.4 6716.7 6810.1 6885.2 6885.6 6917.4 6794.0 6918.6 6932.9 7014.2 7090.8 6639.7 6695.4 6753.6 6756.2 6812.9 6736.6 6778.6 6791.8 6797.6 6801.8 6533.4 6657.6 6662.8 6665.5 6756.0 6594.0 6683.3 6753.3 6759.3 6860.7 6454.8 6486.0 6528.7 6613.3 6620.3 03:59:22 INFO - PID 21940 | 03:59:22 INFO - PID 21940 | [#1] ext.html Cycles:180 Average:36726.69 Median:33343.75 stddev:53191.13 (159.5%) stddev-sans-first:53271.89 03:59:22 INFO - PID 21940 | Values: 788.3 829.1 847.9 881.0 883.7 40126.0 40769.0 40788.2 41044.6 41049.0 37326.3 37598.6 37652.8 37670.8 37686.0 6783.8 6894.4 6907.4 6908.7 6913.3 6810.5 6886.3 6916.5 6943.1 6945.6 6884.6 6922.3 6929.5 7458.4 7648.0 7522.0 7537.9 7594.4 7611.6 7621.2 7487.6 7490.2 7498.7 7498.9 7510.4 37074.3 37097.6 37182.6 37265.3 37602.6 3572.9 3578.5 3594.1 3611.5 3622.8 30434.4 30536.4 30635.2 30762.8 30779.1 5063.5 5151.0 5167.5 5223.9 5243.2 182698.0 183210.0 183853.0 195651.0 199792.0 47666.3 48195.3 48199.5 48241.3 48318.8 49839.3 50135.0 50213.7 50315.0 50685.7 15985.3 16183.8 16188.6 16203.0 16303.7 13530.7 13539.1 13543.4 13548.3 13552.5 13988.2 14098.5 14211.4 14245.4 14282.8 5657.3 5713.5 5758.5 5764.3 5778.6 984.8 1007.5 1008.0 1009.8 1018.5 21570.2 22247.3 22340.4 22390.0 22425.7 1109.4 1115.3 1115.9 1116.5 1133.2 11497.8 11981.8 12005.7 12080.2 12127.3 228442.0 229333.0 230517.0 230600.0 231980.0 50737.2 50752.7 50784.7 50828.7 50978.5 1406.3 1449.8 1450.7 1455.1 1464.3 21884.8 22096.3 22368.7 22755.8 22786.3 33234.2 33250.5 33333.5 33354.0 33410.5 193330.0 193470.0 193880.0 194336.0 194437.0 29581.0 30170.4 32992.3 33212.0 33867.8 41599.0 41853.6 42314.0 42415.8 42764.6 31231.2 31326.7 31406.2 31535.0 31629.8 30916.5 31070.7 31147.5 31183.0 31222.8 34627.0 34853.3 34925.7 35167.1 35261.3 29880.9 30006.2 30419.8 30516.2 30524.1 22592.7 22873.9 23235.2 23301.2 23429.6 03:59:22 INFO - PID 21940 | 03:59:22 INFO - PID 21940 | [#2] jquery.html Cycles:180 Average:35667.08 Median:23316.53 stddev:83686.82 (358.9%) stddev-sans-first:83880.07 03:59:22 INFO - PID 21940 | Values: 558.4 568.6 569.4 572.4 579.2 22010.1 22884.5 22968.9 23021.9 23071.7 24518.0 26640.2 26696.1 26785.0 26812.7 6558.8 6781.9 6782.2 6782.3 6789.5 6606.8 6628.7 6692.6 6701.6 6703.2 4048.9 4064.5 4874.3 5972.7 6654.9 6549.1 6573.0 6580.7 6591.0 6619.5 6088.9 6090.7 6101.6 6102.8 6118.1 27833.4 29374.4 29773.0 29843.0 29870.3 1977.2 2039.6 2041.1 2042.3 2048.3 18442.4 18933.9 18937.2 18966.1 18971.6 2367.1 2377.1 2423.0 2434.0 2462.3 138598.0 138836.0 139574.3 141603.0 142292.0 14153.8 14533.6 14622.6 14924.2 15188.8 23415.5 23511.3 23545.5 23547.8 23552.4 10216.6 10340.5 10426.6 10597.0 10702.8 9653.3 9785.6 9808.7 9948.3 9953.9 7458.2 8678.8 9003.5 9031.9 9203.2 2763.2 2774.4 2786.4 2801.3 2813.2 977.5 991.1 998.0 998.1 1009.1 23451.0 23564.4 24590.6 24670.3 24768.9 1038.0 1046.7 1053.9 1069.3 1075.3 10720.0 10920.1 10968.1 11144.2 11151.6 485124.0 487854.0 497000.0 497861.0 502777.0 123188.0 125775.0 125869.0 126817.0 129142.0 1230.7 1251.2 1284.6 1287.6 1289.9 18074.5 18161.9 18165.4 18362.7 18485.7 58393.4 60023.7 60854.4 61298.0 61533.7 75661.2 78047.6 78275.6 78369.6 79238.2 14402.3 14526.3 14708.9 14727.5 14816.9 22732.7 22850.7 23131.8 23183.3 23217.5 18406.1 18499.4 18645.0 18865.5 18891.2 16611.2 16742.6 17079.7 17234.6 17284.2 18766.2 18877.8 18957.8 19030.1 19038.4 14218.3 14267.0 14311.4 14316.3 14486.4 18212.0 18363.6 18430.7 18464.1 18754.6 03:59:22 INFO - PID 21940 | 03:59:22 INFO - PID 21940 | [#3] mootools.html Cycles:180 Average:9297.87 Median:2678.97 stddev:6618.11 (247.0%) stddev-sans-first:6609.28 03:59:22 INFO - PID 21940 | Values: 1283.6 1352.5 1354.9 1359.2 1359.4 14313.8 14831.6 14907.9 14944.3 14994.7 14731.5 15717.9 15784.2 15825.7 15826.1 2768.2 2832.8 2835.1 2837.6 2840.5 2658.9 2753.1 2832.8 2835.6 2866.5 2814.1 2845.1 2860.1 2863.0 2870.2 2674.9 2705.5 2710.1 2759.0 2778.7 2669.4 2670.7 2673.6 2683.0 2690.0 14974.1 15214.4 15278.5 15296.8 15303.8 8063.8 8292.5 8314.2 8331.8 8336.0 5112.8 5509.0 5510.1 5543.3 5670.0 1953.3 1957.2 1984.6 2030.4 2030.9 25572.0 25754.2 25775.7 25783.5 25804.0 6632.2 6725.4 6758.7 6852.4 6898.6 17279.2 17285.3 17339.8 17386.1 19823.6 8036.3 8223.2 8241.2 8245.4 8247.3 6867.6 7149.3 7213.3 7259.2 7300.7 5662.9 5855.1 5865.6 5882.7 5928.7 1624.7 1666.8 1671.5 1715.8 1748.1 864.9 881.0 889.3 891.5 905.5 7599.9 7799.5 8019.8 8032.1 8087.9 939.7 959.0 960.3 966.9 980.0 4236.8 4327.2 4429.0 4994.3 5084.8 20581.8 21264.8 21517.2 21846.8 21946.7 20752.6 20954.9 21045.7 21309.5 21512.9 11269.4 11343.8 11429.3 11436.5 11548.9 7469.2 7795.8 7825.4 7849.6 7957.3 17972.3 18999.0 19453.7 19477.6 19491.7 18472.2 19011.0 19316.2 19322.9 19414.2 11818.1 12207.7 12245.3 12267.6 12286.9 13181.5 13225.7 13290.5 13295.0 13363.3 8547.0 9952.4 11068.9 11275.3 11353.1 10728.8 10892.7 11028.3 11101.5 11275.6 9702.8 9943.3 10021.4 10021.8 10030.6 6721.7 6847.8 6884.3 6885.9 6940.3 6980.4 7001.9 7006.6 7017.8 7067.4 03:59:22 INFO - PID 21940 | 03:59:22 INFO - PID 21940 | [#4] prototype.html Cycles:180 Average:5637.47 Median:6343.66 stddev:1586.15 (25.0%) stddev-sans-first:1542.96 03:59:22 INFO - PID 21940 | Values: 497.1 505.0 522.5 528.4 529.8 6434.6 6472.3 6499.5 6544.8 6553.9 6143.7 6336.1 6354.7 6360.9 6363.0 5099.0 5125.5 5143.3 5166.3 5187.8 5078.7 5122.8 5149.2 5155.3 5198.2 4842.8 5021.4 5050.9 5085.0 5115.7 5094.3 5100.4 5106.4 5119.9 5132.7 4516.2 4528.4 4529.2 4532.5 4594.5 6264.7 6284.6 6322.0 6351.2 6352.9 5831.3 5834.5 5856.4 5862.0 5869.8 6699.9 6747.5 6761.8 6779.6 6801.5 4928.1 4939.5 4943.9 4957.6 4970.7 6946.5 7008.7 7033.8 7081.2 7140.6 5071.2 5167.4 5168.7 5181.9 5199.6 5167.6 5189.9 5191.2 5203.5 5244.9 5616.0 5750.2 5773.8 5779.2 5847.0 6585.6 6668.2 6709.5 6776.4 6778.8 1769.6 1779.8 1783.3 1814.9 1835.4 1137.1 1145.5 1188.8 1211.4 1256.2 5921.9 5952.8 5956.3 5959.5 6022.8 6748.0 6813.1 6852.9 6879.9 6930.4 5890.4 5895.6 5904.2 5941.5 5963.5 3589.5 3644.2 3682.0 3682.2 3689.0 6506.8 6559.9 6641.8 6675.0 6680.3 6872.2 7065.2 7068.2 7104.5 7149.8 6590.4 6602.1 6614.5 6638.4 6735.9 6812.0 6872.4 6881.8 6891.3 6915.7 6729.3 6776.0 6826.4 6838.4 6873.9 6583.0 6647.2 6670.1 6683.0 6696.9 5087.4 5105.7 5109.9 5114.8 5122.4 6741.3 6801.4 6839.5 6915.2 6973.8 6569.9 6588.4 6670.7 6673.2 6674.8 6522.3 6621.7 6653.7 6669.6 6720.6 6059.4 6504.3 6553.7 6583.6 6593.6 6700.7 6733.4 6739.9 6789.6 6797.3 6419.7 6471.0 6484.1 6499.3 6511.2 03:59:22 INFO - PID 21940 | 03:59:22 INFO - PID 21940 | [#5] yui.html Cycles:180 Average:23828.38 Median:19743.36 stddev:32674.03 (165.5%) stddev-sans-first:32720.32 03:59:22 INFO - PID 21940 | Values: 897.9 910.8 924.8 924.8 925.4 18901.9 19676.9 19687.6 19721.2 19723.2 15620.0 16368.8 18826.5 19101.7 19499.4 1002.3 1027.7 1029.9 1029.9 1030.1 965.2 983.3 994.9 995.9 997.5 1058.5 1059.2 1061.7 1062.1 1067.9 1053.3 1056.5 1060.9 1063.2 1064.8 765.6 772.7 773.4 773.5 774.2 20127.2 20181.8 20275.0 20469.6 20929.2 21296.6 21527.1 21825.6 22067.6 22108.0 17241.8 17588.1 17702.2 17708.1 17877.6 3699.7 3749.5 3750.7 3754.2 3772.0 128303.0 130247.0 131004.0 131846.3 132279.0 16897.9 16903.6 16918.7 17098.7 17308.0 29256.9 29651.9 29862.9 29937.6 30103.7 8529.7 8551.1 8554.5 8579.7 8646.5 4047.0 4185.4 4222.2 4249.0 4281.5 4765.1 4972.9 5156.8 5689.7 5869.5 1094.4 1138.2 1144.1 1144.7 1146.4 668.7 689.4 713.0 715.9 719.7 11259.6 11628.9 11837.9 12101.3 12331.3 670.7 695.8 699.2 707.8 713.2 5175.9 5234.6 5239.4 5315.0 5360.4 103522.0 110362.4 111322.0 114993.0 115332.0 91337.7 94849.0 95134.5 97067.5 101225.5 82790.2 87032.6 87364.0 87884.6 89161.0 11894.9 11953.2 12056.9 12061.7 12559.5 38972.8 39894.0 40530.6 41417.6 42359.8 69540.0 72347.3 72978.0 73329.6 74577.3 17280.1 17352.7 17582.4 17707.8 17966.6 19763.5 20109.5 20172.8 20394.2 20761.4 17867.2 17877.6 17954.6 18336.4 18545.2 16436.1 16584.7 16750.9 16756.0 16948.7 17548.6 17672.3 17757.6 18003.2 18476.7 11745.2 11966.5 12425.2 12789.4 12940.0 12990.6 13320.7 14036.8 14130.8 14352.8 03:59:22 INFO - PID 21940 | -------- Summary: end -------- 03:59:22 INFO - PID 21940 | 03:59:23 INFO - TEST-INFO | 21940: exit 0 03:59:23 INFO - TEST-OK | dromaeo_css | took 1115082ms 03:59:23 INFO - TEST-START | kraken 03:59:23 INFO - Initialising browser for kraken test... 03:59:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45957/getInfo.html -profile /tmp/tmpbP7hS2/profile 03:59:23 INFO - TEST-INFO | started process 22324 (/builds/slave/test/build/application/firefox/firefox http://localhost:45957/getInfo.html) 03:59:28 INFO - TEST-INFO | 22324: exit 0 03:59:28 INFO - Browser initialized. 03:59:28 INFO - Running cycle 1/1 for kraken test... 03:59:28 INFO - Using env: {'DISPLAY': ':0', 03:59:28 INFO - 'HOME': '/home/cltbld', 03:59:28 INFO - 'JSGC_DISABLE_POISONING': '1', 03:59:28 INFO - 'LANG': 'en_US.UTF-8', 03:59:28 INFO - 'LANGUAGE': 'en_US:en', 03:59:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:59:28 INFO - 'LOGNAME': 'cltbld', 03:59:28 INFO - 'MAIL': '/var/mail/cltbld', 03:59:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:59:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:59:28 INFO - 'MOZ_ACCELERATED': '1', 03:59:28 INFO - 'MOZ_CRASHREPORTER': '1', 03:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:59:28 INFO - 'MOZ_NO_REMOTE': '1', 03:59:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:59:28 INFO - 'MOZ_WEBRENDER': '1', 03:59:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:59:28 INFO - 'NO_EM_RESTART': '1', 03:59:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:28 INFO - 'PWD': '/builds/slave/test', 03:59:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:59:28 INFO - 'RUST_BACKTRACE': 'full', 03:59:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:59:28 INFO - 'SHELL': '/bin/bash', 03:59:28 INFO - 'SHLVL': '1', 03:59:28 INFO - 'TERM': 'linux', 03:59:28 INFO - 'TMOUT': '86400', 03:59:28 INFO - 'USER': 'cltbld', 03:59:28 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055', 03:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:28 INFO - '_': '/tools/buildbot/bin/python'} 03:59:28 INFO - TEST-INFO | started process 22517 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbP7hS2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:59:29 INFO - PID 22517 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:59:29 INFO - PID 22517 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:59:30 INFO - PID 22517 | 03:59:30 INFO - PID 22517 | (/builds/slave/test/build/application/firefox/firefox:22579): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:59:30 INFO - PID 22517 | 03:59:40 INFO - PID 22517 | RSS: Main: 205856768 03:59:40 INFO - PID 22517 | 03:59:43 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45957/tests/kraken/driver.html?testName=audio-beat-detection) 03:59:44 INFO - PID 22517 | RSS: Main: 212783104 03:59:44 INFO - PID 22517 | 03:59:44 INFO - PID 22517 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 03:59:46 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45957/tests/kraken/driver.html?testName=audio-dft) 03:59:47 INFO - PID 22517 | RSS: Main: 219688960 03:59:47 INFO - PID 22517 | 03:59:50 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45957/tests/kraken/driver.html?testName=audio-fft) 03:59:51 INFO - PID 22517 | RSS: Main: 212504576 03:59:51 INFO - PID 22517 | 03:59:53 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45957/tests/kraken/driver.html?testName=audio-oscillator) 03:59:53 INFO - PID 22517 | RSS: Main: 213598208 03:59:53 INFO - PID 22517 | 03:59:56 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45957/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:59:56 INFO - PID 22517 | RSS: Main: 213225472 03:59:56 INFO - PID 22517 | 03:59:59 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45957/tests/kraken/driver.html?testName=imaging-darkroom) 04:00:00 INFO - PID 22517 | RSS: Main: 213872640 04:00:00 INFO - PID 22517 | 04:00:04 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45957/tests/kraken/driver.html?testName=imaging-desaturate) 04:00:04 INFO - PID 22517 | RSS: Main: 212426752 04:00:04 INFO - PID 22517 | 04:00:07 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45957/tests/kraken/driver.html?testName=json-parse-financial) 04:00:08 INFO - PID 22517 | RSS: Main: 214106112 04:00:08 INFO - PID 22517 | 04:00:09 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45957/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:00:10 INFO - PID 22517 | RSS: Main: 214044672 04:00:10 INFO - PID 22517 | 04:00:12 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45957/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:00:12 INFO - PID 22517 | RSS: Main: 214499328 04:00:12 INFO - PID 22517 | 04:00:15 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45957/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:00:15 INFO - PID 22517 | RSS: Main: 209543168 04:00:15 INFO - PID 22517 | 04:00:18 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45957/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:00:19 INFO - PID 22517 | RSS: Main: 211640320 04:00:19 INFO - PID 22517 | 04:00:22 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45957/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:00:22 INFO - PID 22517 | RSS: Main: 211656704 04:00:22 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | Cycle 1(1): loaded http://localhost:45957/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45957/tests/kraken/driver.html?testName=ai-astar) 04:00:24 INFO - PID 22517 | RSS: Main: 212680704 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | __start_tp_report 04:00:24 INFO - PID 22517 | _x_x_mozilla_page_load 04:00:24 INFO - PID 22517 | _x_x_mozilla_page_load_details 04:00:24 INFO - PID 22517 | |i|pagename|runs| 04:00:24 INFO - PID 22517 | |0;ai-astar;99;117;113;111;109;113;116;100;115;111 04:00:24 INFO - PID 22517 | |1;audio-beat-detection;147;122;145;189;125;145;149;121;168;117 04:00:24 INFO - PID 22517 | |2;audio-dft;172;163;163;164;178;174;165;164;164;170 04:00:24 INFO - PID 22517 | |3;audio-fft;82;77;73;172;86;74;74;73;160;75 04:00:24 INFO - PID 22517 | |4;audio-oscillator;79;85;129;81;79;87;135;81;81;131 04:00:24 INFO - PID 22517 | |5;imaging-gaussian-blur;115;115;114;114;123;115;115;116;115;115 04:00:24 INFO - PID 22517 | |6;imaging-darkroom;148;158;146;147;153;156;147;156;149;158 04:00:24 INFO - PID 22517 | |7;imaging-desaturate;98;99;93;95;97;99;95;95;95;95 04:00:24 INFO - PID 22517 | |8;json-parse-financial;53;60;55;61;51;54;53;53;57;54 04:00:24 INFO - PID 22517 | |9;json-stringify-tinderbox;40;43;44;48;46;39;45;44;39;49 04:00:24 INFO - PID 22517 | |10;stanford-crypto-aes;75;74;77;77;75;74;80;77;90;84 04:00:24 INFO - PID 22517 | |11;stanford-crypto-ccm;119;137;131;128;129;123;133;134;121;161 04:00:24 INFO - PID 22517 | |12;stanford-crypto-pbkdf2;176;178;178;172;179;184;176;168;174;177 04:00:24 INFO - PID 22517 | |13;stanford-crypto-sha256-iterative;65;71;64;63;63;64;63;68;65;68 04:00:24 INFO - PID 22517 | __end_tp_report 04:00:24 INFO - PID 22517 | __start_cc_report 04:00:24 INFO - PID 22517 | _x_x_mozilla_cycle_collect,934 04:00:24 INFO - PID 22517 | __end_cc_report 04:00:24 INFO - PID 22517 | __startTimestamp1504609224866__endTimestamp 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | ------- Summary: start ------- 04:00:24 INFO - PID 22517 | Number of tests: 14 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#0] ai-astar Cycles:10 Average:110.40 Median:114.00 stddev:6.24 (5.5%) stddev-sans-first:5.07 04:00:24 INFO - PID 22517 | Values: 99.0 117.0 113.0 111.0 109.0 113.0 116.0 100.0 115.0 111.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:146.00 stddev:22.91 (15.7%) stddev-sans-first:24.25 04:00:24 INFO - PID 22517 | Values: 147.0 122.0 145.0 189.0 125.0 145.0 149.0 121.0 168.0 117.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#2] audio-dft Cycles:10 Average:167.70 Median:167.50 stddev:5.40 (3.2%) stddev-sans-first:5.49 04:00:24 INFO - PID 22517 | Values: 172.0 163.0 163.0 164.0 178.0 174.0 165.0 164.0 164.0 170.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#3] audio-fft Cycles:10 Average:94.60 Median:74.50 stddev:37.97 (51.0%) stddev-sans-first:40.00 04:00:24 INFO - PID 22517 | Values: 82.0 77.0 73.0 172.0 86.0 74.0 74.0 73.0 160.0 75.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:81.00 stddev:24.23 (29.9%) stddev-sans-first:24.83 04:00:24 INFO - PID 22517 | Values: 79.0 85.0 129.0 81.0 79.0 87.0 135.0 81.0 81.0 131.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#5] imaging-gaussian-blur Cycles:10 Average:115.70 Median:115.00 stddev:2.63 (2.3%) stddev-sans-first:2.77 04:00:24 INFO - PID 22517 | Values: 115.0 115.0 114.0 114.0 123.0 115.0 115.0 116.0 115.0 115.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#6] imaging-darkroom Cycles:10 Average:151.80 Median:154.50 stddev:4.89 (3.2%) stddev-sans-first:4.99 04:00:24 INFO - PID 22517 | Values: 148.0 158.0 146.0 147.0 153.0 156.0 147.0 156.0 149.0 158.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:2.02 (2.1%) stddev-sans-first:2.03 04:00:24 INFO - PID 22517 | Values: 98.0 99.0 93.0 95.0 97.0 99.0 95.0 95.0 95.0 95.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:54.50 stddev:3.25 (6.0%) stddev-sans-first:3.35 04:00:24 INFO - PID 22517 | Values: 53.0 60.0 55.0 61.0 51.0 54.0 53.0 53.0 57.0 54.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#9] json-stringify-tinderbox Cycles:10 Average:43.70 Median:44.50 stddev:3.53 (7.9%) stddev-sans-first:3.48 04:00:24 INFO - PID 22517 | Values: 40.0 43.0 44.0 48.0 46.0 39.0 45.0 44.0 39.0 49.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:77.00 stddev:5.12 (6.7%) stddev-sans-first:5.29 04:00:24 INFO - PID 22517 | Values: 75.0 74.0 77.0 77.0 75.0 74.0 80.0 77.0 90.0 84.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#11] stanford-crypto-ccm Cycles:10 Average:131.60 Median:132.00 stddev:11.86 (9.0%) stddev-sans-first:11.67 04:00:24 INFO - PID 22517 | Values: 119.0 137.0 131.0 128.0 129.0 123.0 133.0 134.0 121.0 161.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:177.50 stddev:4.29 (2.4%) stddev-sans-first:4.55 04:00:24 INFO - PID 22517 | Values: 176.0 178.0 178.0 172.0 179.0 184.0 176.0 168.0 174.0 177.0 04:00:24 INFO - PID 22517 | 04:00:24 INFO - PID 22517 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.00 stddev:2.72 (4.2%) stddev-sans-first:2.88 04:00:24 INFO - PID 22517 | Values: 65.0 71.0 64.0 63.0 63.0 64.0 63.0 68.0 65.0 68.0 04:00:24 INFO - PID 22517 | -------- Summary: end -------- 04:00:24 INFO - PID 22517 | 04:00:25 INFO - PID 22517 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:00:25 INFO - TEST-INFO | 22517: exit 0 04:00:25 INFO - TEST-OK | kraken | took 62786ms 04:00:25 INFO - SUITE-END | took 1177s 04:00:26 INFO - Completed test suite (00:19:38) 04:00:26 INFO - javascript benchmark 04:00:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8589.541644970954, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3306.8511916963744, 3336.849841757121, 3362.5124356968236, 3371.6796904294097, 3383.966160338399, 6276.843078923029, 6301.968490157555, 6487.708053137609, 6505.707243174057, 6539.5095367847425, 6427.775027874025, 6433.067205255235, 6443.355664433554, 6457.806265812033, 6463.127477790497, 5876.294844618652, 5885.558583106263, 5892.734826932777, 5925.466707996279, 5937.495312898409, 5952.255968003999, 5984.04255319149, 5990.940090599103, 5993.430624090704, 6007.309159446676, 5961.791337303195, 5972.492338151263, 6012.0, 6033.637981721111, 6066.454019138277, 5814.941850581482, 5826.592138550303, 5829.067349224104, 5868.501177399926, 5947.49446297061, 5801.767929282823, 5876.853078673024, 5897.587168898179, 5914.9112763308585, 6014.458698717092, 6174.84562885927, 6249.312575616678, 6375.362463753634, 6447.935520644827, 6460.903086453707, 6888.4144847688, 6915.861682766316, 6966.79099627012, 6977.441804655616, 6981.3367730066075, 7002.47481438889, 7083.504154709172, 7085.751998680022, 7086.610236436994, 7092.219855815856, 6436.356364363526, 6444.838879028015, 6453.322401147906, 6534.4445722113205, 6563.884139696168, 6862.828429289208, 7020.403265722471, 7061.32917372859, 7069.787906362817, 7149.285071492809, 6670.166229221348, 6695.397414232742, 6709.36261055199, 6734.326567343226, 6735.629540375228, 6779.898301525481, 6833.692483838239, 6834.384905358542, 6837.042814006043, 6840.760573379908, 6798.93201067983, 6800.6939687714175, 6838.0, 6840.589564626039, 6846.212685541127, 6778.559393639398, 6789.864202715918, 6800.6939687714175, 6815.42068924147, 6859.2454829968665, 4836.395450568679, 5299.5230429261555, 5382.2464854920345, 5395.136778115482, 5407.540359069444, 4753.786079626356, 4815.807367705253, 4826.6621336506105, 4835.081334546425, 4840.540148685869, 5940.138679891392, 5992.370801065913, 5993.4905533029305, 6072.605280656831, 6082.330943596201, 6783.559068660521, 6869.381755641916, 6877.346652068146, 6915.446764258847, 7012.333828286304, 5895.734691938873, 5897.085951677497, 5914.0241860093465, 5932.169496270526, 5933.0, 5822.621529600562, 5860.677662728591, 5863.325717542537, 5936.5250779937505, 5995.0, 6570.375814297729, 6650.501212409089, 6663.233728121231, 6770.1537307836525, 6776.390124888783, 6715.1941766988275, 6886.724531018703, 6916.20463646687, 7006.439484841098, 7038.718451261892, 6589.934100658932, 6673.83315417099, 6789.694463749044, 6792.286809884891, 6794.3545363190415, 6716.731330746716, 6810.080639113854, 6885.173779146535, 6885.586864788129, 6917.412019978157, 6794.0, 6918.619475928671, 6932.93067069343, 7014.228434872058, 7090.7518236863525, 6639.6680165992475, 6695.4308883745425, 6753.628550429774, 6756.155480564929, 6812.93187068123, 6736.562123461959, 6778.593284402951, 6791.762288319885, 6797.626130562669, 6801.829954251183, 6533.444657204191, 6657.600543967377, 6662.83342916431, 6665.533412661067, 6756.0, 6594.0108983652835, 6683.298253683488, 6753.257141714505, 6759.290274521104, 6860.691268892812, 6454.806355809334, 6485.967570162119, 6528.67356632176, 6613.338666133348, 6620.337966203341], "value": 6286.951394537683, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.341734651566, 829.1211316005039, 847.894013248344, 880.951547664879, 883.7348795361391, 40126.0, 40769.0, 40788.184236315254, 41044.58955410445, 41049.0, 37326.25347493048, 37598.62401375985, 37652.81173594135, 37670.8116459418, 37686.0, 6783.762568310122, 6894.448444124458, 6907.447404207676, 6908.654567271641, 6913.27410621885, 6810.523263371566, 6886.311368863098, 6916.481263905227, 6943.097397338339, 6945.58326500409, 6884.621345825977, 6922.342377474157, 6929.514933954625, 7458.366038886674, 7648.043994500687, 7521.999999999973, 7537.886931696029, 7594.430417718638, 7611.619419029082, 7621.16167221608, 7487.588182649952, 7490.176080631126, 7498.700051997914, 7498.925010749932, 7510.436717246173, 37074.258514829824, 37097.62902370969, 37182.628173718185, 37265.25469490622, 37602.623973760186, 3572.9464058039152, 3578.481120237577, 3594.0655429588496, 3611.5305010348748, 3622.800745958971, 30434.391312173855, 30536.389272214652, 30635.234119146975, 30762.84618576893, 30779.076627700982, 5063.493650634944, 5151.0, 5167.509086636764, 5223.947760522384, 5243.187305967581, 182698.0, 183210.0, 183853.0, 195651.0, 199792.0, 47666.285005724945, 48195.277070843964, 48199.5180048202, 48241.27638085432, 48318.758406207744, 49839.25241121386, 50135.0, 50213.74893125584, 50315.0, 50685.74657126691, 15985.280662370222, 16183.757243641356, 16188.59528511773, 16203.0, 16303.673926521404, 13530.661733456742, 13539.052266341458, 13543.39054742539, 13548.322583870768, 13552.45790168402, 13988.160710357412, 14098.506552270826, 14211.431542738175, 14245.43018279278, 14282.785758213635, 5657.32112146545, 5713.457221564028, 5758.539316854725, 5764.308283006066, 5778.595498315078, 984.8375018122073, 1007.5415685862915, 1008.0, 1009.7980403919246, 1018.4500369800224, 21570.24504142379, 22247.332580022303, 22340.441488962904, 22390.0, 22425.66361504579, 1109.362116782837, 1115.263925808962, 1115.9218854680257, 1116.4585176189541, 1133.167122165208, 11497.827532587018, 11981.820272695917, 12005.699857503632, 12080.154389192849, 12127.272363658209, 228442.0, 229333.0, 230517.0, 230600.0, 231980.0, 50737.23894141591, 50752.746236268584, 50784.74607627012, 50828.74585627122, 50978.490215098114, 1406.2827957741422, 1449.8260208774805, 1450.6663467402557, 1455.0542147604142, 1464.253230852273, 21884.781152188276, 22096.33710988673, 22368.690629075674, 22755.77244227603, 22786.316410507712, 33234.16914577155, 33250.501242480896, 33333.49999750054, 33354.0, 33410.49884251689, 193330.0, 193470.0, 193880.0, 194336.0, 194437.0, 29581.0, 30170.3965920676, 32992.340153197285, 33212.0, 33867.830660846535, 41599.0, 41853.58146418497, 42314.0, 42415.7879210602, 42764.57235427606, 31231.219219519695, 31326.686733132377, 31406.214844628154, 31535.0, 31629.8418507906, 30916.536251956688, 31070.689293106778, 31147.532787008666, 31183.0, 31222.843885780425, 34627.0, 34853.30293394169, 34925.65074349326, 35167.12082197965, 35261.29477410386, 29880.85059574688, 30006.24984375408, 30419.847900760353, 30516.237094072825, 30524.084277471713, 22592.661110083034, 22873.88563057174, 23235.186768463023, 23301.18445854387, 23429.648555272026], "value": 16689.03533703447, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.35231131887, 568.6303902463401, 569.3680015183146, 572.4332910418684, 579.1746760865767, 22010.11959521619, 22884.54230915383, 22968.88515557422, 23021.884890575566, 23071.653925191134, 24518.0, 26640.200793976164, 26696.065637702683, 26785.0, 26812.65936703167, 6558.770443034495, 6781.898271525931, 6782.220044694874, 6782.287859774727, 6789.456843452536, 6606.801795946105, 6628.668566571676, 6692.63190524521, 6701.564398314118, 6703.1956165260235, 4048.8785336439805, 4064.5325787534516, 4874.268859671042, 5972.6715030673295, 6654.866902661944, 6549.050387693782, 6573.046908198308, 6580.736770529173, 6591.0, 6619.4704423646235, 6088.9391106088815, 6090.695465226765, 6101.633901965896, 6102.786402475892, 6118.112873633343, 27833.443331133265, 29374.412511749644, 29773.0, 29843.0, 29870.253243668863, 1977.1597071244664, 2039.6328660841043, 2041.1325186795552, 2042.2647846775149, 2048.3445297504804, 18442.35451759182, 18933.90533047326, 18937.242510299573, 18966.146523406478, 18971.62056758871, 2367.124164059309, 2377.0848223434105, 2422.958128004957, 2434.0, 2462.3105530451503, 138598.0, 138836.0, 139574.30212848872, 141602.99999999898, 142292.0, 14153.787693184611, 14533.636659083608, 14622.634434139232, 14924.179170145746, 15188.848111518964, 23415.53168936612, 23511.29466116019, 23545.529089418116, 23547.7645223549, 23552.411189720395, 10216.6424175155, 10340.482975851177, 10426.635067772591, 10597.0, 10702.785944281226, 9653.276004299705, 9785.559649815776, 9808.705738827846, 9948.253880958957, 9953.900460995443, 7458.179600243969, 8678.783030424163, 9003.504807235533, 9031.909680903238, 9203.217726493323, 2763.226296636945, 2774.3618967637153, 2786.4009238013923, 2801.2716693659386, 2813.2404250852564, 977.4623956823718, 991.108002797488, 997.9700608981597, 998.0967224661548, 1009.0615727373466, 23451.0, 23564.410889727893, 24590.631140532907, 24670.259892203263, 24768.876155619106, 1037.9896201038043, 1046.7226185060965, 1053.9262251642465, 1069.315637991686, 1075.349413604758, 10720.035196832323, 10920.126389888948, 10968.122550196125, 11144.164187685956, 11151.609693660845, 485124.0, 487854.0, 497000.0, 497861.0, 502777.0, 123188.0, 125775.0, 125869.0, 126817.0, 129142.0, 1230.698478872682, 1251.1554700577074, 1284.5825106840314, 1287.6394609509352, 1289.8968082553374, 18074.548136296697, 18161.909190453964, 18165.36511713952, 18362.724559131355, 18485.72271415903, 58393.4160658388, 60023.69988150206, 60854.39145608487, 61298.0, 61533.692331538055, 75661.24338756542, 78047.60976195309, 78275.60862195652, 78369.60815195888, 79238.20761792308, 14402.279886005448, 14526.273686315431, 14708.852911470749, 14727.484538041117, 14816.851831481546, 22732.659010115192, 22850.657240141743, 23131.768682313636, 23183.304500865, 23217.535649287256, 18406.079696015415, 18499.445016649523, 18645.0, 18865.528361791065, 18891.244350225836, 16611.169441528116, 16742.58143546421, 17079.7438038432, 17234.569135771708, 17284.222210000327, 18766.15552300177, 18877.811221888154, 18957.810421895603, 19030.143643535797, 19038.428847134608, 14218.289085545475, 14267.0, 14311.427542898584, 14316.284185790877, 14486.420543178156, 18212.0, 18363.63272734511, 18430.723539146657, 18464.076796160407, 18754.624907502046], "value": 12131.065142287958, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1283.6405806374216, 1352.4792954712436, 1354.850966393697, 1359.1776974930174, 1359.354306704315, 14313.78529322061, 14831.62920926976, 14907.92546037271, 14944.327505262263, 14994.700105997887, 14731.48439804607, 15717.921410392932, 15784.210789460538, 15825.683486330267, 15826.05043697379, 2768.1695491352616, 2832.815866968651, 2835.0927703135003, 2837.588549660298, 2840.5313123334727, 2658.9335266618295, 2753.1465245773875, 2832.815866968651, 2835.6030155778103, 2866.4697031049323, 2814.057290807582, 2845.146456063183, 2860.0847728726817, 2863.026570965871, 2870.1963450233966, 2674.9197524074307, 2705.47243287559, 2710.132757517605, 2759.0481283957, 2778.7221277872254, 2669.399385138348, 2670.7462791034823, 2673.625692403065, 2683.0206974454377, 2689.9865500672568, 14974.101553906801, 15214.391424343125, 15278.465253716178, 15296.847031529653, 15303.846961530353, 8063.798405039863, 8292.460990035628, 8314.210150035737, 8331.83336333276, 8336.0, 5112.769925353368, 5508.9724551377385, 5510.071474983763, 5543.279373681396, 5670.0, 1953.3358658056186, 1957.168203513501, 1984.6030793841146, 2030.35028790787, 2030.9086091125935, 25572.0, 25754.227373178834, 25775.742242577522, 25783.484330313288, 25803.967841286325, 6632.204135503771, 6725.361090696376, 6758.695858686371, 6852.417544508673, 6898.585789913056, 17279.222434990203, 17285.308587656353, 17339.826601734072, 17386.13069346553, 19823.60352792965, 8036.316913062338, 8223.177682231848, 8241.217084376975, 8245.381596380295, 8247.340212782963, 6867.587944723333, 7149.285071492913, 7213.278672132745, 7259.165196002527, 7300.707971681073, 5662.886742265132, 5855.092460668603, 5865.618734782225, 5882.735276912549, 5928.703564821742, 1624.6750649870073, 1666.8333166683476, 1671.5236157694999, 1715.828417158274, 1748.1084646830209, 864.8875646165958, 880.9911900881037, 889.3330002498126, 891.4695756025154, 905.5110240470073, 7599.886001709979, 7799.45403821727, 8019.799505012421, 8032.0763112241675, 8087.919120808717, 939.7180845746385, 958.9568469418754, 960.3373672166183, 966.8936416994125, 979.9510024498746, 4236.830526778895, 4327.221100201994, 4428.977855110768, 4994.250862370601, 5084.847454576368, 20581.794182058286, 21264.78735212659, 21517.246896358552, 21846.781532184792, 21946.670799938012, 20752.584948300948, 20954.895225523775, 21045.684314735292, 21309.46726331854, 21512.892435537724, 11269.380184089954, 11343.773124537463, 11429.257098288585, 11436.542538298541, 11548.942255288837, 7469.178390377163, 7795.766127016199, 7825.4130940178575, 7849.568273744999, 7957.323627491728, 17972.281108755502, 18999.0, 19453.708194376813, 19477.61044779125, 19491.70762438593, 18472.168752405905, 19011.0, 19316.227350905807, 19322.903385483543, 19414.2234310626, 11818.113641476752, 12207.69480762988, 12245.326507042199, 12267.57063502773, 12286.87713122893, 13181.538646147339, 13225.735485290434, 13290.468381264642, 13295.0, 13363.331833408485, 8546.957265213634, 9952.402855828674, 11068.944655276671, 11275.267107637977, 11353.148513861328, 10728.785424291314, 10892.7276818077, 11028.28316159447, 11101.50043248072, 11275.605353812578, 9702.805943881223, 9943.25425593069, 10021.39871607706, 10021.849672254777, 10030.598776048877, 6721.697523611351, 6847.794566162824, 6884.311568843075, 6885.862282754417, 6940.271271516418, 6980.441564674712, 7001.929980700128, 7006.614636194854, 7017.789466316019, 7067.434605231672], "value": 6684.589791489209, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.07718614546474, 505.0051398744472, 522.5427750718121, 528.3738769558074, 529.7854368980564, 6434.581752186116, 6472.255690595583, 6499.512536559753, 6544.836379090525, 6553.934460655392, 6143.723532441039, 6336.112944187806, 6354.68226588671, 6360.886844802164, 6362.963851567501, 5099.0, 5125.513076257768, 5143.27994080828, 5166.328377310944, 5187.844364669066, 5078.720670363128, 5122.795088196468, 5149.17613181891, 5155.3040339554245, 5198.246254293125, 4842.806287748486, 5021.447640759514, 5050.898982020358, 5085.0, 5115.693058416488, 5094.261332106824, 5100.387953445573, 5106.4382917879, 5119.897602047939, 5132.692038477703, 4516.23224051912, 4528.388667529907, 4529.230030894756, 4532.456105267389, 4594.517575654541, 6264.749410023594, 6284.591501552384, 6322.0200868865395, 6351.2060992375955, 6352.872942541123, 5831.329397119343, 5834.474897259224, 5856.385079566627, 5862.0, 5869.765209391662, 6699.866002679968, 6747.460203183734, 6761.797146085625, 6779.559328643622, 6801.4898882584, 4928.1129396708575, 4939.456659767423, 4943.901121977577, 4957.6033917286895, 4970.6520543561965, 6946.479014073965, 7008.684609192598, 7033.839416496272, 7081.185663648644, 7140.642967851587, 5071.2139618359215, 5167.405748338989, 5168.664036837594, 5181.948180518222, 5199.610029247821, 5167.560757335594, 5189.922151167735, 5191.169412893919, 5203.479652034766, 5244.931714946722, 5615.9610472062795, 5750.1949727038245, 5773.7955434733285, 5779.190913272145, 5847.0, 6585.604863708193, 6668.199816022011, 6709.463242940553, 6776.356246156607, 6778.76300820728, 1769.6020144086285, 1779.80422153563, 1783.304511240617, 1814.8729588928911, 1835.3759721694687, 1137.1357768096307, 1145.5360578965533, 1188.803847365175, 1211.418519110814, 1256.1897576063388, 5921.851953701192, 5952.791652292149, 5956.344802071769, 5959.4934430573885, 6022.819315420545, 6747.954067119605, 6813.148356455443, 6852.897206541906, 6879.862402752017, 6930.44556435484, 5890.352061273256, 5895.616784909053, 5904.1734157218025, 5941.465268125889, 5963.479312775215, 3589.515415418885, 3644.2347107107803, 3681.9631803682155, 3682.1957240951742, 3688.981555092207, 6506.763714894155, 6559.901601475982, 6641.833954151185, 6674.966625166843, 6680.331966803281, 6872.209695884937, 7065.1521817382245, 7068.151821781419, 7104.538205016761, 7149.785506434711, 6590.373914478117, 6602.141721576164, 6614.536982411185, 6638.402543771083, 6735.865282694319, 6812.0, 6872.41584465326, 6881.759138430131, 6891.345322194384, 6915.654217289216, 6729.332513779904, 6775.966120169368, 6826.385625293548, 6838.350356716104, 6873.896891546731, 6583.0, 6647.235567909753, 6670.132362938385, 6683.0, 6696.933030669826, 5087.389513258433, 5105.744712764273, 5109.872253193551, 5114.820981265638, 5122.359705036871, 6741.258461569126, 6801.387875091068, 6839.452843772586, 6915.170179578483, 6973.779588572081, 6569.9343006569325, 6588.439982601532, 6670.666466676744, 6673.1992160941, 6674.83312917181, 6522.28254891971, 6621.735130594722, 6653.667316634052, 6669.566478178903, 6720.563163394364, 6059.394060593905, 6504.349565043458, 6553.705083271359, 6583.572067815577, 6593.571417857824, 6700.6984685688285, 6733.383987842784, 6739.865202696017, 6789.574189420079, 6797.286284940208, 6419.743210271537, 6471.0, 6484.0771333342645, 6499.285078641251, 6511.153550038464], "value": 5164.436050833684, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [897.8877640294962, 910.849709797883, 924.7595625137462, 924.796544760153, 925.3753716241538, 18901.905490472545, 19676.901615491937, 19687.606247875035, 19721.21115155392, 19723.21107155714, 15620.0, 16368.75446868295, 18826.529336766554, 19101.713474297896, 19499.41501754946, 1002.2733518199284, 1027.7276521721749, 1029.8867124616286, 1029.9485025748684, 1030.1141018723893, 965.1554889471712, 983.2527279267772, 994.9452780097092, 995.9253056020791, 997.5162046407523, 1058.4866339825178, 1059.2055958031476, 1061.7345663584103, 1062.075993885321, 1067.9466026698674, 1053.257453495284, 1056.5351245451977, 1060.8833028366873, 1063.2185343772321, 1064.8296272596385, 765.5674543882689, 772.6870617399962, 773.443120952913, 773.5126870071875, 774.2180397798264, 20127.194912203493, 20181.798182018138, 20275.0, 20469.5906081879, 20929.162833486644, 21296.574068518697, 21527.138914443403, 21825.563488730295, 22067.558648826933, 22108.0, 17241.82758172415, 17588.12059397038, 17702.203400846993, 17708.114594270235, 17877.642447151113, 3699.667029967316, 3749.531308586427, 3750.7374483786148, 3754.1928485375774, 3772.0, 128303.0, 130247.0, 131004.0, 131846.3407682965, 132279.0, 16897.91551042237, 16903.577410564834, 16918.661626767644, 17098.743518847226, 17308.0, 29256.853715731286, 29651.85174074116, 29862.85068574643, 29937.550936735966, 30103.698963010527, 8529.65881364751, 8551.1448855114, 8554.486730796172, 8579.699710510227, 8646.481211127353, 4046.979765101156, 4185.372194170899, 4222.19778242133, 4249.022724773257, 4281.529031806499, 4765.11845308619, 4972.90054198914, 5156.767945442464, 5689.715514224272, 5869.5010924071075, 1094.3871431997948, 1138.248755821154, 1144.0790163918125, 1144.6966553863233, 1146.363768108691, 668.7157957867887, 689.4036658290543, 712.9572225666373, 715.8711431942196, 719.7301012120455, 11259.605913792979, 11628.94185529084, 11837.88162118385, 12101.334426606445, 12331.335269738649, 670.6546128743701, 695.8295217671601, 699.1959246866043, 707.844274259662, 713.1940906775415, 5175.870603234949, 5234.581233501311, 5239.397469291081, 5315.01672190646, 5360.383555891044, 103522.0, 110362.44818775855, 111322.0, 114993.0, 115332.0, 91337.7329929503, 94849.0, 95134.52432737792, 97067.51466242624, 101225.49387253165, 82790.17209827945, 87032.5648371754, 87364.0, 87884.56057719671, 89161.0, 11894.940525297317, 11953.163278570417, 12056.879431205576, 12061.698457538632, 12559.497620095459, 38972.80513597414, 39894.0, 40530.59469405386, 41417.58582414137, 42359.78820106003, 69540.0, 72347.27652723405, 72978.0, 73329.6333518329, 74577.25422745703, 17280.135993200038, 17352.739708904628, 17582.38461653887, 17707.822921770618, 17966.640667186322, 19763.505912352306, 20109.49726256797, 20172.79827201709, 20394.184232630527, 20761.377158685264, 17867.195976180843, 17877.553061173574, 17954.551136221176, 18336.358227461973, 18545.165467554263, 16436.096014719304, 16584.66830663404, 16750.916245418695, 16756.0, 16948.661026779642, 17548.561285967953, 17672.293108275524, 17757.556061098578, 18003.18985645623, 18476.722849157006, 11745.236559623598, 11966.46150923228, 12425.19236249641, 12789.424475898793, 12940.0, 12990.610281691565, 13320.666983325495, 14036.78944815808, 14130.78803817923, 14352.784708229594], "value": 9139.614731201153, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1526.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 117.0, 113.0, 111.0, 109.0, 113.0, 116.0, 100.0, 115.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 145.0, 189.0, 125.0, 145.0, 149.0, 121.0, 168.0, 117.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 163.0, 163.0, 164.0, 178.0, 174.0, 165.0, 164.0, 164.0, 170.0], "value": 167.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 77.0, 73.0, 172.0, 86.0, 74.0, 74.0, 73.0, 160.0, 75.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 85.0, 129.0, 81.0, 79.0, 87.0, 135.0, 81.0, 81.0, 131.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 114.0, 123.0, 115.0, 115.0, 116.0, 115.0, 115.0], "value": 115.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 158.0, 146.0, 147.0, 153.0, 156.0, 147.0, 156.0, 149.0, 158.0], "value": 151.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 93.0, 95.0, 97.0, 99.0, 95.0, 95.0, 95.0, 95.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 60.0, 55.0, 61.0, 51.0, 54.0, 53.0, 53.0, 57.0, 54.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 43.0, 44.0, 48.0, 46.0, 39.0, 45.0, 44.0, 39.0, 49.0], "value": 43.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 77.0, 77.0, 75.0, 74.0, 80.0, 77.0, 90.0, 84.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 137.0, 131.0, 128.0, 129.0, 123.0, 133.0, 134.0, 121.0, 161.0], "value": 131.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 178.0, 178.0, 172.0, 179.0, 184.0, 176.0, 168.0, 174.0, 177.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 64.0, 63.0, 63.0, 64.0, 63.0, 68.0, 65.0, 68.0], "value": 65.4, "unit": "score"}]}]} 04:00:26 INFO - Return code: 0 04:00:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:00:26 INFO - # TBPL SUCCESS # 04:00:26 INFO - Running post-action listener: _package_coverage_data 04:00:26 INFO - Running post-action listener: _resource_record_post_action 04:00:26 INFO - [mozharness: 2017-09-05 11:00:26.164798Z] Finished run-tests step (success) 04:00:26 INFO - Running post-run listener: _resource_record_post_run 04:00:26 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 16384; Write bytes: 393129984; Read time: 208; Write time: 254560 04:00:26 INFO - TinderboxPrint: CPU usage
13.3% 04:00:26 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 208 04:00:26 INFO - TinderboxPrint: I/O write bytes / time
393,129,984 / 254,560 04:00:26 INFO - TinderboxPrint: CPU idle
8,258.9 (86.7%) 04:00:26 INFO - TinderboxPrint: CPU user
1,203.1 (12.6%) 04:00:26 INFO - TinderboxPrint: Swap in / out
0 / 0 04:00:26 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 110436352; Read time: 52; Write time: 100720 04:00:26 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:00:26 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 8192; Write bytes: 282693632; Read time: 140; Write time: 153840 04:00:26 INFO - Running post-run listener: _upload_blobber_files 04:00:26 INFO - Blob upload gear active. 04:00:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:00:26 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 04:00:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:00:27 INFO - (blobuploader) - INFO - Open directory for files ... 04:00:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:00:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:00:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:29 INFO - (blobuploader) - INFO - Done attempting. 04:00:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:00:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:00:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:30 INFO - (blobuploader) - INFO - Done attempting. 04:00:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:00:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:00:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:39 INFO - (blobuploader) - INFO - Done attempting. 04:00:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:00:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:00:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:40 INFO - (blobuploader) - INFO - Done attempting. 04:00:40 INFO - (blobuploader) - INFO - Iteration through files over. 04:00:40 INFO - Return code: 0 04:00:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:00:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:00:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc49b34fc6f01c8f0109751fbecaa7e852a921a75ebd443552f41d6ce419a22447f38a8e0a1299e3f0e0882c2976eab052cbcb81e638c15bf343781882eb6246", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fd54875d50bbac8a8e79a3853eff353325db1c0fbd57912680435b062070ae7ce8223f1a4af213b002675633d1e8a3bd789dc1d5e0f804588d7e30ac6ecdaef", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64ae194c34d9bf76b0fb91395fd5d1e5b2022b90eb3ef20e2bd6dbb304adc4b648e46c7d6e4f7901b49764d7e6a007023d62ea134c60fe6353c90c2cc7eba82b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93dcd5e223f175126ffaa3839f06a3cde9799af0878ddb2f1c29b65eb4d64bc4a48de8b5a19bb57c0842a543c5803e1b92524d56c5d027c0089e4bb290144bd4"} 04:00:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:00:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:00:40 INFO - Contents: 04:00:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc49b34fc6f01c8f0109751fbecaa7e852a921a75ebd443552f41d6ce419a22447f38a8e0a1299e3f0e0882c2976eab052cbcb81e638c15bf343781882eb6246", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fd54875d50bbac8a8e79a3853eff353325db1c0fbd57912680435b062070ae7ce8223f1a4af213b002675633d1e8a3bd789dc1d5e0f804588d7e30ac6ecdaef", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64ae194c34d9bf76b0fb91395fd5d1e5b2022b90eb3ef20e2bd6dbb304adc4b648e46c7d6e4f7901b49764d7e6a007023d62ea134c60fe6353c90c2cc7eba82b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93dcd5e223f175126ffaa3839f06a3cde9799af0878ddb2f1c29b65eb4d64bc4a48de8b5a19bb57c0842a543c5803e1b92524d56c5d027c0089e4bb290144bd4"} 04:00:40 INFO - Running post-run listener: copy_logs_to_upload_dir 04:00:40 INFO - Copying logs to upload dir... 04:00:40 INFO - mkdir: /builds/slave/test/build/upload/logs 04:00:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.230306 ========= master_lag: 0.23 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-09-05 04:00:40.726906) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 04:00:40.730029) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc49b34fc6f01c8f0109751fbecaa7e852a921a75ebd443552f41d6ce419a22447f38a8e0a1299e3f0e0882c2976eab052cbcb81e638c15bf343781882eb6246", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fd54875d50bbac8a8e79a3853eff353325db1c0fbd57912680435b062070ae7ce8223f1a4af213b002675633d1e8a3bd789dc1d5e0f804588d7e30ac6ecdaef", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64ae194c34d9bf76b0fb91395fd5d1e5b2022b90eb3ef20e2bd6dbb304adc4b648e46c7d6e4f7901b49764d7e6a007023d62ea134c60fe6353c90c2cc7eba82b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93dcd5e223f175126ffaa3839f06a3cde9799af0878ddb2f1c29b65eb4d64bc4a48de8b5a19bb57c0842a543c5803e1b92524d56c5d027c0089e4bb290144bd4"} build_url:https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc49b34fc6f01c8f0109751fbecaa7e852a921a75ebd443552f41d6ce419a22447f38a8e0a1299e3f0e0882c2976eab052cbcb81e638c15bf343781882eb6246", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fd54875d50bbac8a8e79a3853eff353325db1c0fbd57912680435b062070ae7ce8223f1a4af213b002675633d1e8a3bd789dc1d5e0f804588d7e30ac6ecdaef", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64ae194c34d9bf76b0fb91395fd5d1e5b2022b90eb3ef20e2bd6dbb304adc4b648e46c7d6e4f7901b49764d7e6a007023d62ea134c60fe6353c90c2cc7eba82b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93dcd5e223f175126ffaa3839f06a3cde9799af0878ddb2f1c29b65eb4d64bc4a48de8b5a19bb57c0842a543c5803e1b92524d56c5d027c0089e4bb290144bd4"}' ========= master_lag: 0.27 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 04:00:41.006927) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 04:00:41.007552) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1504607978.646953-1714253055 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004516 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 04:00:41.036258) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 04:00:41.036892) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 04:00:41.042309) ========= ========= Total master_lag: 0.59 =========