builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-037 starttime: 1504283042.66 results: success (0) revision: 34933f6390d52779ea498a6a5fd5f34d54734780 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:02.661370) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:02.661798) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:02.662070) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:02.677944) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:02.678270) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 09:24:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2017-09-01 09:24:02 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081756 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:02.778219) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:02.778587) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018006 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:02.828598) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:02.828938) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 34933f6390d52779ea498a6a5fd5f34d54734780 --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 34933f6390d52779ea498a6a5fd5f34d54734780 --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=56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 09:24:02,869 truncating revision to first 12 chars 2017-09-01 09:24:02,869 Setting DEBUG logging. 2017-09-01 09:24:02,869 attempt 1/10 2017-09-01 09:24:02,869 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/34933f6390d5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 09:24:03,383 unpacking tar archive at: mozilla-central-34933f6390d5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.932435 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:03.776097) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:03.776443) ========= script_repo_revision: 34933f6390d52779ea498a6a5fd5f34d54734780 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:03.776846) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:03.777116) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 09:24:03.792437) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-01 09:24:03.792867) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:24:03 INFO - MultiFileLogger online at 20170901 09:24:03 in /builds/slave/test 09:24:03 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 09:24:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:24:03 INFO - {'append_to_log': False, 09:24:03 INFO - 'base_work_dir': '/builds/slave/test', 09:24:03 INFO - 'blob_upload_branch': 'Firefox', 09:24:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:24:03 INFO - 'branch': 'Firefox', 09:24:03 INFO - 'buildbot_json_path': 'buildprops.json', 09:24:03 INFO - 'code_coverage': False, 09:24:03 INFO - 'config_files': ('talos/linux_config.py',), 09:24:03 INFO - 'default_actions': ('clobber', 09:24:03 INFO - 'read-buildbot-config', 09:24:03 INFO - 'download-and-extract', 09:24:03 INFO - 'populate-webroot', 09:24:03 INFO - 'create-virtualenv', 09:24:03 INFO - 'install', 09:24:03 INFO - 'setup-mitmproxy', 09:24:03 INFO - 'run-tests'), 09:24:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:24:03 INFO - 'disable_ccov_upload': False, 09:24:03 INFO - 'disable_stylo': False, 09:24:03 INFO - 'download_minidump_stackwalk': True, 09:24:03 INFO - 'download_symbols': 'ondemand', 09:24:03 INFO - 'enable_stylo': False, 09:24:03 INFO - 'enable_webrender': False, 09:24:03 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 09:24:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:24:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:24:03 INFO - 'gecko_profile': False, 09:24:03 INFO - 'gecko_profile_interval': 0, 09:24:03 INFO - 'installer_path': 'installer.exe', 09:24:03 INFO - 'log_level': 'info', 09:24:03 INFO - 'log_name': 'talos', 09:24:03 INFO - 'log_to_console': True, 09:24:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:24:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:24:03 INFO - 'opt_config_files': (), 09:24:03 INFO - 'pip_index': False, 09:24:03 INFO - 'suite': 'dromaeojs-e10s', 09:24:03 INFO - 'system_bits': '32', 09:24:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:24:03 INFO - 'title': 'talos-linux64-ix-037', 09:24:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:24:03 INFO - 'use_talos_json': True, 09:24:03 INFO - 'verify': 'False', 09:24:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:24:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:24:03 INFO - 'work_dir': 'build'} 09:24:03 INFO - [mozharness: 2017-09-01 16:24:03.970646Z] Running clobber step. 09:24:03 INFO - Running pre-action listener: _resource_record_pre_action 09:24:03 INFO - Running main action method: clobber 09:24:03 INFO - rmtree: /builds/slave/test/build 09:24:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:24:05 INFO - Running post-action listener: _resource_record_post_action 09:24:05 INFO - [mozharness: 2017-09-01 16:24:05.553566Z] Finished clobber step (success) 09:24:05 INFO - [mozharness: 2017-09-01 16:24:05.553665Z] Running read-buildbot-config step. 09:24:05 INFO - Running pre-action listener: _resource_record_pre_action 09:24:05 INFO - Running main action method: read_buildbot_config 09:24:05 INFO - Using buildbot properties: 09:24:05 INFO - { 09:24:05 INFO - "project": "", 09:24:05 INFO - "product": "firefox", 09:24:05 INFO - "who": "archaeopteryx@coole-files.de", 09:24:05 INFO - "installer_path": "public/build/target.tar.bz2", 09:24:05 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:24:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 09:24:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 09:24:05 INFO - "stage_platform": "linux64-pgo", 09:24:05 INFO - "basedir": "/builds/slave/test", 09:24:05 INFO - "buildnumber": 134, 09:24:05 INFO - "platform": "ubuntu64_hw", 09:24:05 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:24:05 INFO - "slavebuilddir": "test", 09:24:05 INFO - "taskId": "XXxY-C-8SGS0Sjy1yG4rsQ", 09:24:05 INFO - "branch": "mozilla-central", 09:24:05 INFO - "script_repo_revision": "production", 09:24:05 INFO - "revision": "34933f6390d52779ea498a6a5fd5f34d54734780", 09:24:05 INFO - "slavename": "talos-linux64-ix-037", 09:24:05 INFO - "repo_path": "mozilla-central" 09:24:05 INFO - } 09:24:05 INFO - Finding installer, test and symbols from parent task. 09:24:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XXxY-C-8SGS0Sjy1yG4rsQ'}, attempt #1 09:24:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XXxY-C-8SGS0Sjy1yG4rsQ'}, attempt #1 09:24:06 INFO - Task dependencies: CjDT4LNTRv2k0ngmn0SImw 09:24:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw'}, attempt #1 09:24:06 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.tar.bz2 09:24:06 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.test_packages.json 09:24:06 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.crashreporter-symbols.zip 09:24:06 INFO - Running post-action listener: _resource_record_post_action 09:24:06 INFO - [mozharness: 2017-09-01 16:24:06.921605Z] Finished read-buildbot-config step (success) 09:24:06 INFO - [mozharness: 2017-09-01 16:24:06.921825Z] Running download-and-extract step. 09:24:06 INFO - Running pre-action listener: _resource_record_pre_action 09:24:06 INFO - Running main action method: download_and_extract 09:24:06 INFO - mkdir: /builds/slave/test/build/tests 09:24:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:06 INFO - trying https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.test_packages.json 09:24:06 INFO - Downloading https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:24:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:24:07 INFO - Downloaded 989 bytes. 09:24:07 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:24:07 INFO - Using the following test package requirements: 09:24:07 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:24:07 INFO - u'common': [u'target.common.tests.zip'], 09:24:07 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:24:07 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:24:07 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:24:07 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:24:07 INFO - u'mozbase': [u'target.common.tests.zip'], 09:24:07 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:24:07 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:24:07 INFO - u'web-platform': [u'target.common.tests.zip', 09:24:07 INFO - u'target.web-platform.tests.tar.gz'], 09:24:07 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:24:07 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:24:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.common.tests.zip 09:24:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:24:07 INFO - Fetch https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.common.tests.zip into memory 09:24:08 INFO - Content-Length response header: 55581766 09:24:08 INFO - Bytes received: 55581766 09:24:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:24:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.common.tests.zip 09:24:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:24:19 INFO - Fetch https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.common.tests.zip into memory 09:24:20 INFO - Content-Length response header: 55581766 09:24:20 INFO - Bytes received: 55581766 09:24:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.talos.tests.zip 09:24:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:24:32 INFO - Fetch https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.talos.tests.zip into memory 09:24:33 INFO - Content-Length response header: 14496650 09:24:33 INFO - Bytes received: 14496650 09:24:33 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')]} 09:24:33 INFO - trying https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.tar.bz2 09:24:33 INFO - Downloading https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:24:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:24:34 INFO - Downloaded 63280884 bytes. 09:24:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.tar.bz2 09:24:34 INFO - mkdir: /builds/slave/test/properties 09:24:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:24:34 INFO - Writing to file /builds/slave/test/properties/build_url 09:24:34 INFO - Contents: 09:24:34 INFO - build_url:https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.tar.bz2 09:24:34 INFO - Running post-action listener: _resource_record_post_action 09:24:34 INFO - Running post-action listener: find_tests_for_verification 09:24:34 INFO - Running post-action listener: set_extra_try_arguments 09:24:34 INFO - [mozharness: 2017-09-01 16:24:34.663778Z] Finished download-and-extract step (success) 09:24:34 INFO - [mozharness: 2017-09-01 16:24:34.663876Z] Running populate-webroot step. 09:24:34 INFO - Running pre-action listener: _resource_record_pre_action 09:24:34 INFO - Running main action method: populate_webroot 09:24:34 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:24:34 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:24:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:24:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:24:34 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:24:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:24:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:24:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:34 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:24:34 INFO - 'talos_options': ['--enable-stylo'], 09:24:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:24:34 INFO - 'tests': ['damp', 'tps']}, 09:24:34 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:24:34 INFO - 'talos_options': ['--enable-stylo'], 09:24:34 INFO - 'tests': ['damp', 'tps']}, 09:24:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:24:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:24:34 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:24:34 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:24:34 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:24:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:24:34 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:24:34 INFO - 'talos_options': ['--enable-stylo'], 09:24:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:24:34 INFO - 'other-e10s': {'tests': ['a11yr', 09:24:34 INFO - 'ts_paint', 09:24:34 INFO - 'tpaint', 09:24:34 INFO - 'sessionrestore', 09:24:34 INFO - 'sessionrestore_many_windows', 09:24:34 INFO - 'sessionrestore_no_auto_restore', 09:24:34 INFO - 'tabpaint']}, 09:24:34 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:24:34 INFO - 'tests': ['a11yr', 09:24:34 INFO - 'ts_paint', 09:24:34 INFO - 'tpaint', 09:24:34 INFO - 'sessionrestore', 09:24:34 INFO - 'sessionrestore_many_windows', 09:24:34 INFO - 'sessionrestore_no_auto_restore', 09:24:34 INFO - 'tabpaint']}, 09:24:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:24:34 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:24:34 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:24:34 INFO - 'tests': ['bloom_basic_singleton']}, 09:24:34 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:24:34 INFO - 'tests': ['bloom_basic']}, 09:24:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:24:34 INFO - 'tsvgr_opacity', 09:24:34 INFO - 'tart', 09:24:34 INFO - 'tscrollx', 09:24:34 INFO - 'tsvg_static']}, 09:24:34 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 09:24:34 INFO - 'tests': ['tsvgx', 09:24:34 INFO - 'tsvgr_opacity', 09:24:34 INFO - 'tart', 09:24:34 INFO - 'tscrollx', 09:24:34 INFO - 'tsvg_static']}, 09:24:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:24:34 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:24:34 INFO - 'talos_options': ['--enable-stylo'], 09:24:34 INFO - 'tests': ['tp5o']}, 09:24:34 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:24:34 INFO - 'talos_options': ['--mitmproxy', 09:24:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:24:34 INFO - '--firstNonBlankPaint'], 09:24:34 INFO - 'tests': ['tp6_google', 09:24:34 INFO - 'tp6_youtube', 09:24:34 INFO - 'tp6_amazon', 09:24:34 INFO - 'tp6_facebook']}, 09:24:34 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:24:34 INFO - 'talos_options': ['--enable-stylo', 09:24:34 INFO - '--mitmproxy', 09:24:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:24:34 INFO - '--firstNonBlankPaint'], 09:24:34 INFO - 'tests': ['tp6_google', 09:24:34 INFO - 'tp6_youtube', 09:24:34 INFO - 'tp6_amazon', 09:24:34 INFO - 'tp6_facebook']}, 09:24:34 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:24:34 INFO - 'talos_options': ['--enable-stylo', 09:24:34 INFO - '--stylo-threads=1', 09:24:34 INFO - '--mitmproxy', 09:24:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:24:34 INFO - '--firstNonBlankPaint'], 09:24:34 INFO - 'tests': ['tp6_google', 09:24:34 INFO - 'tp6_youtube', 09:24:34 INFO - 'tp6_amazon', 09:24:34 INFO - 'tp6_facebook']}, 09:24:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:24:34 INFO - 'talos_options': ['--xperf_path', 09:24:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:34 INFO - 'tests': ['tp5n']}, 09:24:34 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 09:24:34 INFO - 'talos_options': ['--enable-stylo', 09:24:34 INFO - '--xperf_path', 09:24:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:34 INFO - 'tests': ['tp5n']}}} 09:24:34 INFO - Running post-action listener: _resource_record_post_action 09:24:34 INFO - [mozharness: 2017-09-01 16:24:34.678276Z] Finished populate-webroot step (success) 09:24:34 INFO - [mozharness: 2017-09-01 16:24:34.678366Z] Running create-virtualenv step. 09:24:34 INFO - Running pre-action listener: _resource_record_pre_action 09:24:34 INFO - Running main action method: create_virtualenv 09:24:34 INFO - Creating virtualenv /builds/slave/test/build/venv 09:24:34 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:24:34 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 09:24:34 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:24:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:24:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:24:35 INFO - Installing distribute.............................................................................................................................................................................................done. 09:24:35 INFO - Installing pip...............done. 09:24:35 INFO - Return code: 0 09:24:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:24:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:24:35 INFO - Reading from file tmpfile_stdout 09:24:35 INFO - Output received: 09:24:35 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 09:24:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:24:35 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')]} 09:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:35 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')]} 09:24:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:35 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 0x28e31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aaca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c53090>, '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 0x2c53d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4df90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', '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 09:24:35 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 09:24:35 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 09:24:35 INFO - Using env: {'DISPLAY': ':0', 09:24:35 INFO - 'HOME': '/home/cltbld', 09:24:35 INFO - 'LANG': 'en_US.UTF-8', 09:24:35 INFO - 'LANGUAGE': 'en_US:en', 09:24:35 INFO - 'LOGNAME': 'cltbld', 09:24:35 INFO - 'MAIL': '/var/mail/cltbld', 09:24:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:35 INFO - 'MOZ_NO_REMOTE': '1', 09:24:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:35 INFO - 'NO_EM_RESTART': '1', 09:24:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:35 INFO - 'PWD': '/builds/slave/test', 09:24:35 INFO - 'SHELL': '/bin/bash', 09:24:35 INFO - 'SHLVL': '1', 09:24:35 INFO - 'TERM': 'linux', 09:24:35 INFO - 'TMOUT': '86400', 09:24:35 INFO - 'USER': 'cltbld', 09:24:35 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:24:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:35 INFO - '_': '/tools/buildbot/bin/python'} 09:24:36 INFO - Ignoring indexes: http://pypi.python.org/simple/ 09:24:36 INFO - Downloading/unpacking pip>=1.5 09:24:36 INFO - Running setup.py egg_info for package pip 09:24:36 INFO - warning: no files found matching 'pip/cacert.pem' 09:24:36 INFO - warning: no files found matching '*.html' under directory 'docs' 09:24:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:24:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:24:36 INFO - Installing collected packages: pip 09:24:36 INFO - Found existing installation: pip 1.1 09:24:36 INFO - Uninstalling pip: 09:24:36 INFO - Successfully uninstalled pip 09:24:36 INFO - Running setup.py install for pip 09:24:36 INFO - warning: no files found matching 'pip/cacert.pem' 09:24:36 INFO - warning: no files found matching '*.html' under directory 'docs' 09:24:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:24:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:24:36 INFO - Installing pip script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 09:24:36 INFO - Successfully installed pip 09:24:36 INFO - Cleaning up... 09:24:36 INFO - Return code: 0 09:24:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:24:36 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')]} 09:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:36 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')]} 09:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:36 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 0x28e31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aaca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c53090>, '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 0x2c53d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4df90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', '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 09:24:36 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 09:24:36 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 09:24:36 INFO - Using env: {'DISPLAY': ':0', 09:24:36 INFO - 'HOME': '/home/cltbld', 09:24:36 INFO - 'LANG': 'en_US.UTF-8', 09:24:36 INFO - 'LANGUAGE': 'en_US:en', 09:24:36 INFO - 'LOGNAME': 'cltbld', 09:24:36 INFO - 'MAIL': '/var/mail/cltbld', 09:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:36 INFO - 'MOZ_NO_REMOTE': '1', 09:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:36 INFO - 'NO_EM_RESTART': '1', 09:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:36 INFO - 'PWD': '/builds/slave/test', 09:24:36 INFO - 'SHELL': '/bin/bash', 09:24:36 INFO - 'SHLVL': '1', 09:24:36 INFO - 'TERM': 'linux', 09:24:36 INFO - 'TMOUT': '86400', 09:24:36 INFO - 'USER': 'cltbld', 09:24:36 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:36 INFO - '_': '/tools/buildbot/bin/python'} 09:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:37 INFO - Downloading/unpacking psutil>=3.1.1 09:24:37 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 09:24:37 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 09:24:37 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 09:24:37 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 09:24:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:24:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:38 INFO - Installing collected packages: psutil 09:24:38 INFO - Running setup.py install for psutil 09:24:38 INFO - building 'psutil._psutil_linux' extension 09:24:38 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 09:24:38 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 09:24:38 INFO - building 'psutil._psutil_posix' extension 09:24:38 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 09:24:38 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 09:24:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:38 INFO - Successfully installed psutil 09:24:38 INFO - Cleaning up... 09:24:38 INFO - Return code: 0 09:24:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:38 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 0x28e31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aaca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c53090>, '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 0x2c53d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4df90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', '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 09:24:38 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 09:24:38 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 09:24:38 INFO - Using env: {'DISPLAY': ':0', 09:24:38 INFO - 'HOME': '/home/cltbld', 09:24:38 INFO - 'LANG': 'en_US.UTF-8', 09:24:38 INFO - 'LANGUAGE': 'en_US:en', 09:24:38 INFO - 'LOGNAME': 'cltbld', 09:24:38 INFO - 'MAIL': '/var/mail/cltbld', 09:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:38 INFO - 'MOZ_NO_REMOTE': '1', 09:24:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:38 INFO - 'NO_EM_RESTART': '1', 09:24:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:38 INFO - 'PWD': '/builds/slave/test', 09:24:38 INFO - 'SHELL': '/bin/bash', 09:24:38 INFO - 'SHLVL': '1', 09:24:38 INFO - 'TERM': 'linux', 09:24:38 INFO - 'TMOUT': '86400', 09:24:38 INFO - 'USER': 'cltbld', 09:24:38 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:38 INFO - '_': '/tools/buildbot/bin/python'} 09:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:24:39 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 09:24:39 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 09:24:39 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 09:24:39 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 09:24:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:24:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:24:40 INFO - Installing collected packages: mozsystemmonitor 09:24:40 INFO - Running setup.py install for mozsystemmonitor 09:24:40 INFO - Successfully installed mozsystemmonitor 09:24:40 INFO - Cleaning up... 09:24:40 INFO - Return code: 0 09:24:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:24:40 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')]} 09:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:40 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')]} 09:24:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:40 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 0x28e31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aaca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c53090>, '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 0x2c53d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4df90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', '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 09:24:40 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 09:24:40 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 09:24:40 INFO - Using env: {'DISPLAY': ':0', 09:24:40 INFO - 'HOME': '/home/cltbld', 09:24:40 INFO - 'LANG': 'en_US.UTF-8', 09:24:40 INFO - 'LANGUAGE': 'en_US:en', 09:24:40 INFO - 'LOGNAME': 'cltbld', 09:24:40 INFO - 'MAIL': '/var/mail/cltbld', 09:24:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:40 INFO - 'MOZ_NO_REMOTE': '1', 09:24:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:40 INFO - 'NO_EM_RESTART': '1', 09:24:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:40 INFO - 'PWD': '/builds/slave/test', 09:24:40 INFO - 'SHELL': '/bin/bash', 09:24:40 INFO - 'SHLVL': '1', 09:24:40 INFO - 'TERM': 'linux', 09:24:40 INFO - 'TMOUT': '86400', 09:24:40 INFO - 'USER': 'cltbld', 09:24:40 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:24:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:40 INFO - '_': '/tools/buildbot/bin/python'} 09:24:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:40 INFO - Downloading/unpacking jsonschema==2.5.1 09:24:40 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 09:24:40 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 09:24:40 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 09:24:40 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 09:24:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:24:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:24:41 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 09:24:41 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 09:24:41 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 09:24:41 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 09:24:41 INFO - Downloading functools32-3.2.3-2.tar.gz 09:24:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:24:41 INFO - warning: no files found matching '*.txt' 09:24:41 INFO - no previously-included directories found matching 'build' 09:24:41 INFO - no previously-included directories found matching 'dist' 09:24:41 INFO - no previously-included directories found matching '.git*' 09:24:41 INFO - Installing collected packages: jsonschema, functools32 09:24:41 INFO - Running setup.py install for functools32 09:24:41 INFO - warning: no files found matching '*.txt' 09:24:41 INFO - no previously-included directories found matching 'build' 09:24:41 INFO - no previously-included directories found matching 'dist' 09:24:41 INFO - no previously-included directories found matching '.git*' 09:24:41 INFO - Successfully installed jsonschema functools32 09:24:41 INFO - Cleaning up... 09:24:41 INFO - Return code: 0 09:24:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:24:42 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')]} 09:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:42 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')]} 09:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:42 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 0x28e31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aaca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c53090>, '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 0x2c53d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4df90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', '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 09:24:42 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 09:24:42 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 09:24:42 INFO - Using env: {'DISPLAY': ':0', 09:24:42 INFO - 'HOME': '/home/cltbld', 09:24:42 INFO - 'LANG': 'en_US.UTF-8', 09:24:42 INFO - 'LANGUAGE': 'en_US:en', 09:24:42 INFO - 'LOGNAME': 'cltbld', 09:24:42 INFO - 'MAIL': '/var/mail/cltbld', 09:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:42 INFO - 'MOZ_NO_REMOTE': '1', 09:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:42 INFO - 'NO_EM_RESTART': '1', 09:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:42 INFO - 'PWD': '/builds/slave/test', 09:24:42 INFO - 'SHELL': '/bin/bash', 09:24:42 INFO - 'SHLVL': '1', 09:24:42 INFO - 'TERM': 'linux', 09:24:42 INFO - 'TMOUT': '86400', 09:24:42 INFO - 'USER': 'cltbld', 09:24:42 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:42 INFO - '_': '/tools/buildbot/bin/python'} 09:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:24:42 INFO - Cleaning up... 09:24:42 INFO - Return code: 0 09:24:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:24:42 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')]} 09:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:42 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')]} 09:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:42 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 0x28e31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aaca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c53090>, '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 0x2c53d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4df90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', '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 09:24:42 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 09:24:42 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 09:24:42 INFO - Using env: {'DISPLAY': ':0', 09:24:42 INFO - 'HOME': '/home/cltbld', 09:24:42 INFO - 'LANG': 'en_US.UTF-8', 09:24:42 INFO - 'LANGUAGE': 'en_US:en', 09:24:42 INFO - 'LOGNAME': 'cltbld', 09:24:42 INFO - 'MAIL': '/var/mail/cltbld', 09:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:42 INFO - 'MOZ_NO_REMOTE': '1', 09:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:42 INFO - 'NO_EM_RESTART': '1', 09:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:42 INFO - 'PWD': '/builds/slave/test', 09:24:42 INFO - 'SHELL': '/bin/bash', 09:24:42 INFO - 'SHLVL': '1', 09:24:42 INFO - 'TERM': 'linux', 09:24:42 INFO - 'TMOUT': '86400', 09:24:42 INFO - 'USER': 'cltbld', 09:24:42 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:42 INFO - '_': '/tools/buildbot/bin/python'} 09:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:42 INFO - Downloading/unpacking blobuploader==1.2.4 09:24:42 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 09:24:42 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 09:24:42 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 09:24:42 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 09:24:43 INFO - Downloading blobuploader-1.2.4.tar.gz 09:24:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:24:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:24:43 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 09:24:43 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 09:24:43 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 09:24:43 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 09:24:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:24:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:24:43 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 09:24:43 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 09:24:43 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 09:24:43 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 09:24:44 INFO - Downloading docopt-0.6.1.tar.gz 09:24:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:24:44 INFO - Installing collected packages: blobuploader, requests, docopt 09:24:44 INFO - Running setup.py install for blobuploader 09:24:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:24:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:24:44 INFO - Running setup.py install for requests 09:24:44 INFO - Running setup.py install for docopt 09:24:44 INFO - Successfully installed blobuploader requests docopt 09:24:44 INFO - Cleaning up... 09:24:44 INFO - Return code: 0 09:24:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:44 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')]} 09:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:44 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')]} 09:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:44 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 0x28e31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aaca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c53090>, '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 0x2c53d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4df90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', '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 09:24:44 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 09:24:44 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 09:24:44 INFO - Using env: {'DISPLAY': ':0', 09:24:44 INFO - 'HOME': '/home/cltbld', 09:24:44 INFO - 'LANG': 'en_US.UTF-8', 09:24:44 INFO - 'LANGUAGE': 'en_US:en', 09:24:44 INFO - 'LOGNAME': 'cltbld', 09:24:44 INFO - 'MAIL': '/var/mail/cltbld', 09:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:44 INFO - 'MOZ_NO_REMOTE': '1', 09:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:44 INFO - 'NO_EM_RESTART': '1', 09:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:44 INFO - 'PWD': '/builds/slave/test', 09:24:44 INFO - 'SHELL': '/bin/bash', 09:24:44 INFO - 'SHLVL': '1', 09:24:44 INFO - 'TERM': 'linux', 09:24:44 INFO - 'TMOUT': '86400', 09:24:44 INFO - 'USER': 'cltbld', 09:24:44 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:44 INFO - '_': '/tools/buildbot/bin/python'} 09:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:44 INFO - Running setup.py (path:/tmp/pip-WDbsKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:45 INFO - Running setup.py (path:/tmp/pip-mKll7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:45 INFO - Running setup.py (path:/tmp/pip-b4dfbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:45 INFO - Running setup.py (path:/tmp/pip-rfaWmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:45 INFO - Running setup.py (path:/tmp/pip-QTwOlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:45 INFO - Running setup.py (path:/tmp/pip-v1YEvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:45 INFO - Running setup.py (path:/tmp/pip-dKhhwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:45 INFO - Running setup.py (path:/tmp/pip-OEU9WF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:45 INFO - Running setup.py (path:/tmp/pip-RrWTmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:45 INFO - Running setup.py (path:/tmp/pip-l0KnLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:45 INFO - Running setup.py (path:/tmp/pip-K6bNnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:45 INFO - Running setup.py (path:/tmp/pip-RGsypS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:45 INFO - Running setup.py (path:/tmp/pip-uLRfoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:45 INFO - Running setup.py (path:/tmp/pip-xP8AoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:45 INFO - Running setup.py (path:/tmp/pip-Xcrrbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:45 INFO - Running setup.py (path:/tmp/pip-1xQlQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:46 INFO - Running setup.py (path:/tmp/pip-aHSbdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:24:46 INFO - Running setup.py install for manifestparser 09:24:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:24:46 INFO - Running setup.py install for mozcrash 09:24:46 INFO - Running setup.py install for mozdebug 09:24:46 INFO - Running setup.py install for mozdevice 09:24:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:24:46 INFO - Running setup.py install for mozfile 09:24:46 INFO - Running setup.py install for mozhttpd 09:24:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:24:46 INFO - Running setup.py install for mozinfo 09:24:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:24:46 INFO - Running setup.py install for mozInstall 09:24:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:24:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:24:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:24:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:24:46 INFO - Running setup.py install for mozleak 09:24:47 INFO - Running setup.py install for mozlog 09:24:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:24:47 INFO - Running setup.py install for moznetwork 09:24:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:24:47 INFO - Running setup.py install for mozprocess 09:24:47 INFO - Running setup.py install for mozprofile 09:24:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:24:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:24:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:24:47 INFO - Running setup.py install for mozrunner 09:24:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:24:47 INFO - Running setup.py install for mozscreenshot 09:24:47 INFO - Running setup.py install for moztest 09:24:47 INFO - Running setup.py install for mozversion 09:24:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:24:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:24:47 INFO - Cleaning up... 09:24:47 INFO - Return code: 0 09:24:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24: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')]} 09:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24: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')]} 09:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:47 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 0x28e31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aaca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c53090>, '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 0x2c53d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4df90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', '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 09:24:47 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 09:24:47 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 09:24:47 INFO - Using env: {'DISPLAY': ':0', 09:24:47 INFO - 'HOME': '/home/cltbld', 09:24:47 INFO - 'LANG': 'en_US.UTF-8', 09:24:47 INFO - 'LANGUAGE': 'en_US:en', 09:24:47 INFO - 'LOGNAME': 'cltbld', 09:24:47 INFO - 'MAIL': '/var/mail/cltbld', 09:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:47 INFO - 'MOZ_NO_REMOTE': '1', 09:24:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:47 INFO - 'NO_EM_RESTART': '1', 09:24:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:47 INFO - 'PWD': '/builds/slave/test', 09:24:47 INFO - 'SHELL': '/bin/bash', 09:24:47 INFO - 'SHLVL': '1', 09:24:47 INFO - 'TERM': 'linux', 09:24:47 INFO - 'TMOUT': '86400', 09:24:47 INFO - 'USER': 'cltbld', 09:24:47 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:47 INFO - '_': '/tools/buildbot/bin/python'} 09:24:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:48 INFO - Running setup.py (path:/tmp/pip-UI47UT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:48 INFO - Running setup.py (path:/tmp/pip-sGOLTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:48 INFO - Running setup.py (path:/tmp/pip-DWFnJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:48 INFO - Running setup.py (path:/tmp/pip-NP8gl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:48 INFO - Running setup.py (path:/tmp/pip-wHvV5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:48 INFO - Running setup.py (path:/tmp/pip-e8hx31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:48 INFO - Running setup.py (path:/tmp/pip-zbV7cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:48 INFO - Running setup.py (path:/tmp/pip-P4Gl9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:48 INFO - Running setup.py (path:/tmp/pip-BGbbLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:48 INFO - Running setup.py (path:/tmp/pip-4CSic8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:48 INFO - Running setup.py (path:/tmp/pip-7HaIoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:48 INFO - Running setup.py (path:/tmp/pip-LVFBkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:48 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)) 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:48 INFO - Running setup.py (path:/tmp/pip-mNe6Gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:49 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)) 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:49 INFO - Running setup.py (path:/tmp/pip-4FifTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:49 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)) 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:49 INFO - Running setup.py (path:/tmp/pip-PxqvNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:49 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)) 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:49 INFO - Running setup.py (path:/tmp/pip-1VCMDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:49 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)) 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:49 INFO - Running setup.py (path:/tmp/pip-dgZf8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:49 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)) 09:24:49 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)) 09:24:49 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)) 09:24:49 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)) 09:24:49 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)) 09:24:49 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)) 09:24:49 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)) 09:24:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:50 INFO - Downloading blessings-1.6.tar.gz 09:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:24:50 INFO - Installing collected packages: blessings 09:24:50 INFO - Running setup.py install for blessings 09:24:50 INFO - Successfully installed blessings 09:24:50 INFO - Cleaning up... 09:24:50 INFO - Return code: 0 09:24:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:24:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:50 INFO - Reading from file tmpfile_stdout 09:24:50 INFO - Current package versions: 09:24:50 INFO - argparse == 1.2.1 09:24:50 INFO - blessings == 1.6 09:24:50 INFO - blobuploader == 1.2.4 09:24:50 INFO - docopt == 0.6.1 09:24:50 INFO - functools32 == 3.2.3-2 09:24:50 INFO - jsonschema == 2.5.1 09:24:50 INFO - manifestparser == 1.1 09:24:50 INFO - mozInstall == 1.13 09:24:50 INFO - mozcrash == 1.0 09:24:50 INFO - mozdebug == 0.1 09:24:50 INFO - mozdevice == 0.51 09:24:50 INFO - mozfile == 1.2 09:24:50 INFO - mozhttpd == 0.7 09:24:50 INFO - mozinfo == 0.10 09:24:50 INFO - mozleak == 0.1 09:24:50 INFO - mozlog == 3.5 09:24:50 INFO - moznetwork == 0.27 09:24:50 INFO - mozprocess == 0.25 09:24:50 INFO - mozprofile == 0.28 09:24:50 INFO - mozrunner == 6.13 09:24:50 INFO - mozscreenshot == 0.1 09:24:50 INFO - mozsystemmonitor == 0.3 09:24:50 INFO - moztest == 0.8 09:24:50 INFO - mozversion == 1.4 09:24:50 INFO - psutil == 3.1.1 09:24:50 INFO - requests == 1.2.3 09:24:50 INFO - wsgiref == 0.1.2 09:24:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:50 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 0x28e31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aaca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c53090>, '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 0x2c53d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4df90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', '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 09:24:50 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 09:24:50 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 09:24:50 INFO - Using env: {'DISPLAY': ':0', 09:24:50 INFO - 'HOME': '/home/cltbld', 09:24:50 INFO - 'LANG': 'en_US.UTF-8', 09:24:50 INFO - 'LANGUAGE': 'en_US:en', 09:24:50 INFO - 'LOGNAME': 'cltbld', 09:24:50 INFO - 'MAIL': '/var/mail/cltbld', 09:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:50 INFO - 'MOZ_NO_REMOTE': '1', 09:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:50 INFO - 'NO_EM_RESTART': '1', 09:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:50 INFO - 'PWD': '/builds/slave/test', 09:24:50 INFO - 'SHELL': '/bin/bash', 09:24:50 INFO - 'SHLVL': '1', 09:24:50 INFO - 'TERM': 'linux', 09:24:50 INFO - 'TMOUT': '86400', 09:24:50 INFO - 'USER': 'cltbld', 09:24:50 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:50 INFO - '_': '/tools/buildbot/bin/python'} 09:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:51 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)) 09:24:51 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)) 09:24:51 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)) 09:24:51 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)) 09:24:51 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)) 09:24:51 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)) 09:24:51 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)) 09:24:51 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)) 09:24:51 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)) 09:24:51 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:24:52 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)) 09:24:52 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)) 09:24:52 INFO - Installing collected packages: simplejson 09:24:52 INFO - Running setup.py install for simplejson 09:24:52 INFO - building 'simplejson._speedups' extension 09:24:52 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 09:24:53 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 09:24:53 INFO - Successfully installed simplejson 09:24:53 INFO - Cleaning up... 09:24:53 INFO - Return code: 0 09:24:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:24:53 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')]} 09:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:53 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')]} 09:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:53 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 0x28e31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aaca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c4fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c53090>, '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 0x2c53d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4df90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', '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 09:24:53 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 09:24:53 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 09:24:53 INFO - Using env: {'DISPLAY': ':0', 09:24:53 INFO - 'HOME': '/home/cltbld', 09:24:53 INFO - 'LANG': 'en_US.UTF-8', 09:24:53 INFO - 'LANGUAGE': 'en_US:en', 09:24:53 INFO - 'LOGNAME': 'cltbld', 09:24:53 INFO - 'MAIL': '/var/mail/cltbld', 09:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:53 INFO - 'MOZ_NO_REMOTE': '1', 09:24:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:24:53 INFO - 'NO_EM_RESTART': '1', 09:24:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:53 INFO - 'PWD': '/builds/slave/test', 09:24:53 INFO - 'SHELL': '/bin/bash', 09:24:53 INFO - 'SHLVL': '1', 09:24:53 INFO - 'TERM': 'linux', 09:24:53 INFO - 'TMOUT': '86400', 09:24:53 INFO - 'USER': 'cltbld', 09:24:53 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:53 INFO - '_': '/tools/buildbot/bin/python'} 09:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:24:53 INFO - Cleaning up... 09:24:53 INFO - Return code: 0 09:24:53 INFO - Running post-action listener: _resource_record_post_action 09:24:53 INFO - Running post-action listener: _start_resource_monitoring 09:24:53 INFO - Starting resource monitoring. 09:24:53 INFO - [mozharness: 2017-09-01 16:24:53.553925Z] Finished create-virtualenv step (success) 09:24:53 INFO - [mozharness: 2017-09-01 16:24:53.554441Z] Running install step. 09:24:53 INFO - Running pre-action listener: _resource_record_pre_action 09:24:53 INFO - Running main action method: install 09:24:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:53 INFO - Reading from file tmpfile_stdout 09:24:53 INFO - Detecting whether we're running mozinstall >=1.0... 09:24:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:24:53 INFO - Reading from file tmpfile_stdout 09:24:53 INFO - Output received: 09:24:53 INFO - Usage: mozinstall [options] installer 09:24:53 INFO - Options: 09:24:53 INFO - -h, --help show this help message and exit 09:24:53 INFO - -d DEST, --destination=DEST 09:24:53 INFO - Directory to install application into. [default: 09:24:53 INFO - "/builds/slave/test"] 09:24:53 INFO - --app=APP Application being installed. [default: firefox] 09:24:53 INFO - mkdir: /builds/slave/test/build/application 09:24:53 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'] 09:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:25:10 INFO - Reading from file tmpfile_stdout 09:25:10 INFO - Output received: 09:25:10 INFO - /builds/slave/test/build/application/firefox/firefox 09:25:10 INFO - Running post-action listener: _resource_record_post_action 09:25:10 INFO - [mozharness: 2017-09-01 16:25:10.195274Z] Finished install step (success) 09:25:10 INFO - [mozharness: 2017-09-01 16:25:10.195420Z] Running setup-mitmproxy step. 09:25:10 INFO - Running pre-action listener: _resource_record_pre_action 09:25:10 INFO - Running main action method: setup_mitmproxy 09:25:10 INFO - Skipping: mitmproxy is not required 09:25:10 INFO - Running post-action listener: _resource_record_post_action 09:25:10 INFO - [mozharness: 2017-09-01 16:25:10.195949Z] Finished setup-mitmproxy step (success) 09:25:10 INFO - [mozharness: 2017-09-01 16:25:10.196052Z] Running run-tests step. 09:25:10 INFO - Running pre-action listener: _resource_record_pre_action 09:25:10 INFO - Running pre-action listener: _set_gcov_prefix 09:25:10 INFO - Running main action method: run_tests 09:25:10 WARNING - Try message not found. 09:25:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:25:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:25:10 INFO - Python 2.7.3 09:25:10 INFO - Return code: 0 09:25:10 INFO - grabbing minidump binary from tooltool 09:25: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')]} 09:25:10 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 0x2c53090>, '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 0x2c53d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c4df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:25:10 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 09:25:10 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 09:25:10 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 09:25:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:25:10 INFO - Return code: 0 09:25:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:25:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:25:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:25:10 INFO - ENV: RUST_BACKTRACE is now full 09:25:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:25:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:25:10 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/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 09:25:10 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/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 09:25:10 INFO - Using env: {'DISPLAY': ':0', 09:25:10 INFO - 'HOME': '/home/cltbld', 09:25:10 INFO - 'LANG': 'en_US.UTF-8', 09:25:10 INFO - 'LANGUAGE': 'en_US:en', 09:25:10 INFO - 'LOGNAME': 'cltbld', 09:25:10 INFO - 'MAIL': '/var/mail/cltbld', 09:25:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:25:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:10 INFO - 'MOZ_NO_REMOTE': '1', 09:25:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:25:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:10 INFO - 'NO_EM_RESTART': '1', 09:25:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:10 INFO - 'PWD': '/builds/slave/test', 09:25:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:25:10 INFO - 'RUST_BACKTRACE': 'full', 09:25:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:25:10 INFO - 'SHELL': '/bin/bash', 09:25:10 INFO - 'SHLVL': '1', 09:25:10 INFO - 'TERM': 'linux', 09:25:10 INFO - 'TMOUT': '86400', 09:25:10 INFO - 'USER': 'cltbld', 09:25:10 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:10 INFO - '_': '/tools/buildbot/bin/python'} 09:25:10 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/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 09:25:10 INFO - mozversion application_buildid: 20170901150655 09:25:10 INFO - mozversion application_changeset: 34933f6390d52779ea498a6a5fd5f34d54734780 09:25:10 INFO - mozversion application_display_name: Nightly 09:25:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:25:10 INFO - mozversion application_name: Firefox 09:25:10 INFO - mozversion application_remotingname: firefox 09:25:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:25:10 INFO - mozversion application_vendor: Mozilla 09:25:10 INFO - mozversion application_version: 57.0a1 09:25:10 INFO - mozversion platform_buildid: 20170901150655 09:25:10 INFO - mozversion platform_changeset: 34933f6390d52779ea498a6a5fd5f34d54734780 09:25:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:25:10 INFO - mozversion platform_version: 57.0a1 09:25:10 INFO - using testdate: 1504283110 09:25:10 INFO - actual date: 1504283110 09:25:10 INFO - starting webserver on 'localhost:49951' 09:25:10 INFO - SUITE-START | Running 2 tests 09:25:10 INFO - TEST-START | dromaeo_css 09:25:10 INFO - Initialising browser for dromaeo_css test... 09:25:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49951/getInfo.html -profile /tmp/tmpZSLv4X/profile 09:25:10 INFO - TEST-INFO | started process 32398 (/builds/slave/test/build/application/firefox/firefox http://localhost:49951/getInfo.html) 09:25:15 INFO - TEST-INFO | 32398: exit 0 09:25:15 INFO - Browser initialized. 09:25:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:25:15 INFO - Using env: {'DISPLAY': ':0', 09:25:15 INFO - 'HOME': '/home/cltbld', 09:25:15 INFO - 'JSGC_DISABLE_POISONING': '1', 09:25:15 INFO - 'LANG': 'en_US.UTF-8', 09:25:15 INFO - 'LANGUAGE': 'en_US:en', 09:25:15 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:25:15 INFO - 'LOGNAME': 'cltbld', 09:25:15 INFO - 'MAIL': '/var/mail/cltbld', 09:25:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:25:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:25:15 INFO - 'MOZ_CRASHREPORTER': '1', 09:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:15 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:25:15 INFO - 'MOZ_NO_REMOTE': '1', 09:25:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:25:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:25:15 INFO - 'NO_EM_RESTART': '1', 09:25:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:15 INFO - 'PWD': '/builds/slave/test', 09:25:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:25:15 INFO - 'RUST_BACKTRACE': 'full', 09:25:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:25:15 INFO - 'SHELL': '/bin/bash', 09:25:15 INFO - 'SHLVL': '1', 09:25:15 INFO - 'TERM': 'linux', 09:25:15 INFO - 'TMOUT': '86400', 09:25:15 INFO - 'USER': 'cltbld', 09:25:15 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:15 INFO - '_': '/tools/buildbot/bin/python'} 09:25:15 INFO - TEST-INFO | started process 32583 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZSLv4X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:16 INFO - PID 32583 | 09:25:16 INFO - PID 32583 | (/builds/slave/test/build/application/firefox/firefox:32635): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:25:16 INFO - PID 32583 | 09:25:26 INFO - PID 32583 | RSS: Main: 164167680 09:25:26 INFO - PID 32583 | 09:28:26 INFO - PID 32583 | Cycle 1(1): loaded http://localhost:49951/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49951/tests/dromaeo/cssquery-ext.html) 09:28:27 INFO - PID 32583 | RSS: Main: 165212160 09:28:27 INFO - PID 32583 | 09:31:28 INFO - PID 32583 | Cycle 1(1): loaded http://localhost:49951/tests/dromaeo/cssquery-ext.html (next: http://localhost:49951/tests/dromaeo/cssquery-jquery.html) 09:31:29 INFO - PID 32583 | RSS: Main: 161734656 09:31:29 INFO - PID 32583 | 09:34:30 INFO - PID 32583 | Cycle 1(1): loaded http://localhost:49951/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49951/tests/dromaeo/cssquery-mootools.html) 09:34:31 INFO - PID 32583 | RSS: Main: 164143104 09:34:31 INFO - PID 32583 | 09:37:33 INFO - PID 32583 | Cycle 1(1): loaded http://localhost:49951/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49951/tests/dromaeo/cssquery-prototype.html) 09:37:33 INFO - PID 32583 | RSS: Main: 162480128 09:37:33 INFO - PID 32583 | 09:40:36 INFO - PID 32583 | Cycle 1(1): loaded http://localhost:49951/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49951/tests/dromaeo/cssquery-yui.html) 09:40:37 INFO - PID 32583 | RSS: Main: 163319808 09:40:37 INFO - PID 32583 | 09:43:39 INFO - PID 32583 | Cycle 1(1): loaded http://localhost:49951/tests/dromaeo/cssquery-yui.html (next: http://localhost:49951/tests/dromaeo/cssquery-dojo.html) 09:43:39 INFO - PID 32583 | RSS: Main: 166289408 09:43:39 INFO - PID 32583 | 09:43:39 INFO - PID 32583 | __start_tp_report 09:43:39 INFO - PID 32583 | _x_x_mozilla_page_load 09:43:39 INFO - PID 32583 | _x_x_mozilla_page_load_details 09:43:39 INFO - PID 32583 | |i|pagename|runs| 09:43:39 INFO - PID 32583 | |0;dojo.html;3841.807909604519;3853.903652408691;3856.961430385696;3891;3937.645611894929;6503.219613646357;6725.361090696389;6747.22406923204;6810.387065164135;6819.454443644503;6670.833229169261;6733.629650369227;6740.52816302859;6764.425023872976;6792.3207679232055;5967.731452084645;6058.485028772559;6084.148219249309;6137;6158.7228574714245;6052.546059045567;6118.051701986177;6154.230721159855;6157.168782214389;6172.969135154318;5966.940330596725;6054.788082417094;6133.417325354084;6194.628322300677;6235.345288744685;6029.336772954972;6067.575269731121;6136.2636483622255;6141.385861413867;6156.6613836238985;6049.243844519435;6052.061930400795;6103.877922441527;6125.540584456184;6175.969120154416;6683.732650694015;6756.898646520306;6779.966100169468;6802.115724955724;6803.795886123375;7345.853082938364;7354.852902941911;7358.484906056578;7365.153007404164;7372.152202496674;7327.047483827122;7343.000952794264;7454.40364770817;7458.888116678254;7463.477556571042;6705.731770729214;6856.5200435969;6861.759838405632;6870.175578930511;6873.2783057778715;7455.739049133254;7465.328120469184;7494.6627401767055;7557.05536807899;7565.130010048915;7202.351788339075;7202.855942881218;7247.673854676446;7260.346568808727;7324.450666199949;7177.856442871218;7208.603526805971;7281.126264848146;7337.19290878003;7413.258674132651;7263.382612477999;7275.89086163708;7287.781366559012;7409.555426674417;7424.146223184295;7284.645785347765;7324.858208472099;7381.630918454163;7435.330820226206;7455.030845989987;6124.693765311717;6163.322034576193;6172.444479996821;6240.812775616739;6258.9374106259265;5323.121684922022;5334.59990500714;5414.187871819258;5440.727963601883;5445.945540544544;6250.3749625037135;6361.300256971729;6387.105805187278;6397.976323788172;6407.903881441782;7267.946147808644;7285.490015698851;7301.926980730124;7304;7402.925970740223;6239.095331176954;6289.842753931189;6344.333844946214;6346.048092786119;6360.7137678804565;6556.573822701509;6609.768658096944;6711.295313991961;6727.159105111861;6731.225909020427;7119.1457025157315;7129.714811407589;7139.714411423589;7224.241454647186;7230.602316872622;7269.091363579552;7339.4862359636445;7343.926560734538;7347.08161479815;7361.926380736339;7041.3310735480045;7054.365107140176;7071.752488662872;7075.115610548682;7118.110236220473;7126.572405655677;7228.20489746138;7240.96379518099;7254.238304978113;7260.782176534713;7284.817879553054;7325.816854578678;7432.92567074344;7452.813679658052;7500.212477689982;7115.146182458138;7126.643667816485;7176.928230717626;7178;7205.71177152908;6980.476464265099;7049.929500704928;7055.2944705529035;7085.0435191248525;7151.8569628608175;6972.895406568804;7036.648167591702;7040.401565866754;7043.225245223124;7061.752838650623;7170.856582868418;7217.6030318333;7257.564546127249;7285.23505031964;7295.708171673074;6842;6931.306869313028;6938.063361446342;6941.618210998444;6997 09:43:39 INFO - PID 32583 | |1;ext.html;851.0872708743616;901.8241442918631;919.246218101157;920.8894932608084;1255.1402289431735;31354.372912541763;32494.67505324946;40919.79540102299;41884.162316753645;42025;43522.347164792554;43850.342244866355;43894.12211755763;43974.560254397446;44058.55941440585;7963.402744794149;8059.838803223932;8245.587720613976;8250.46371985822;8252.21603947627;7985.9600701996405;8052.59737013147;8057.1539988301265;8076.353891688651;8091.231333023354;8059.355251579859;8114.553699546523;8147.307478864274;8148;8150.307223885977;8030.7590772276635;8033.839323213532;8066.959665201695;8072.354211663052;8082.2725954664365;8076.6769329227345;8082.353411727047;8086.353091752646;8099.190080991853;8102.149274326229;38609.22781544353;38665.61334386676;38679.806600967095;38737;38758;3670;3710.9443358349645;3711.981440092782;3726.2547490502006;3732.309522738302;31507;31821.931574447586;31866.522002169986;31891.202719931956;31925.680743192504;5338.652987555796;5491.203775452537;5499.202615620753;5508.14623733322;5616.606837521375;200282;201469;202283;202642;202760;49954;49970.50029499731;50011.49988500141;50139;50185;52172.47827521676;55517.44482555203;56446.43553564494;56578.7171064142;56611;12259.938700306442;12438.626841194779;16077.276522556513;16433.753493697604;16494.092824894746;13456.663583410493;13464.326783660777;13469.663258418617;13475;13495.25776082325;14688.706225875423;14707.41170353174;14744.189069601274;14786.186759728103;14883.404663813326;5921.61509501881;5960.135780311917;5993.220881285405;6020.367861374581;6056.212692349966;1084.0514549768952;1100.3287994323457;1107.1364335818075;1132.2017977325966;1137.9089672826153;25227.873860630578;25429.872850635627;25581;25601.743982559936;25776.742232577435;1196.6934256468537;1204.8855358740905;1225.8957988571071;1227.5764861122898;1246.1214843535467;13365.53220637273;13586.184828910296;13630.386632601556;13658.034402454621;13761.105528140639;270108;273525;273628;274818;276606;53914.19128713072;54368.728156358964;54650.72674636601;54711;54749.45250547523;1488.5757559095823;1499.572621802781;1524.5883611425074;1529.5334922848638;1549.7210502109504;24667.753322467262;29332.41335173242;30376.240593984443;30673.53989690201;30756.846215769674;52240.47759522356;52650.736746317554;52816.47183528269;52905;52928.73535632297;220568;228046;228054;228558;228594;37148.25703485861;37864.81067594644;38580.614193857706;38582;38629.068546571994;47423.762881186754;48660;49109.75445122751;49295.75352123217;49445;36307.45538816867;36434.542618294974;36445.817770912035;36722;36937.07657308589;35467.645323546436;35531.644683553866;35570.93287201388;35621.28757424785;35654.28691426209;41361.79319103385;41387.586124139576;41414.1717165661;41450;41631;33983.15042123967;34223.48664769981;34473.827630861684;34475.310493790486;34885.825570873;26060;26195.476090478463;26476.602850957635;26488.41069535834;27032.729672703023 09:43:39 INFO - PID 32583 | |2;jquery.html;594.4085634793381;599.7720866070892;605.4944121658415;618.2673531864741;629.675717005742;22637;23162.768372316314;23332.766672333273;23347.766522334772;23365.766342336592;26221.737782622167;26663.733362666368;26794.464110717774;26860.865695671495;26961.460770784623;6572.40848323652;6683;6686.565373250747;6711.630860302681;6717.059611654372;6041.818745437644;6344.365563443642;6523.380278873499;6718.69765860535;6723.361280678328;6669.099671544328;6685.799426017227;6691.330866913294;6691.83270418241;6693.364130407628;6658.667066646648;6663.466922646201;6666.333366663319;6671.966640166816;6675.432588230006;6220.129181914535;6221.751129954796;6238.095476155977;6238.563300568917;6250.468710159642;30734.231644209074;31158;31344.686553134405;32668.509972350435;32730.672693273;2277.271273192579;2322.6632138340015;2323.453988312745;2324.418895276181;2325.186184835311;20531.794682053136;20639.58720825575;20827;21382.144714211412;21479;2552.5660637691635;2574.8068894832777;2623.0032587616774;2643.074923776682;2645.550256456407;151616;152597;153305;154734;156802;16222.918885405497;16340.18299085041;16517.58706032359;16680.833191667927;17132.828671713123;26630.6005409919;26643.333916651853;26766.19701408961;26778.062767803032;26779.866100669762;10926.617568385069;10952.39761813092;10986.780264394667;11153.888461115448;11192;10049.397036177852;10061.597536098474;10127.594896204217;10159.9492002541;10232.846507302396;9652.855207171899;9754.024597540189;9840.311178217456;9996.900030999743;10145.543450544743;2755.3938133610573;2834.3197632568035;2919.8832046717894;2925.2101932478113;2953.4388466191353;1031.7575369788094;1055.6041484443333;1060.1095080132725;1073.903348698621;1079.3416016230094;21476.57046859054;22093.447663808212;22244.888775556017;22330.330090097323;22479.550408992054;1093.7211011192096;1098.4617537406614;1110.0730889707186;1114.3982249585133;1115.3642423818508;11462.770744585061;12022.458989345501;12025.579104731467;12107;12212.267263964013;551095;552058;559141;563682;564569;131067;131386;131754;133355.33322333326;135141.32429337988;1224.9632511024683;1237.5359240284893;1261.3504045416619;1268.5877089945805;1269.8857102860786;19569.70645440348;19711.408657740292;23459.648105278095;24365.147219847222;24412.6338104925;64574.35425645871;64749;64958.675206623666;67028.6648566754;67302.66348668226;72708.27291727015;76160.61919690366;76592;76695.23304767103;77526.22473775415;15861.920690396475;15963.521094367188;16147.67704645924;16181.190940452694;16303.429379972118;25453.236402907944;25516.872415638547;25574.744252557237;25765.742342576334;25907.22278331653;18789.718154227972;20085.49786255355;20160.697589535874;20447.4888127798;20502.667326623723;16043.839561604234;16884.155792210586;18128.45614631563;18285.360012399502;18385.356512521998;20720;21154;21162.365129046153;21251.256206032715;21288.467788305417;15545.455909043128;16069;16168.272427741014;16209.675806483569;16212.756808648115;20402;20537.79462205359;20599.897000514902;20690.58618827585;20784 09:43:39 INFO - PID 32583 | |3;mootools.html;1407.6199426154938;1448.2107251547905;1474.7935289059526;1477.8965472416921;1488.5311126995002;16261.674766504662;16461.75307370389;17114.229859656316;17114.914425427854;17139.571510712223;16838.494845154633;16997.2351244194;17002.574935626617;17009.829901700978;17012.234449449774;2863.2555535560696;2920.050983430383;2920.050983430383;2920.2407374082786;2922.4155168966186;2802;2874.985625071882;2878.49626315395;2879.55366918127;2880.29432788967;2819.4783964966546;2853.443578502193;2853.7288957549;2854.685984541699;2857.5713642953515;2734.111413790516;2799.9860000699864;2801.845898475573;2802.7337402946687;2803.3832556837465;2771.556550951838;2804.467151241258;2817.1689351641317;2820.4077143799827;2820.873060712273;16021.439249626206;16332.428365007168;16345;16355.754663680054;16357.672846543002;8261.463004904663;8468.026176989664;8498.490090594521;8509.48943063412;8543.957280213559;5308.124159513675;5551.02938456846;5678.858028549319;5722.97138514309;5728.341240757283;2118.4915620251027;2167.3606286145623;2169.370882444089;2207.381933058746;2220.0897631970975;28558.714412855814;28835.27911802201;28864.134075977756;28882.566761498594;28884;7165.570065796069;7167.569945803269;7215.891761623579;7226.458015648847;7278.745243916544;18982.620347593245;18991.43025709231;19080.71378929317;19092.2363105477;19120.043997800054;8263.421560490708;8624.18070283322;8626.956865215634;8628.654853805903;8652.610632521426;6532.379423954717;6797.184337879387;6932.861342773116;7341.265873412616;7768;5627.662340259598;5781.768729250867;5803;5813.186153938452;5934.762609495659;1582.9287682054335;1602.9759553606473;1613.983190589921;1631.2007116512823;1638.4756877799227;987.5802783816849;1015.5379302417382;1017.2574020964737;1023.7184774187009;1036.2228328753436;8677.219050285505;8715.084916083846;8715.172058654418;8726.432781869158;8799.164079412574;791.3352783661752;1053.7154968158554;1064.6965614799744;1080.4921686807188;1085.180688580117;5590.944090559124;5678.716064196774;5716.742746576414;5729.598928074995;5813.273340832396;23909;25067.74932250654;25179.24462266135;25187;25191.11831085903;22671.546569068858;24460.38849028753;24516.877415613166;24606.630900536507;24727.87636061808;12941.2882291473;13138.868611313956;13209.73580528384;13250;13355.131916425595;9462.881114690348;9991.100800927952;10062.949685251528;10070.295079344376;10422.739431514272;20643.793562064187;20655.17379304811;20751.066202020644;21107.894460527597;21149.365519034454;21362.679559806926;21619.891900540395;21625;21668.24161154352;21856.344309670734;13670.726585468035;14001.649958751113;14022.29888505591;14028.649283767987;14039.929800350932;15462;15906.363745450053;15952.441664541686;15968.600784980468;16006;11146.777064458827;11442.656720298404;12083.879161208628;12140.33228172459;12177.208481448677;12322.507099715911;12323.630291091282;12363.443645035815;12372.443240054039;12677.239365638092;9905.900940990497;9968.651097211563;10017.749556261151;10067.899321006696;10073.899261007296;8335.624896879775;8377;8380.120087390736;8388.454750441202;8421;8241.175882411952;8242.423030387816;8280.792980175303;8296.336293096418;8361.16388361159 09:43:39 INFO - PID 32583 | |4;prototype.html;565.2679779685309;567.9602427830048;588.7056471764117;602.0487629545319;603.1555821849411;6566.7701630442825;6603.834904127387;6604.438622717069;6662.666866656672;6714.865702685949;6518.445932095766;6538.313477084897;6542.280349161589;6571.802845914618;6580.769673061443;5393.83818485446;5405.621606487547;5423.810166644168;5427.755750991195;5449.6730196188155;5123.615728820335;5186.5850731941355;5391.73041347931;5421.159720243369;5439.782408703647;5191.922121168185;5204.843854684365;5204.959008198375;5224.190250511177;5229.797146656252;5102.489751024905;5209.400918894337;5214.843554693365;5218.251885618303;5236.7905283788605;4706;4729.834455794065;4733.5503127202865;4734.881627959294;4737;6421.93578064218;6423;6472.9029064564065;6472.967635161794;6539.673016349164;6170.8765824683705;6173.074038894157;6173.3146081454715;6193.721282542297;6198.535109866778;7055.717771289143;7061.293870612898;7067.681954312068;7068.363847253772;7080.716771329141;5288.418273989858;5312.73436328182;5315.681059136464;5322.600804939645;5336.252924590522;7134.821629459253;7141.250168732312;7284.344409003215;7380.446466515033;7445;5383.623146379716;5427.728613569306;5428.565714742811;5445.128779395278;5452.40023597404;5508.366537848199;5513.173024046425;5519.944800551943;5521.889562208733;5564.387917329091;6095.299040610386;6115.663638499926;6117.235345581802;6161.137440758297;6161.815145545641;7087.397571206505;7101.964490177619;7131.716291067658;7158.427325813923;7189.424846012306;1899.5725961658516;1900.790912999569;1905.752252207232;1921.5292253398006;1923.6537423263946;1287.76820172368;1337.458329376604;1338.4980632262902;1341.5304643374936;1352.573939209146;6061.787837425757;6072.453479186895;6109;6122.816315510542;6133.355997620275;7052.788416347517;7073.257307982691;7097.538659987187;7140.500164988505;7195.92804071963;6057.303410107894;6089.208402907594;6136.938630613726;6190.721417536221;6196.783112591038;3706.1475860551686;3714.8699795507027;3728.142527218755;3785.205106927521;3796.7911764853197;6762.797116086525;6863.014588653951;7002.229754726976;7076.964615176994;7086.291370862976;7196.8920466192;7229.530080544748;7282.635868206568;7312.817179570553;7340.44946629005;6810.944303632945;6907.689153988083;6917;6928.411085057826;6975.3024697530855;7062.329078737512;7066;7090.787276381717;7096.006559081629;7138.179109402485;7109.28907109285;7131.322524360177;7165.820854478471;7165.856682866418;7213.6032518212005;6913.3777959981835;6945.0971373721095;6969.965150174216;6999.825004374931;7052.153741551047;5401.513863752124;5417.268668729828;5455.8363249102595;5456.154296084113;5460.754266058117;6953.930460695328;7089.255628159175;7108.466864985044;7210.423166146591;7245.601491917995;6671.366220209073;6825.624590647562;6857.142857142857;6882.724691012303;6902.585844849325;6835.282295358916;6871.931280687129;6876.346747059021;6878.38094571491;6923.723051077901;6874.828129296808;6898.586084834926;6906.136732908387;6907.825669636274;6917.550359226634;6950.65246737651;6959.826004349729;6987.0567473392475;6994.440444764508;7004.509684322253;6778.2205046420295;6799.796006119825;6811.625360605214;6822;6842.726290948505 09:43:39 INFO - PID 32583 | |5;yui.html;979.3927764785832;988.4761076629385;1015.6648306059001;1018.6129270877066;1034.8240799064156;19284.807151928493;19834.305799297028;19899.204031838726;19917.30289439868;23182.768172318272;21863.12547498102;22201.777982220174;22329.441763955867;22341;22342;999.9550020249088;1019.108280254777;1020.097213965642;1020.5917632946844;1021.0453226233458;1050.0811789684026;1086.684861390196;1093.797647435223;1105.9391733454656;1110.8778034416207;1149.373591392693;1156.3062162702354;1157.2304417562311;1163.1799581295168;1166.562539047857;1155.3299086529835;1155.7052951497399;1157.1668398752884;1157.322966064853;1157.3692337675986;845.7716416567492;849.0321034021221;849.5030407211789;850.4302117581235;851.6593362654926;18663.160157792932;19149.329773457863;19183.808161918343;19249.9037504813;19362.128704208204;22161.66757498639;22269.66595501069;22328.665070023962;22370.2170424036;22633.773662263495;17005;17173.313067477287;17192.398266060754;17321.307147714077;17534.912325438418;3971.463852379921;3972.225416043844;3992.8602498912687;4025.054112283611;4028.3353246714255;140838.99999999898;141647;142529;143198;144082.9570965011;17150.31398744061;17515.56211094733;17531;17556.29774808993;17584.12079396051;33400.33199336;33760;34098.31803363968;34176.316473670886;34241.315173696385;8651.91348086511;8746.737597872074;8750.86873696895;8775.824483510421;8777.912220877837;4437.400950871591;4503.099380123988;4504.549545045535;4508.616767574727;4520.186366454004;6202.689865506707;6496.447801936888;6748.4938629602975;6769.695363708645;6772.356626120511;1305.3668970549277;1326.297062556827;1342.5569562044698;1342.8388593368663;1349.3657980749033;728.3699599846095;766.4404984361447;769.4613770360658;779.3570304498697;781.1212386065677;12637.936810316074;12796.552120675731;13624.455021799215;13797.10318829293;13823.585292441243;755.3013462547232;756.9318761311508;762.6339357108619;762.9694812207449;782.487470706688;6283.96858015707;6413.6793160341795;6519.445847102955;6600.108985287021;6636.270010298863;128213;129953;131152;133910.33044834714;134048;108896;118368.40815796038;119236.40381798036;120260;121515;102664;103471.4826425863;103963.4801825986;104161;104764.47617761862;13181.736365272833;13230.867691322963;13280.734385312433;13373.39819708096;13450.798238026633;49126;49638.25542616791;49639.75180124076;50519.74740126276;50946.98106037932;63374.36625633685;63540.36459635344;64054;64256.35743642691;66093.66953165203;19250.8074919249;19573.314933977243;19834.404967850987;19859.30492432758;19960.401187964384;21510.247141349977;21581.568368632852;21740.239091631716;21821.23625673094;22422.103115875183;20370.898145509178;20531;20711.275105371238;20860.58278834445;20885.89557052205;19440.2223911042;19482.220711171394;19867.403977880687;20050.799492005477;20207;18483.16825742818;18595.72106418432;18602.44192674222;18823.6235275291;19206.61586768285;14056.367463463965;14302.856971430152;14516.709665806837;14597.489087881873;14602.85397146015;13212.735745285232;13314.600561982768;13408.530701425403;13532.526361577298;13607.79588306196 09:43:39 INFO - PID 32583 | __end_tp_report 09:43:39 INFO - PID 32583 | __start_cc_report 09:43:39 INFO - PID 32583 | _x_x_mozilla_cycle_collect,371 09:43:39 INFO - PID 32583 | __end_cc_report 09:43:39 INFO - PID 32583 | __startTimestamp1504284219333__endTimestamp 09:43:39 INFO - PID 32583 | 09:43:39 INFO - PID 32583 | ------- Summary: start ------- 09:43:39 INFO - PID 32583 | Number of tests: 6 09:43:39 INFO - PID 32583 | 09:43:39 INFO - PID 32583 | [#0] dojo.html Cycles:165 Average:6753.20 Median:7036.65 stddev:736.45 (10.5%) stddev-sans-first:702.40 09:43:39 INFO - PID 32583 | Values: 3841.8 3853.9 3857.0 3891.0 3937.6 6503.2 6725.4 6747.2 6810.4 6819.5 6670.8 6733.6 6740.5 6764.4 6792.3 5967.7 6058.5 6084.1 6137.0 6158.7 6052.5 6118.1 6154.2 6157.2 6173.0 5966.9 6054.8 6133.4 6194.6 6235.3 6029.3 6067.6 6136.3 6141.4 6156.7 6049.2 6052.1 6103.9 6125.5 6176.0 6683.7 6756.9 6780.0 6802.1 6803.8 7345.9 7354.9 7358.5 7365.2 7372.2 7327.0 7343.0 7454.4 7458.9 7463.5 6705.7 6856.5 6861.8 6870.2 6873.3 7455.7 7465.3 7494.7 7557.1 7565.1 7202.4 7202.9 7247.7 7260.3 7324.5 7177.9 7208.6 7281.1 7337.2 7413.3 7263.4 7275.9 7287.8 7409.6 7424.1 7284.6 7324.9 7381.6 7435.3 7455.0 6124.7 6163.3 6172.4 6240.8 6258.9 5323.1 5334.6 5414.2 5440.7 5445.9 6250.4 6361.3 6387.1 6398.0 6407.9 7267.9 7285.5 7301.9 7304.0 7402.9 6239.1 6289.8 6344.3 6346.0 6360.7 6556.6 6609.8 6711.3 6727.2 6731.2 7119.1 7129.7 7139.7 7224.2 7230.6 7269.1 7339.5 7343.9 7347.1 7361.9 7041.3 7054.4 7071.8 7075.1 7118.1 7126.6 7228.2 7241.0 7254.2 7260.8 7284.8 7325.8 7432.9 7452.8 7500.2 7115.1 7126.6 7176.9 7178.0 7205.7 6980.5 7049.9 7055.3 7085.0 7151.9 6972.9 7036.6 7040.4 7043.2 7061.8 7170.9 7217.6 7257.6 7285.2 7295.7 6842.0 6931.3 6938.1 6941.6 6997.0 09:43:39 INFO - PID 32583 | 09:43:39 INFO - PID 32583 | [#1] ext.html Cycles:180 Average:41675.67 Median:20430.12 stddev:61311.94 (300.1%) stddev-sans-first:61407.31 09:43:39 INFO - PID 32583 | Values: 851.1 901.8 919.2 920.9 1255.1 31354.4 32494.7 40919.8 41884.2 42025.0 43522.3 43850.3 43894.1 43974.6 44058.6 7963.4 8059.8 8245.6 8250.5 8252.2 7986.0 8052.6 8057.2 8076.4 8091.2 8059.4 8114.6 8147.3 8148.0 8150.3 8030.8 8033.8 8067.0 8072.4 8082.3 8076.7 8082.4 8086.4 8099.2 8102.1 38609.2 38665.6 38679.8 38737.0 38758.0 3670.0 3710.9 3712.0 3726.3 3732.3 31507.0 31821.9 31866.5 31891.2 31925.7 5338.7 5491.2 5499.2 5508.1 5616.6 200282.0 201469.0 202283.0 202642.0 202760.0 49954.0 49970.5 50011.5 50139.0 50185.0 52172.5 55517.4 56446.4 56578.7 56611.0 12259.9 12438.6 16077.3 16433.8 16494.1 13456.7 13464.3 13469.7 13475.0 13495.3 14688.7 14707.4 14744.2 14786.2 14883.4 5921.6 5960.1 5993.2 6020.4 6056.2 1084.1 1100.3 1107.1 1132.2 1137.9 25227.9 25429.9 25581.0 25601.7 25776.7 1196.7 1204.9 1225.9 1227.6 1246.1 13365.5 13586.2 13630.4 13658.0 13761.1 270108.0 273525.0 273628.0 274818.0 276606.0 53914.2 54368.7 54650.7 54711.0 54749.5 1488.6 1499.6 1524.6 1529.5 1549.7 24667.8 29332.4 30376.2 30673.5 30756.8 52240.5 52650.7 52816.5 52905.0 52928.7 220568.0 228046.0 228054.0 228558.0 228594.0 37148.3 37864.8 38580.6 38582.0 38629.1 47423.8 48660.0 49109.8 49295.8 49445.0 36307.5 36434.5 36445.8 36722.0 36937.1 35467.6 35531.6 35570.9 35621.3 35654.3 41361.8 41387.6 41414.2 41450.0 41631.0 33983.2 34223.5 34473.8 34475.3 34885.8 26060.0 26195.5 26476.6 26488.4 27032.7 09:43:39 INFO - PID 32583 | 09:43:39 INFO - PID 32583 | [#2] jquery.html Cycles:180 Average:38917.37 Median:12742.72 stddev:94017.95 (737.8%) stddev-sans-first:94237.67 09:43:39 INFO - PID 32583 | Values: 594.4 599.8 605.5 618.3 629.7 22637.0 23162.8 23332.8 23347.8 23365.8 26221.7 26663.7 26794.5 26860.9 26961.5 6572.4 6683.0 6686.6 6711.6 6717.1 6041.8 6344.4 6523.4 6718.7 6723.4 6669.1 6685.8 6691.3 6691.8 6693.4 6658.7 6663.5 6666.3 6672.0 6675.4 6220.1 6221.8 6238.1 6238.6 6250.5 30734.2 31158.0 31344.7 32668.5 32730.7 2277.3 2322.7 2323.5 2324.4 2325.2 20531.8 20639.6 20827.0 21382.1 21479.0 2552.6 2574.8 2623.0 2643.1 2645.6 151616.0 152597.0 153305.0 154734.0 156802.0 16222.9 16340.2 16517.6 16680.8 17132.8 26630.6 26643.3 26766.2 26778.1 26779.9 10926.6 10952.4 10986.8 11153.9 11192.0 10049.4 10061.6 10127.6 10159.9 10232.8 9652.9 9754.0 9840.3 9996.9 10145.5 2755.4 2834.3 2919.9 2925.2 2953.4 1031.8 1055.6 1060.1 1073.9 1079.3 21476.6 22093.4 22244.9 22330.3 22479.6 1093.7 1098.5 1110.1 1114.4 1115.4 11462.8 12022.5 12025.6 12107.0 12212.3 551095.0 552058.0 559141.0 563682.0 564569.0 131067.0 131386.0 131754.0 133355.3 135141.3 1225.0 1237.5 1261.4 1268.6 1269.9 19569.7 19711.4 23459.6 24365.1 24412.6 64574.4 64749.0 64958.7 67028.7 67302.7 72708.3 76160.6 76592.0 76695.2 77526.2 15861.9 15963.5 16147.7 16181.2 16303.4 25453.2 25516.9 25574.7 25765.7 25907.2 18789.7 20085.5 20160.7 20447.5 20502.7 16043.8 16884.2 18128.5 18285.4 18385.4 20720.0 21154.0 21162.4 21251.3 21288.5 15545.5 16069.0 16168.3 16209.7 16212.8 20402.0 20537.8 20599.9 20690.6 20784.0 09:43:39 INFO - PID 32583 | 09:43:39 INFO - PID 32583 | [#3] mootools.html Cycles:180 Average:10333.79 Median:25123.50 stddev:7510.63 (29.9%) stddev-sans-first:7501.76 09:43:39 INFO - PID 32583 | Values: 1407.6 1448.2 1474.8 1477.9 1488.5 16261.7 16461.8 17114.2 17114.9 17139.6 16838.5 16997.2 17002.6 17009.8 17012.2 2863.3 2920.1 2920.1 2920.2 2922.4 2802.0 2875.0 2878.5 2879.6 2880.3 2819.5 2853.4 2853.7 2854.7 2857.6 2734.1 2800.0 2801.8 2802.7 2803.4 2771.6 2804.5 2817.2 2820.4 2820.9 16021.4 16332.4 16345.0 16355.8 16357.7 8261.5 8468.0 8498.5 8509.5 8544.0 5308.1 5551.0 5678.9 5723.0 5728.3 2118.5 2167.4 2169.4 2207.4 2220.1 28558.7 28835.3 28864.1 28882.6 28884.0 7165.6 7167.6 7215.9 7226.5 7278.7 18982.6 18991.4 19080.7 19092.2 19120.0 8263.4 8624.2 8627.0 8628.7 8652.6 6532.4 6797.2 6932.9 7341.3 7768.0 5627.7 5781.8 5803.0 5813.2 5934.8 1582.9 1603.0 1614.0 1631.2 1638.5 987.6 1015.5 1017.3 1023.7 1036.2 8677.2 8715.1 8715.2 8726.4 8799.2 791.3 1053.7 1064.7 1080.5 1085.2 5590.9 5678.7 5716.7 5729.6 5813.3 23909.0 25067.7 25179.2 25187.0 25191.1 22671.5 24460.4 24516.9 24606.6 24727.9 12941.3 13138.9 13209.7 13250.0 13355.1 9462.9 9991.1 10062.9 10070.3 10422.7 20643.8 20655.2 20751.1 21107.9 21149.4 21362.7 21619.9 21625.0 21668.2 21856.3 13670.7 14001.6 14022.3 14028.6 14039.9 15462.0 15906.4 15952.4 15968.6 16006.0 11146.8 11442.7 12083.9 12140.3 12177.2 12322.5 12323.6 12363.4 12372.4 12677.2 9905.9 9968.7 10017.7 10067.9 10073.9 8335.6 8377.0 8380.1 8388.5 8421.0 8241.2 8242.4 8280.8 8296.3 8361.2 09:43:39 INFO - PID 32583 | 09:43:39 INFO - PID 32583 | [#4] prototype.html Cycles:180 Average:5883.65 Median:6538.99 stddev:1636.11 (25.0%) stddev-sans-first:1591.25 09:43:39 INFO - PID 32583 | Values: 565.3 568.0 588.7 602.0 603.2 6566.8 6603.8 6604.4 6662.7 6714.9 6518.4 6538.3 6542.3 6571.8 6580.8 5393.8 5405.6 5423.8 5427.8 5449.7 5123.6 5186.6 5391.7 5421.2 5439.8 5191.9 5204.8 5205.0 5224.2 5229.8 5102.5 5209.4 5214.8 5218.3 5236.8 4706.0 4729.8 4733.6 4734.9 4737.0 6421.9 6423.0 6472.9 6473.0 6539.7 6170.9 6173.1 6173.3 6193.7 6198.5 7055.7 7061.3 7067.7 7068.4 7080.7 5288.4 5312.7 5315.7 5322.6 5336.3 7134.8 7141.3 7284.3 7380.4 7445.0 5383.6 5427.7 5428.6 5445.1 5452.4 5508.4 5513.2 5519.9 5521.9 5564.4 6095.3 6115.7 6117.2 6161.1 6161.8 7087.4 7102.0 7131.7 7158.4 7189.4 1899.6 1900.8 1905.8 1921.5 1923.7 1287.8 1337.5 1338.5 1341.5 1352.6 6061.8 6072.5 6109.0 6122.8 6133.4 7052.8 7073.3 7097.5 7140.5 7195.9 6057.3 6089.2 6136.9 6190.7 6196.8 3706.1 3714.9 3728.1 3785.2 3796.8 6762.8 6863.0 7002.2 7077.0 7086.3 7196.9 7229.5 7282.6 7312.8 7340.4 6810.9 6907.7 6917.0 6928.4 6975.3 7062.3 7066.0 7090.8 7096.0 7138.2 7109.3 7131.3 7165.8 7165.9 7213.6 6913.4 6945.1 6970.0 6999.8 7052.2 5401.5 5417.3 5455.8 5456.2 5460.8 6953.9 7089.3 7108.5 7210.4 7245.6 6671.4 6825.6 6857.1 6882.7 6902.6 6835.3 6871.9 6876.3 6878.4 6923.7 6874.8 6898.6 6906.1 6907.8 6917.6 6950.7 6959.8 6987.1 6994.4 7004.5 6778.2 6799.8 6811.6 6822.0 6842.7 09:43:39 INFO - PID 32583 | 09:43:39 INFO - PID 32583 | [#5] yui.html Cycles:180 Average:26584.45 Median:19527.77 stddev:37343.74 (191.2%) stddev-sans-first:37399.00 09:43:39 INFO - PID 32583 | Values: 979.4 988.5 1015.7 1018.6 1034.8 19284.8 19834.3 19899.2 19917.3 23182.8 21863.1 22201.8 22329.4 22341.0 22342.0 1000.0 1019.1 1020.1 1020.6 1021.0 1050.1 1086.7 1093.8 1105.9 1110.9 1149.4 1156.3 1157.2 1163.2 1166.6 1155.3 1155.7 1157.2 1157.3 1157.4 845.8 849.0 849.5 850.4 851.7 18663.2 19149.3 19183.8 19249.9 19362.1 22161.7 22269.7 22328.7 22370.2 22633.8 17005.0 17173.3 17192.4 17321.3 17534.9 3971.5 3972.2 3992.9 4025.1 4028.3 140839.0 141647.0 142529.0 143198.0 144083.0 17150.3 17515.6 17531.0 17556.3 17584.1 33400.3 33760.0 34098.3 34176.3 34241.3 8651.9 8746.7 8750.9 8775.8 8777.9 4437.4 4503.1 4504.5 4508.6 4520.2 6202.7 6496.4 6748.5 6769.7 6772.4 1305.4 1326.3 1342.6 1342.8 1349.4 728.4 766.4 769.5 779.4 781.1 12637.9 12796.6 13624.5 13797.1 13823.6 755.3 756.9 762.6 763.0 782.5 6284.0 6413.7 6519.4 6600.1 6636.3 128213.0 129953.0 131152.0 133910.3 134048.0 108896.0 118368.4 119236.4 120260.0 121515.0 102664.0 103471.5 103963.5 104161.0 104764.5 13181.7 13230.9 13280.7 13373.4 13450.8 49126.0 49638.3 49639.8 50519.7 50947.0 63374.4 63540.4 64054.0 64256.4 66093.7 19250.8 19573.3 19834.4 19859.3 19960.4 21510.2 21581.6 21740.2 21821.2 22422.1 20370.9 20531.0 20711.3 20860.6 20885.9 19440.2 19482.2 19867.4 20050.8 20207.0 18483.2 18595.7 18602.4 18823.6 19206.6 14056.4 14302.9 14516.7 14597.5 14602.9 13212.7 13314.6 13408.5 13532.5 13607.8 09:43:39 INFO - PID 32583 | -------- Summary: end -------- 09:43:39 INFO - PID 32583 | 09:43:39 INFO - TEST-INFO | 32583: exit 0 09:43:40 INFO - TEST-OK | dromaeo_css | took 1109434ms 09:43:40 INFO - TEST-START | kraken 09:43:40 INFO - Initialising browser for kraken test... 09:43:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49951/getInfo.html -profile /tmp/tmpzZfe8x/profile 09:43:40 INFO - TEST-INFO | started process 492 (/builds/slave/test/build/application/firefox/firefox http://localhost:49951/getInfo.html) 09:43:44 INFO - TEST-INFO | 492: exit 0 09:43:44 INFO - Browser initialized. 09:43:44 INFO - Running cycle 1/1 for kraken test... 09:43:44 INFO - Using env: {'DISPLAY': ':0', 09:43:44 INFO - 'HOME': '/home/cltbld', 09:43:44 INFO - 'JSGC_DISABLE_POISONING': '1', 09:43:44 INFO - 'LANG': 'en_US.UTF-8', 09:43:44 INFO - 'LANGUAGE': 'en_US:en', 09:43:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:43:44 INFO - 'LOGNAME': 'cltbld', 09:43:44 INFO - 'MAIL': '/var/mail/cltbld', 09:43:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:43:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:43:44 INFO - 'MOZ_CRASHREPORTER': '1', 09:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:43:44 INFO - 'MOZ_NO_REMOTE': '1', 09:43:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:43:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:43:44 INFO - 'NO_EM_RESTART': '1', 09:43:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:44 INFO - 'PWD': '/builds/slave/test', 09:43:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:43:44 INFO - 'RUST_BACKTRACE': 'full', 09:43:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:43:44 INFO - 'SHELL': '/bin/bash', 09:43:44 INFO - 'SHLVL': '1', 09:43:44 INFO - 'TERM': 'linux', 09:43:44 INFO - 'TMOUT': '86400', 09:43:44 INFO - 'USER': 'cltbld', 09:43:44 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616', 09:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:44 INFO - '_': '/tools/buildbot/bin/python'} 09:43:44 INFO - TEST-INFO | started process 678 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzZfe8x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:43:45 INFO - PID 678 | 09:43:45 INFO - PID 678 | (/builds/slave/test/build/application/firefox/firefox:731): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:43:45 INFO - PID 678 | 09:43:56 INFO - PID 678 | RSS: Main: 163225600 09:43:56 INFO - PID 678 | 09:43:58 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49951/tests/kraken/driver.html?testName=audio-beat-detection) 09:43:59 INFO - PID 678 | RSS: Main: 168079360 09:43:59 INFO - PID 678 | 09:44:02 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49951/tests/kraken/driver.html?testName=audio-dft) 09:44:02 INFO - PID 678 | RSS: Main: 172142592 09:44:02 INFO - PID 678 | 09:44:05 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49951/tests/kraken/driver.html?testName=audio-fft) 09:44:06 INFO - PID 678 | RSS: Main: 164696064 09:44:06 INFO - PID 678 | 09:44:08 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49951/tests/kraken/driver.html?testName=audio-oscillator) 09:44:08 INFO - PID 678 | RSS: Main: 166268928 09:44:08 INFO - PID 678 | 09:44:10 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49951/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:44:11 INFO - PID 678 | RSS: Main: 166379520 09:44:11 INFO - PID 678 | 09:44:14 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49951/tests/kraken/driver.html?testName=imaging-darkroom) 09:44:15 INFO - PID 678 | RSS: Main: 166207488 09:44:15 INFO - PID 678 | 09:44:18 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49951/tests/kraken/driver.html?testName=imaging-desaturate) 09:44:19 INFO - PID 678 | RSS: Main: 167440384 09:44:19 INFO - PID 678 | 09:44:21 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49951/tests/kraken/driver.html?testName=json-parse-financial) 09:44:22 INFO - PID 678 | RSS: Main: 167391232 09:44:22 INFO - PID 678 | 09:44:24 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49951/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:44:24 INFO - PID 678 | RSS: Main: 167411712 09:44:24 INFO - PID 678 | 09:44:26 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49951/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:44:26 INFO - PID 678 | RSS: Main: 167194624 09:44:26 INFO - PID 678 | 09:44:28 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49951/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:44:29 INFO - PID 678 | RSS: Main: 164339712 09:44:29 INFO - PID 678 | 09:44:31 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49951/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:44:32 INFO - PID 678 | RSS: Main: 164290560 09:44:32 INFO - PID 678 | 09:44:35 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49951/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:44:36 INFO - PID 678 | RSS: Main: 164769792 09:44:36 INFO - PID 678 | 09:44:37 INFO - PID 678 | Cycle 1(1): loaded http://localhost:49951/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49951/tests/kraken/driver.html?testName=ai-astar) 09:44:37 INFO - PID 678 | RSS: Main: 166449152 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | __start_tp_report 09:44:37 INFO - PID 678 | _x_x_mozilla_page_load 09:44:37 INFO - PID 678 | _x_x_mozilla_page_load_details 09:44:37 INFO - PID 678 | |i|pagename|runs| 09:44:37 INFO - PID 678 | |0;ai-astar;99;115;99;105;105;106;107;97;103;108 09:44:37 INFO - PID 678 | |1;audio-beat-detection;135;111;140;181;112;147;138;112;185;113 09:44:37 INFO - PID 678 | |2;audio-dft;162;165;162;161;161;161;164;161;162;169 09:44:37 INFO - PID 678 | |3;audio-fft;73;73;73;176;72;73;73;83;92;73 09:44:37 INFO - PID 678 | |4;audio-oscillator;77;79;132;78;78;78;136;78;81;123 09:44:37 INFO - PID 678 | |5;imaging-gaussian-blur;115;115;114;114;113;113;114;115;114;113 09:44:37 INFO - PID 678 | |6;imaging-darkroom;148;149;149;150;150;151;150;150;149;149 09:44:37 INFO - PID 678 | |7;imaging-desaturate;93;93;93;95;94;93;95;94;94;94 09:44:37 INFO - PID 678 | |8;json-parse-financial;45;54;43;44;44;44;44;45;44;44 09:44:37 INFO - PID 678 | |9;json-stringify-tinderbox;39;39;39;38;39;39;39;40;38;39 09:44:37 INFO - PID 678 | |10;stanford-crypto-aes;72;73;74;73;71;71;79;71;70;71 09:44:37 INFO - PID 678 | |11;stanford-crypto-ccm;115;120;112;113;114;117;110;110;109;141 09:44:37 INFO - PID 678 | |12;stanford-crypto-pbkdf2;157;162;170;158;153;162;153;153;154;154 09:44:37 INFO - PID 678 | |13;stanford-crypto-sha256-iterative;64;58;59;59;62;67;58;60;62;65 09:44:37 INFO - PID 678 | __end_tp_report 09:44:37 INFO - PID 678 | __start_cc_report 09:44:37 INFO - PID 678 | _x_x_mozilla_cycle_collect,895 09:44:37 INFO - PID 678 | __end_cc_report 09:44:37 INFO - PID 678 | __startTimestamp1504284277963__endTimestamp 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | ------- Summary: start ------- 09:44:37 INFO - PID 678 | Number of tests: 14 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#0] ai-astar Cycles:10 Average:104.40 Median:111.50 stddev:5.27 (4.7%) stddev-sans-first:5.22 09:44:37 INFO - PID 678 | Values: 99.0 115.0 99.0 105.0 105.0 106.0 107.0 97.0 103.0 108.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:139.00 stddev:27.60 (19.9%) stddev-sans-first:29.26 09:44:37 INFO - PID 678 | Values: 135.0 111.0 140.0 181.0 112.0 147.0 138.0 112.0 185.0 113.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#2] audio-dft Cycles:10 Average:162.80 Median:162.00 stddev:2.57 (1.6%) stddev-sans-first:2.71 09:44:37 INFO - PID 678 | Values: 162.0 165.0 162.0 161.0 161.0 161.0 164.0 161.0 162.0 169.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#3] audio-fft Cycles:10 Average:86.10 Median:73.00 stddev:32.24 (44.2%) stddev-sans-first:33.85 09:44:37 INFO - PID 678 | Values: 73.0 73.0 73.0 176.0 72.0 73.0 73.0 83.0 92.0 73.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:78.00 stddev:25.29 (32.4%) stddev-sans-first:26.06 09:44:37 INFO - PID 678 | Values: 77.0 79.0 132.0 78.0 78.0 78.0 136.0 78.0 81.0 123.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 09:44:37 INFO - PID 678 | Values: 115.0 115.0 114.0 114.0 113.0 113.0 114.0 115.0 114.0 113.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#6] imaging-darkroom Cycles:10 Average:149.50 Median:150.00 stddev:0.85 (0.6%) stddev-sans-first:0.71 09:44:37 INFO - PID 678 | Values: 148.0 149.0 149.0 150.0 150.0 151.0 150.0 150.0 149.0 149.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.78 09:44:37 INFO - PID 678 | Values: 93.0 93.0 93.0 95.0 94.0 93.0 95.0 94.0 94.0 94.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#8] json-parse-financial Cycles:10 Average:45.10 Median:44.00 stddev:3.18 (7.2%) stddev-sans-first:3.37 09:44:37 INFO - PID 678 | Values: 45.0 54.0 43.0 44.0 44.0 44.0 44.0 45.0 44.0 44.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#9] json-stringify-tinderbox Cycles:10 Average:38.90 Median:39.00 stddev:0.57 (1.5%) stddev-sans-first:0.60 09:44:37 INFO - PID 678 | Values: 39.0 39.0 39.0 38.0 39.0 39.0 39.0 40.0 38.0 39.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#10] stanford-crypto-aes Cycles:10 Average:72.50 Median:72.50 stddev:2.59 (3.6%) stddev-sans-first:2.74 09:44:37 INFO - PID 678 | Values: 72.0 73.0 74.0 73.0 71.0 71.0 79.0 71.0 70.0 71.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#11] stanford-crypto-ccm Cycles:10 Average:116.10 Median:114.50 stddev:9.39 (8.2%) stddev-sans-first:9.95 09:44:37 INFO - PID 678 | Values: 115.0 120.0 112.0 113.0 114.0 117.0 110.0 110.0 109.0 141.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.60 Median:157.50 stddev:5.60 (3.6%) stddev-sans-first:5.94 09:44:37 INFO - PID 678 | Values: 157.0 162.0 170.0 158.0 153.0 162.0 153.0 153.0 154.0 154.0 09:44:37 INFO - PID 678 | 09:44:37 INFO - PID 678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:62.00 stddev:3.13 (5.1%) stddev-sans-first:3.18 09:44:37 INFO - PID 678 | Values: 64.0 58.0 59.0 59.0 62.0 67.0 58.0 60.0 62.0 65.0 09:44:37 INFO - PID 678 | -------- Summary: end -------- 09:44:37 INFO - PID 678 | 09:44:38 INFO - PID 678 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:44:38 INFO - TEST-INFO | 678: exit 0 09:44:38 INFO - TEST-OK | kraken | took 58650ms 09:44:38 INFO - SUITE-END | took 1168s 09:44:39 INFO - Completed test suite (00:19:28) 09:44:39 INFO - javascript benchmark 09:44:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9286.566400895505, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3841.807909604519, 3853.903652408691, 3856.961430385696, 3891.0, 3937.645611894929, 6503.219613646357, 6725.361090696389, 6747.22406923204, 6810.387065164135, 6819.454443644503, 6670.833229169261, 6733.629650369227, 6740.52816302859, 6764.425023872976, 6792.3207679232055, 5967.731452084645, 6058.485028772559, 6084.148219249309, 6137.0, 6158.7228574714245, 6052.546059045567, 6118.051701986177, 6154.230721159855, 6157.168782214389, 6172.969135154318, 5966.940330596725, 6054.788082417094, 6133.417325354084, 6194.628322300677, 6235.345288744685, 6029.336772954972, 6067.575269731121, 6136.2636483622255, 6141.385861413867, 6156.6613836238985, 6049.243844519435, 6052.061930400795, 6103.877922441527, 6125.540584456184, 6175.969120154416, 6683.732650694015, 6756.898646520306, 6779.966100169468, 6802.115724955724, 6803.795886123375, 7345.853082938364, 7354.852902941911, 7358.484906056578, 7365.153007404164, 7372.152202496674, 7327.047483827122, 7343.000952794264, 7454.40364770817, 7458.888116678254, 7463.477556571042, 6705.731770729214, 6856.5200435969, 6861.759838405632, 6870.175578930511, 6873.2783057778715, 7455.739049133254, 7465.328120469184, 7494.6627401767055, 7557.05536807899, 7565.130010048915, 7202.351788339075, 7202.855942881218, 7247.673854676446, 7260.346568808727, 7324.450666199949, 7177.856442871218, 7208.603526805971, 7281.126264848146, 7337.19290878003, 7413.258674132651, 7263.382612477999, 7275.89086163708, 7287.781366559012, 7409.555426674417, 7424.146223184295, 7284.645785347765, 7324.858208472099, 7381.630918454163, 7435.330820226206, 7455.030845989987, 6124.693765311717, 6163.322034576193, 6172.444479996821, 6240.812775616739, 6258.9374106259265, 5323.121684922022, 5334.59990500714, 5414.187871819258, 5440.727963601883, 5445.945540544544, 6250.3749625037135, 6361.300256971729, 6387.105805187278, 6397.976323788172, 6407.903881441782, 7267.946147808644, 7285.490015698851, 7301.926980730124, 7304.0, 7402.925970740223, 6239.095331176954, 6289.842753931189, 6344.333844946214, 6346.048092786119, 6360.7137678804565, 6556.573822701509, 6609.768658096944, 6711.295313991961, 6727.159105111861, 6731.225909020427, 7119.1457025157315, 7129.714811407589, 7139.714411423589, 7224.241454647186, 7230.602316872622, 7269.091363579552, 7339.4862359636445, 7343.926560734538, 7347.08161479815, 7361.926380736339, 7041.3310735480045, 7054.365107140176, 7071.752488662872, 7075.115610548682, 7118.110236220473, 7126.572405655677, 7228.20489746138, 7240.96379518099, 7254.238304978113, 7260.782176534713, 7284.817879553054, 7325.816854578678, 7432.92567074344, 7452.813679658052, 7500.212477689982, 7115.146182458138, 7126.643667816485, 7176.928230717626, 7178.0, 7205.71177152908, 6980.476464265099, 7049.929500704928, 7055.2944705529035, 7085.0435191248525, 7151.8569628608175, 6972.895406568804, 7036.648167591702, 7040.401565866754, 7043.225245223124, 7061.752838650623, 7170.856582868418, 7217.6030318333, 7257.564546127249, 7285.23505031964, 7295.708171673074, 6842.0, 6931.306869313028, 6938.063361446342, 6941.618210998444, 6997.0], "value": 6705.359619996028, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [851.0872708743616, 901.8241442918631, 919.246218101157, 920.8894932608084, 1255.1402289431735, 31354.372912541763, 32494.67505324946, 40919.79540102299, 41884.162316753645, 42025.0, 43522.347164792554, 43850.342244866355, 43894.12211755763, 43974.560254397446, 44058.55941440585, 7963.402744794149, 8059.838803223932, 8245.587720613976, 8250.46371985822, 8252.21603947627, 7985.9600701996405, 8052.59737013147, 8057.1539988301265, 8076.353891688651, 8091.231333023354, 8059.355251579859, 8114.553699546523, 8147.307478864274, 8148.0, 8150.307223885977, 8030.7590772276635, 8033.839323213532, 8066.959665201695, 8072.354211663052, 8082.2725954664365, 8076.6769329227345, 8082.353411727047, 8086.353091752646, 8099.190080991853, 8102.149274326229, 38609.22781544353, 38665.61334386676, 38679.806600967095, 38737.0, 38758.0, 3670.0, 3710.9443358349645, 3711.981440092782, 3726.2547490502006, 3732.309522738302, 31507.0, 31821.931574447586, 31866.522002169986, 31891.202719931956, 31925.680743192504, 5338.652987555796, 5491.203775452537, 5499.202615620753, 5508.14623733322, 5616.606837521375, 200282.0, 201469.0, 202283.0, 202642.0, 202760.0, 49954.0, 49970.50029499731, 50011.49988500141, 50139.0, 50185.0, 52172.47827521676, 55517.44482555203, 56446.43553564494, 56578.7171064142, 56611.0, 12259.938700306442, 12438.626841194779, 16077.276522556513, 16433.753493697604, 16494.092824894746, 13456.663583410493, 13464.326783660777, 13469.663258418617, 13475.0, 13495.25776082325, 14688.706225875423, 14707.41170353174, 14744.189069601274, 14786.186759728103, 14883.404663813326, 5921.61509501881, 5960.135780311917, 5993.220881285405, 6020.367861374581, 6056.212692349966, 1084.0514549768952, 1100.3287994323457, 1107.1364335818075, 1132.2017977325966, 1137.9089672826153, 25227.873860630578, 25429.872850635627, 25581.0, 25601.743982559936, 25776.742232577435, 1196.6934256468537, 1204.8855358740905, 1225.8957988571071, 1227.5764861122898, 1246.1214843535467, 13365.53220637273, 13586.184828910296, 13630.386632601556, 13658.034402454621, 13761.105528140639, 270108.0, 273525.0, 273628.0, 274818.0, 276606.0, 53914.19128713072, 54368.728156358964, 54650.72674636601, 54711.0, 54749.45250547523, 1488.5757559095823, 1499.572621802781, 1524.5883611425074, 1529.5334922848638, 1549.7210502109504, 24667.753322467262, 29332.41335173242, 30376.240593984443, 30673.53989690201, 30756.846215769674, 52240.47759522356, 52650.736746317554, 52816.47183528269, 52905.0, 52928.73535632297, 220568.0, 228046.0, 228054.0, 228558.0, 228594.0, 37148.25703485861, 37864.81067594644, 38580.614193857706, 38582.0, 38629.068546571994, 47423.762881186754, 48660.0, 49109.75445122751, 49295.75352123217, 49445.0, 36307.45538816867, 36434.542618294974, 36445.817770912035, 36722.0, 36937.07657308589, 35467.645323546436, 35531.644683553866, 35570.93287201388, 35621.28757424785, 35654.28691426209, 41361.79319103385, 41387.586124139576, 41414.1717165661, 41450.0, 41631.0, 33983.15042123967, 34223.48664769981, 34473.827630861684, 34475.310493790486, 34885.825570873, 26060.0, 26195.476090478463, 26476.602850957635, 26488.41069535834, 27032.729672703023], "value": 18563.095721082427, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.4085634793381, 599.7720866070892, 605.4944121658415, 618.2673531864741, 629.675717005742, 22637.0, 23162.768372316314, 23332.766672333273, 23347.766522334772, 23365.766342336592, 26221.737782622167, 26663.733362666368, 26794.464110717774, 26860.865695671495, 26961.460770784623, 6572.40848323652, 6683.0, 6686.565373250747, 6711.630860302681, 6717.059611654372, 6041.818745437644, 6344.365563443642, 6523.380278873499, 6718.69765860535, 6723.361280678328, 6669.099671544328, 6685.799426017227, 6691.330866913294, 6691.83270418241, 6693.364130407628, 6658.667066646648, 6663.466922646201, 6666.333366663319, 6671.966640166816, 6675.432588230006, 6220.129181914535, 6221.751129954796, 6238.095476155977, 6238.563300568917, 6250.468710159642, 30734.231644209074, 31158.0, 31344.686553134405, 32668.509972350435, 32730.672693273, 2277.271273192579, 2322.6632138340015, 2323.453988312745, 2324.418895276181, 2325.186184835311, 20531.794682053136, 20639.58720825575, 20827.0, 21382.144714211412, 21479.0, 2552.5660637691635, 2574.8068894832777, 2623.0032587616774, 2643.074923776682, 2645.550256456407, 151616.0, 152597.0, 153305.0, 154734.0, 156802.0, 16222.918885405497, 16340.18299085041, 16517.58706032359, 16680.833191667927, 17132.828671713123, 26630.6005409919, 26643.333916651853, 26766.19701408961, 26778.062767803032, 26779.866100669762, 10926.617568385069, 10952.39761813092, 10986.780264394667, 11153.888461115448, 11192.0, 10049.397036177852, 10061.597536098474, 10127.594896204217, 10159.9492002541, 10232.846507302396, 9652.855207171899, 9754.024597540189, 9840.311178217456, 9996.900030999743, 10145.543450544743, 2755.3938133610573, 2834.3197632568035, 2919.8832046717894, 2925.2101932478113, 2953.4388466191353, 1031.7575369788094, 1055.6041484443333, 1060.1095080132725, 1073.903348698621, 1079.3416016230094, 21476.57046859054, 22093.447663808212, 22244.888775556017, 22330.330090097323, 22479.550408992054, 1093.7211011192096, 1098.4617537406614, 1110.0730889707186, 1114.3982249585133, 1115.3642423818508, 11462.770744585061, 12022.458989345501, 12025.579104731467, 12107.0, 12212.267263964013, 551095.0, 552058.0, 559141.0, 563682.0, 564569.0, 131067.0, 131386.0, 131754.0, 133355.33322333326, 135141.32429337988, 1224.9632511024683, 1237.5359240284893, 1261.3504045416619, 1268.5877089945805, 1269.8857102860786, 19569.70645440348, 19711.408657740292, 23459.648105278095, 24365.147219847222, 24412.6338104925, 64574.35425645871, 64749.0, 64958.675206623666, 67028.6648566754, 67302.66348668226, 72708.27291727015, 76160.61919690366, 76592.0, 76695.23304767103, 77526.22473775415, 15861.920690396475, 15963.521094367188, 16147.67704645924, 16181.190940452694, 16303.429379972118, 25453.236402907944, 25516.872415638547, 25574.744252557237, 25765.742342576334, 25907.22278331653, 18789.718154227972, 20085.49786255355, 20160.697589535874, 20447.4888127798, 20502.667326623723, 16043.839561604234, 16884.155792210586, 18128.45614631563, 18285.360012399502, 18385.356512521998, 20720.0, 21154.0, 21162.365129046153, 21251.256206032715, 21288.467788305417, 15545.455909043128, 16069.0, 16168.272427741014, 16209.675806483569, 16212.756808648115, 20402.0, 20537.79462205359, 20599.897000514902, 20690.58618827585, 20784.0], "value": 13004.162438180647, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.6199426154938, 1448.2107251547905, 1474.7935289059526, 1477.8965472416921, 1488.5311126995002, 16261.674766504662, 16461.75307370389, 17114.229859656316, 17114.914425427854, 17139.571510712223, 16838.494845154633, 16997.2351244194, 17002.574935626617, 17009.829901700978, 17012.234449449774, 2863.2555535560696, 2920.050983430383, 2920.050983430383, 2920.2407374082786, 2922.4155168966186, 2802.0, 2874.985625071882, 2878.49626315395, 2879.55366918127, 2880.29432788967, 2819.4783964966546, 2853.443578502193, 2853.7288957549, 2854.685984541699, 2857.5713642953515, 2734.111413790516, 2799.9860000699864, 2801.845898475573, 2802.7337402946687, 2803.3832556837465, 2771.556550951838, 2804.467151241258, 2817.1689351641317, 2820.4077143799827, 2820.873060712273, 16021.439249626206, 16332.428365007168, 16345.0, 16355.754663680054, 16357.672846543002, 8261.463004904663, 8468.026176989664, 8498.490090594521, 8509.48943063412, 8543.957280213559, 5308.124159513675, 5551.02938456846, 5678.858028549319, 5722.97138514309, 5728.341240757283, 2118.4915620251027, 2167.3606286145623, 2169.370882444089, 2207.381933058746, 2220.0897631970975, 28558.714412855814, 28835.27911802201, 28864.134075977756, 28882.566761498594, 28884.0, 7165.570065796069, 7167.569945803269, 7215.891761623579, 7226.458015648847, 7278.745243916544, 18982.620347593245, 18991.43025709231, 19080.71378929317, 19092.2363105477, 19120.043997800054, 8263.421560490708, 8624.18070283322, 8626.956865215634, 8628.654853805903, 8652.610632521426, 6532.379423954717, 6797.184337879387, 6932.861342773116, 7341.265873412616, 7768.0, 5627.662340259598, 5781.768729250867, 5803.0, 5813.186153938452, 5934.762609495659, 1582.9287682054335, 1602.9759553606473, 1613.983190589921, 1631.2007116512823, 1638.4756877799227, 987.5802783816849, 1015.5379302417382, 1017.2574020964737, 1023.7184774187009, 1036.2228328753436, 8677.219050285505, 8715.084916083846, 8715.172058654418, 8726.432781869158, 8799.164079412574, 791.3352783661752, 1053.7154968158554, 1064.6965614799744, 1080.4921686807188, 1085.180688580117, 5590.944090559124, 5678.716064196774, 5716.742746576414, 5729.598928074995, 5813.273340832396, 23909.0, 25067.74932250654, 25179.24462266135, 25187.0, 25191.11831085903, 22671.546569068858, 24460.38849028753, 24516.877415613166, 24606.630900536507, 24727.87636061808, 12941.2882291473, 13138.868611313956, 13209.73580528384, 13250.0, 13355.131916425595, 9462.881114690348, 9991.100800927952, 10062.949685251528, 10070.295079344376, 10422.739431514272, 20643.793562064187, 20655.17379304811, 20751.066202020644, 21107.894460527597, 21149.365519034454, 21362.679559806926, 21619.891900540395, 21625.0, 21668.24161154352, 21856.344309670734, 13670.726585468035, 14001.649958751113, 14022.29888505591, 14028.649283767987, 14039.929800350932, 15462.0, 15906.363745450053, 15952.441664541686, 15968.600784980468, 16006.0, 11146.777064458827, 11442.656720298404, 12083.879161208628, 12140.33228172459, 12177.208481448677, 12322.507099715911, 12323.630291091282, 12363.443645035815, 12372.443240054039, 12677.239365638092, 9905.900940990497, 9968.651097211563, 10017.749556261151, 10067.899321006696, 10073.899261007296, 8335.624896879775, 8377.0, 8380.120087390736, 8388.454750441202, 8421.0, 8241.175882411952, 8242.423030387816, 8280.792980175303, 8296.336293096418, 8361.16388361159], "value": 7301.110700916023, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.2679779685309, 567.9602427830048, 588.7056471764117, 602.0487629545319, 603.1555821849411, 6566.7701630442825, 6603.834904127387, 6604.438622717069, 6662.666866656672, 6714.865702685949, 6518.445932095766, 6538.313477084897, 6542.280349161589, 6571.802845914618, 6580.769673061443, 5393.83818485446, 5405.621606487547, 5423.810166644168, 5427.755750991195, 5449.6730196188155, 5123.615728820335, 5186.5850731941355, 5391.73041347931, 5421.159720243369, 5439.782408703647, 5191.922121168185, 5204.843854684365, 5204.959008198375, 5224.190250511177, 5229.797146656252, 5102.489751024905, 5209.400918894337, 5214.843554693365, 5218.251885618303, 5236.7905283788605, 4706.0, 4729.834455794065, 4733.5503127202865, 4734.881627959294, 4737.0, 6421.93578064218, 6423.0, 6472.9029064564065, 6472.967635161794, 6539.673016349164, 6170.8765824683705, 6173.074038894157, 6173.3146081454715, 6193.721282542297, 6198.535109866778, 7055.717771289143, 7061.293870612898, 7067.681954312068, 7068.363847253772, 7080.716771329141, 5288.418273989858, 5312.73436328182, 5315.681059136464, 5322.600804939645, 5336.252924590522, 7134.821629459253, 7141.250168732312, 7284.344409003215, 7380.446466515033, 7445.0, 5383.623146379716, 5427.728613569306, 5428.565714742811, 5445.128779395278, 5452.40023597404, 5508.366537848199, 5513.173024046425, 5519.944800551943, 5521.889562208733, 5564.387917329091, 6095.299040610386, 6115.663638499926, 6117.235345581802, 6161.137440758297, 6161.815145545641, 7087.397571206505, 7101.964490177619, 7131.716291067658, 7158.427325813923, 7189.424846012306, 1899.5725961658516, 1900.790912999569, 1905.752252207232, 1921.5292253398006, 1923.6537423263946, 1287.76820172368, 1337.458329376604, 1338.4980632262902, 1341.5304643374936, 1352.573939209146, 6061.787837425757, 6072.453479186895, 6109.0, 6122.816315510542, 6133.355997620275, 7052.788416347517, 7073.257307982691, 7097.538659987187, 7140.500164988505, 7195.92804071963, 6057.303410107894, 6089.208402907594, 6136.938630613726, 6190.721417536221, 6196.783112591038, 3706.1475860551686, 3714.8699795507027, 3728.142527218755, 3785.205106927521, 3796.7911764853197, 6762.797116086525, 6863.014588653951, 7002.229754726976, 7076.964615176994, 7086.291370862976, 7196.8920466192, 7229.530080544748, 7282.635868206568, 7312.817179570553, 7340.44946629005, 6810.944303632945, 6907.689153988083, 6917.0, 6928.411085057826, 6975.3024697530855, 7062.329078737512, 7066.0, 7090.787276381717, 7096.006559081629, 7138.179109402485, 7109.28907109285, 7131.322524360177, 7165.820854478471, 7165.856682866418, 7213.6032518212005, 6913.3777959981835, 6945.0971373721095, 6969.965150174216, 6999.825004374931, 7052.153741551047, 5401.513863752124, 5417.268668729828, 5455.8363249102595, 5456.154296084113, 5460.754266058117, 6953.930460695328, 7089.255628159175, 7108.466864985044, 7210.423166146591, 7245.601491917995, 6671.366220209073, 6825.624590647562, 6857.142857142857, 6882.724691012303, 6902.585844849325, 6835.282295358916, 6871.931280687129, 6876.346747059021, 6878.38094571491, 6923.723051077901, 6874.828129296808, 6898.586084834926, 6906.136732908387, 6907.825669636274, 6917.550359226634, 6950.65246737651, 6959.826004349729, 6987.0567473392475, 6994.440444764508, 7004.509684322253, 6778.2205046420295, 6799.796006119825, 6811.625360605214, 6822.0, 6842.726290948505], "value": 5412.125027714717, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [979.3927764785832, 988.4761076629385, 1015.6648306059001, 1018.6129270877066, 1034.8240799064156, 19284.807151928493, 19834.305799297028, 19899.204031838726, 19917.30289439868, 23182.768172318272, 21863.12547498102, 22201.777982220174, 22329.441763955867, 22341.0, 22342.0, 999.9550020249088, 1019.108280254777, 1020.097213965642, 1020.5917632946844, 1021.0453226233458, 1050.0811789684026, 1086.684861390196, 1093.797647435223, 1105.9391733454656, 1110.8778034416207, 1149.373591392693, 1156.3062162702354, 1157.2304417562311, 1163.1799581295168, 1166.562539047857, 1155.3299086529835, 1155.7052951497399, 1157.1668398752884, 1157.322966064853, 1157.3692337675986, 845.7716416567492, 849.0321034021221, 849.5030407211789, 850.4302117581235, 851.6593362654926, 18663.160157792932, 19149.329773457863, 19183.808161918343, 19249.9037504813, 19362.128704208204, 22161.66757498639, 22269.66595501069, 22328.665070023962, 22370.2170424036, 22633.773662263495, 17005.0, 17173.313067477287, 17192.398266060754, 17321.307147714077, 17534.912325438418, 3971.463852379921, 3972.225416043844, 3992.8602498912687, 4025.054112283611, 4028.3353246714255, 140838.99999999898, 141647.0, 142529.0, 143198.0, 144082.9570965011, 17150.31398744061, 17515.56211094733, 17531.0, 17556.29774808993, 17584.12079396051, 33400.33199336, 33760.0, 34098.31803363968, 34176.316473670886, 34241.315173696385, 8651.91348086511, 8746.737597872074, 8750.86873696895, 8775.824483510421, 8777.912220877837, 4437.400950871591, 4503.099380123988, 4504.549545045535, 4508.616767574727, 4520.186366454004, 6202.689865506707, 6496.447801936888, 6748.4938629602975, 6769.695363708645, 6772.356626120511, 1305.3668970549277, 1326.297062556827, 1342.5569562044698, 1342.8388593368663, 1349.3657980749033, 728.3699599846095, 766.4404984361447, 769.4613770360658, 779.3570304498697, 781.1212386065677, 12637.936810316074, 12796.552120675731, 13624.455021799215, 13797.10318829293, 13823.585292441243, 755.3013462547232, 756.9318761311508, 762.6339357108619, 762.9694812207449, 782.487470706688, 6283.96858015707, 6413.6793160341795, 6519.445847102955, 6600.108985287021, 6636.270010298863, 128213.0, 129953.0, 131152.0, 133910.33044834714, 134048.0, 108896.0, 118368.40815796038, 119236.40381798036, 120260.0, 121515.0, 102664.0, 103471.4826425863, 103963.4801825986, 104161.0, 104764.47617761862, 13181.736365272833, 13230.867691322963, 13280.734385312433, 13373.39819708096, 13450.798238026633, 49126.0, 49638.25542616791, 49639.75180124076, 50519.74740126276, 50946.98106037932, 63374.36625633685, 63540.36459635344, 64054.0, 64256.35743642691, 66093.66953165203, 19250.8074919249, 19573.314933977243, 19834.404967850987, 19859.30492432758, 19960.401187964384, 21510.247141349977, 21581.568368632852, 21740.239091631716, 21821.23625673094, 22422.103115875183, 20370.898145509178, 20531.0, 20711.275105371238, 20860.58278834445, 20885.89557052205, 19440.2223911042, 19482.220711171394, 19867.403977880687, 20050.799492005477, 20207.0, 18483.16825742818, 18595.72106418432, 18602.44192674222, 18823.6235275291, 19206.61586768285, 14056.367463463965, 14302.856971430152, 14516.709665806837, 14597.489087881873, 14602.85397146015, 13212.735745285232, 13314.600561982768, 13408.530701425403, 13532.526361577298, 13607.79588306196], "value": 10027.562866870572, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1433.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 115.0, 99.0, 105.0, 105.0, 106.0, 107.0, 97.0, 103.0, 108.0], "value": 104.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 111.0, 140.0, 181.0, 112.0, 147.0, 138.0, 112.0, 185.0, 113.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 162.0, 161.0, 161.0, 161.0, 164.0, 161.0, 162.0, 169.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 176.0, 72.0, 73.0, 73.0, 83.0, 92.0, 73.0], "value": 86.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 132.0, 78.0, 78.0, 78.0, 136.0, 78.0, 81.0, 123.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 114.0, 113.0, 113.0, 114.0, 115.0, 114.0, 113.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 149.0, 150.0, 150.0, 151.0, 150.0, 150.0, 149.0, 149.0], "value": 149.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 95.0, 94.0, 93.0, 95.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 54.0, 43.0, 44.0, 44.0, 44.0, 44.0, 45.0, 44.0, 44.0], "value": 45.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 38.0, 39.0, 39.0, 39.0, 40.0, 38.0, 39.0], "value": 38.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 74.0, 73.0, 71.0, 71.0, 79.0, 71.0, 70.0, 71.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 120.0, 112.0, 113.0, 114.0, 117.0, 110.0, 110.0, 109.0, 141.0], "value": 116.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 162.0, 170.0, 158.0, 153.0, 162.0, 153.0, 153.0, 154.0, 154.0], "value": 157.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 58.0, 59.0, 59.0, 62.0, 67.0, 58.0, 60.0, 62.0, 65.0], "value": 61.4, "unit": "score"}]}]} 09:44:39 INFO - Return code: 0 09:44:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:44:39 INFO - # TBPL SUCCESS # 09:44:39 INFO - Running post-action listener: _package_coverage_data 09:44:39 INFO - Running post-action listener: _resource_record_post_action 09:44:39 INFO - [mozharness: 2017-09-01 16:44:39.274257Z] Finished run-tests step (success) 09:44:39 INFO - Running post-run listener: _resource_record_post_run 09:44:39 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 375742464; Read time: 12; Write time: 194196 09:44:39 INFO - TinderboxPrint: CPU usage
13.0% 09:44:39 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:44:39 INFO - TinderboxPrint: I/O write bytes / time
375,742,464 / 194,196 09:44:39 INFO - TinderboxPrint: CPU idle
8,215.6 (86.9%) 09:44:39 INFO - TinderboxPrint: CPU user
1,185.5 (12.5%) 09:44:39 INFO - TinderboxPrint: Swap in / out
0 / 0 09:44:39 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107995136; Read time: 0; Write time: 62144 09:44:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:44:39 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 4096; Write bytes: 267747328; Read time: 12; Write time: 132052 09:44:39 INFO - Running post-run listener: _upload_blobber_files 09:44:39 INFO - Blob upload gear active. 09:44:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:44:39 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 09:44:39 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'] 09:44:39 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 09:44:40 INFO - (blobuploader) - INFO - Open directory for files ... 09:44:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:44:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:44:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:41 INFO - (blobuploader) - INFO - Done attempting. 09:44:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:44:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:44:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:44 INFO - (blobuploader) - INFO - Done attempting. 09:44:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:44:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:44:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:45 INFO - (blobuploader) - INFO - Done attempting. 09:44:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:44:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:44:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:44:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:44:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:44:46 INFO - (blobuploader) - INFO - Done attempting. 09:44:46 INFO - (blobuploader) - INFO - Iteration through files over. 09:44:46 INFO - Return code: 0 09:44:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:44:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:44:46 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1add9b4db6dd937070246879af32154c4a49c701510cb7fe69f50a5b2576753d042d361e55e4df9b3f4157b15c88a923fd9f1b596b5278be82445e8d93d926a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7043cdd8d766a1de2d83b5aaefa5ebc3879c78e4bb9a12d76c168febd62be2acd34e8d9b1d3c852034f9b39aac9b3e6fef5058bdc7f843b62be69711e93b63d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8867d933bf56ba2d69654171862d85a510f92e0d5186d3160ab8ed9d8489796878a798b5ef04dff8a8ab049de93c1c8169e9f7d2aa35b85fd4a5b85a1c0fbff", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d69870852a6a29af1791a8b945a68fa4a84222e1f1e2f36cf1ff6b3c9b78f23a4fc0c010ad59d72eb3c92fe4c87fbeab6409923b08705110d77a59c85fecadb"} 09:44:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:44:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:44:46 INFO - Contents: 09:44:46 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1add9b4db6dd937070246879af32154c4a49c701510cb7fe69f50a5b2576753d042d361e55e4df9b3f4157b15c88a923fd9f1b596b5278be82445e8d93d926a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7043cdd8d766a1de2d83b5aaefa5ebc3879c78e4bb9a12d76c168febd62be2acd34e8d9b1d3c852034f9b39aac9b3e6fef5058bdc7f843b62be69711e93b63d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8867d933bf56ba2d69654171862d85a510f92e0d5186d3160ab8ed9d8489796878a798b5ef04dff8a8ab049de93c1c8169e9f7d2aa35b85fd4a5b85a1c0fbff", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d69870852a6a29af1791a8b945a68fa4a84222e1f1e2f36cf1ff6b3c9b78f23a4fc0c010ad59d72eb3c92fe4c87fbeab6409923b08705110d77a59c85fecadb"} 09:44:46 INFO - Running post-run listener: copy_logs_to_upload_dir 09:44:46 INFO - Copying logs to upload dir... 09:44:46 INFO - mkdir: /builds/slave/test/build/upload/logs 09:44:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.567323 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-01 09:44:46.413813) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:44:46.417208) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1add9b4db6dd937070246879af32154c4a49c701510cb7fe69f50a5b2576753d042d361e55e4df9b3f4157b15c88a923fd9f1b596b5278be82445e8d93d926a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7043cdd8d766a1de2d83b5aaefa5ebc3879c78e4bb9a12d76c168febd62be2acd34e8d9b1d3c852034f9b39aac9b3e6fef5058bdc7f843b62be69711e93b63d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8867d933bf56ba2d69654171862d85a510f92e0d5186d3160ab8ed9d8489796878a798b5ef04dff8a8ab049de93c1c8169e9f7d2aa35b85fd4a5b85a1c0fbff", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d69870852a6a29af1791a8b945a68fa4a84222e1f1e2f36cf1ff6b3c9b78f23a4fc0c010ad59d72eb3c92fe4c87fbeab6409923b08705110d77a59c85fecadb"} build_url:https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011357 build_url: 'https://queue.taskcluster.net/v1/task/CjDT4LNTRv2k0ngmn0SImw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1add9b4db6dd937070246879af32154c4a49c701510cb7fe69f50a5b2576753d042d361e55e4df9b3f4157b15c88a923fd9f1b596b5278be82445e8d93d926a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7043cdd8d766a1de2d83b5aaefa5ebc3879c78e4bb9a12d76c168febd62be2acd34e8d9b1d3c852034f9b39aac9b3e6fef5058bdc7f843b62be69711e93b63d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8867d933bf56ba2d69654171862d85a510f92e0d5186d3160ab8ed9d8489796878a798b5ef04dff8a8ab049de93c1c8169e9f7d2aa35b85fd4a5b85a1c0fbff", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d69870852a6a29af1791a8b945a68fa4a84222e1f1e2f36cf1ff6b3c9b78f23a4fc0c010ad59d72eb3c92fe4c87fbeab6409923b08705110d77a59c85fecadb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:44:46.446895) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:44:46.447234) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504283041.694113-1802642616 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:44:46.528682) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:44:46.529012) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:44:46.529312) ========= ========= Total master_lag: 0.21 =========