builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-057 starttime: 1506511170.32 results: success (0) revision: 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:30.317207) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:30.317699) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:30.318017) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:30.336493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:30.336844) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 04:19:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 109M=0s 2017-09-27 04:19:30 (109 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.191458 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:30.545908) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:30.546225) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015900 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:30.596217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:30.596568) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 --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 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 --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=934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 04:19:30,649 truncating revision to first 12 chars 2017-09-27 04:19:30,649 Setting DEBUG logging. 2017-09-27 04:19:30,649 attempt 1/10 2017-09-27 04:19:30,649 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/35fbf14b96a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 04:19:31,011 unpacking tar archive at: mozilla-central-35fbf14b96a6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.664295 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:31.278257) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:31.278602) ========= script_repo_revision: 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:31.279014) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:31.279277) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 04:19:31.294248) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-27 04:19:31.294730) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:19:31 INFO - MultiFileLogger online at 20170927 04:19:31 in /builds/slave/test 04:19:31 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 04:19:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:19:31 INFO - {'append_to_log': False, 04:19:31 INFO - 'base_work_dir': '/builds/slave/test', 04:19:31 INFO - 'blob_upload_branch': 'Firefox', 04:19:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:19:31 INFO - 'branch': 'Firefox', 04:19:31 INFO - 'buildbot_json_path': 'buildprops.json', 04:19:31 INFO - 'code_coverage': False, 04:19:31 INFO - 'config_files': ('talos/linux_config.py',), 04:19:31 INFO - 'default_actions': ('clobber', 04:19:31 INFO - 'read-buildbot-config', 04:19:31 INFO - 'download-and-extract', 04:19:31 INFO - 'populate-webroot', 04:19:31 INFO - 'create-virtualenv', 04:19:31 INFO - 'install', 04:19:31 INFO - 'setup-mitmproxy', 04:19:31 INFO - 'run-tests'), 04:19:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:19:31 INFO - 'disable_ccov_upload': False, 04:19:31 INFO - 'disable_stylo': False, 04:19:31 INFO - 'download_minidump_stackwalk': True, 04:19:31 INFO - 'download_symbols': 'ondemand', 04:19:31 INFO - 'enable_stylo': False, 04:19:31 INFO - 'enable_webrender': False, 04:19:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:19:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:19:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:19:31 INFO - 'gecko_profile': False, 04:19:31 INFO - 'gecko_profile_interval': 0, 04:19:31 INFO - 'installer_path': 'installer.exe', 04:19:31 INFO - 'log_level': 'info', 04:19:31 INFO - 'log_name': 'talos', 04:19:31 INFO - 'log_to_console': True, 04:19:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:19:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:19:31 INFO - 'opt_config_files': (), 04:19:31 INFO - 'pip_index': False, 04:19:31 INFO - 'suite': 'dromaeojs-e10s', 04:19:31 INFO - 'system_bits': '32', 04:19:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:19:31 INFO - 'title': 'talos-linux64-ix-057', 04:19:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:19:31 INFO - 'use_talos_json': True, 04:19:31 INFO - 'verify': 'False', 04:19:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:19:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:19:31 INFO - 'work_dir': 'build'} 04:19:31 INFO - [mozharness: 2017-09-27 11:19:31.485284Z] Running clobber step. 04:19:31 INFO - Running pre-action listener: _resource_record_pre_action 04:19:31 INFO - Running main action method: clobber 04:19:31 INFO - rmtree: /builds/slave/test/build 04:19:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:19:33 INFO - Running post-action listener: _resource_record_post_action 04:19:33 INFO - [mozharness: 2017-09-27 11:19:33.007142Z] Finished clobber step (success) 04:19:33 INFO - [mozharness: 2017-09-27 11:19:33.007241Z] Running read-buildbot-config step. 04:19:33 INFO - Running pre-action listener: _resource_record_pre_action 04:19:33 INFO - Running main action method: read_buildbot_config 04:19:33 INFO - Using buildbot properties: 04:19:33 INFO - { 04:19:33 INFO - "project": "", 04:19:33 INFO - "product": "firefox", 04:19:33 INFO - "who": "archaeopteryx@coole-files.de", 04:19:33 INFO - "installer_path": "public/build/target.tar.bz2", 04:19:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:19:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:19:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:19:33 INFO - "stage_platform": "linux64-pgo", 04:19:33 INFO - "basedir": "/builds/slave/test", 04:19:33 INFO - "buildnumber": 209, 04:19:33 INFO - "platform": "ubuntu64_hw", 04:19:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:19:33 INFO - "slavebuilddir": "test", 04:19:33 INFO - "taskId": "NxUMesepS623wowBTMdw-A", 04:19:33 INFO - "branch": "mozilla-central", 04:19:33 INFO - "script_repo_revision": "production", 04:19:33 INFO - "revision": "35fbf14b96a633c3f66ea13c1a163a3f3a4219b9", 04:19:33 INFO - "slavename": "talos-linux64-ix-057", 04:19:33 INFO - "repo_path": "mozilla-central" 04:19:33 INFO - } 04:19:33 INFO - Finding installer, test and symbols from parent task. 04:19:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NxUMesepS623wowBTMdw-A'}, attempt #1 04:19:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NxUMesepS623wowBTMdw-A'}, attempt #1 04:19:33 INFO - Task dependencies: NUZcoITBQvu1HCNBvdTH4w 04:19:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w'}, attempt #1 04:19:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.tar.bz2 04:19:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.test_packages.json 04:19:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.crashreporter-symbols.zip 04:19:34 INFO - Running post-action listener: _resource_record_post_action 04:19:34 INFO - [mozharness: 2017-09-27 11:19:34.487959Z] Finished read-buildbot-config step (success) 04:19:34 INFO - [mozharness: 2017-09-27 11:19:34.488176Z] Running download-and-extract step. 04:19:34 INFO - Running pre-action listener: _resource_record_pre_action 04:19:34 INFO - Running main action method: download_and_extract 04:19:34 INFO - mkdir: /builds/slave/test/build/tests 04:19:34 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')]} 04:19:34 INFO - trying https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.test_packages.json 04:19:34 INFO - Downloading https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:19:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:19:35 INFO - Downloaded 989 bytes. 04:19:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:19:35 INFO - Using the following test package requirements: 04:19:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:19:35 INFO - u'common': [u'target.common.tests.zip'], 04:19:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:19:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:19:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:19:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:19:35 INFO - u'mozbase': [u'target.common.tests.zip'], 04:19:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:19:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:19:35 INFO - u'web-platform': [u'target.common.tests.zip', 04:19:35 INFO - u'target.web-platform.tests.tar.gz'], 04:19:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:19:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:19:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.common.tests.zip 04:19:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:19:35 INFO - Fetch https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.common.tests.zip into memory 04:19:36 INFO - Content-Length response header: 55350525 04:19:36 INFO - Bytes received: 55350525 04:19:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.talos.tests.zip 04:19:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:19:46 INFO - Fetch https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.talos.tests.zip into memory 04:19:47 INFO - Content-Length response header: 14513665 04:19:47 INFO - Bytes received: 14513665 04:19: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')]} 04:19:47 INFO - trying https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.tar.bz2 04:19:47 INFO - Downloading https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:19:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:19:49 INFO - Downloaded 62320910 bytes. 04:19:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.tar.bz2 04:19:49 INFO - mkdir: /builds/slave/test/properties 04:19:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:19:49 INFO - Writing to file /builds/slave/test/properties/build_url 04:19:49 INFO - Contents: 04:19:49 INFO - build_url:https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.tar.bz2 04:19:49 INFO - Running post-action listener: _resource_record_post_action 04:19:49 INFO - Running post-action listener: find_tests_for_verification 04:19:49 INFO - Running post-action listener: set_extra_try_arguments 04:19:49 INFO - [mozharness: 2017-09-27 11:19:49.040766Z] Finished download-and-extract step (success) 04:19:49 INFO - [mozharness: 2017-09-27 11:19:49.040916Z] Running populate-webroot step. 04:19:49 INFO - Running pre-action listener: _resource_record_pre_action 04:19:49 INFO - Running main action method: populate_webroot 04:19:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:19:49 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['tresize', 04:19:49 INFO - 'tcanvasmark']}, 04:19:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:19:49 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['dromaeo_css', 04:19:49 INFO - 'kraken']}, 04:19:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:19:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:19:49 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:19:49 INFO - 'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:19:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:19:49 INFO - 'tests': ['damp', 'tps']}, 04:19:49 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:19:49 INFO - 'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['damp', 'tps']}, 04:19:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:19:49 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['dromaeo_dom']}, 04:19:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:19:49 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['basic_compositor_video', 04:19:49 INFO - 'glvideo']}, 04:19:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:19:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:19:49 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:19:49 INFO - 'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['ts_paint_webext', 04:19:49 INFO - 'tp5o_webext']}, 04:19:49 INFO - 'other-e10s': {'tests': ['a11yr', 04:19:49 INFO - 'ts_paint', 04:19:49 INFO - 'tpaint', 04:19:49 INFO - 'sessionrestore', 04:19:49 INFO - 'sessionrestore_many_windows', 04:19:49 INFO - 'sessionrestore_no_auto_restore', 04:19:49 INFO - 'tabpaint']}, 04:19:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['a11yr', 04:19:49 INFO - 'ts_paint', 04:19:49 INFO - 'tpaint', 04:19:49 INFO - 'sessionrestore', 04:19:49 INFO - 'sessionrestore_many_windows', 04:19:49 INFO - 'sessionrestore_no_auto_restore', 04:19:49 INFO - 'tabpaint']}, 04:19:49 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 04:19:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:19:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['perf_reftest_singletons']}, 04:19:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['perf_reftest']}, 04:19:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:19:49 INFO - 'tsvgr_opacity', 04:19:49 INFO - 'tart', 04:19:49 INFO - 'tscrollx', 04:19:49 INFO - 'tsvg_static']}, 04:19:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['tsvgx', 04:19:49 INFO - 'tsvgr_opacity', 04:19:49 INFO - 'tart', 04:19:49 INFO - 'tscrollx', 04:19:49 INFO - 'tsvg_static']}, 04:19:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:19:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:19:49 INFO - 'talos_options': ['--disable-stylo'], 04:19:49 INFO - 'tests': ['tp5o']}, 04:19:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:19:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:19:49 INFO - 'talos_options': ['--mitmproxy', 04:19:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:19:49 INFO - '--firstNonBlankPaint'], 04:19:49 INFO - 'tests': ['tp6_google', 04:19:49 INFO - 'tp6_youtube', 04:19:49 INFO - 'tp6_amazon', 04:19:49 INFO - 'tp6_facebook']}, 04:19:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:19:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:19:49 INFO - 'talos_options': ['--disable-stylo', 04:19:49 INFO - '--mitmproxy', 04:19:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:19:49 INFO - '--firstNonBlankPaint'], 04:19:49 INFO - 'tests': ['tp6_google', 04:19:49 INFO - 'tp6_youtube', 04:19:49 INFO - 'tp6_amazon', 04:19:49 INFO - 'tp6_facebook']}, 04:19:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:19:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:19:49 INFO - 'talos_options': ['--stylo-threads=1', 04:19:49 INFO - '--mitmproxy', 04:19:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:19:49 INFO - '--firstNonBlankPaint'], 04:19:49 INFO - 'tests': ['tp6_google', 04:19:49 INFO - 'tp6_youtube', 04:19:49 INFO - 'tp6_amazon', 04:19:49 INFO - 'tp6_facebook']}, 04:19:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:19:49 INFO - 'talos_options': ['--xperf_path', 04:19:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:19:49 INFO - 'tests': ['tp5n']}, 04:19:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:19:49 INFO - 'talos_options': ['--disable-stylo', 04:19:49 INFO - '--xperf_path', 04:19:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:19:49 INFO - 'tests': ['tp5n']}}} 04:19:49 INFO - Running post-action listener: _resource_record_post_action 04:19:49 INFO - [mozharness: 2017-09-27 11:19:49.061961Z] Finished populate-webroot step (success) 04:19:49 INFO - [mozharness: 2017-09-27 11:19:49.062069Z] Running create-virtualenv step. 04:19:49 INFO - Running pre-action listener: _resource_record_pre_action 04:19:49 INFO - Running main action method: create_virtualenv 04:19:49 INFO - Creating virtualenv /builds/slave/test/build/venv 04:19:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:19:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:19:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:19:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:19:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:19:49 INFO - Installing distribute.............................................................................................................................................................................................done. 04:19:50 INFO - Installing pip...............done. 04:19:50 INFO - Return code: 0 04:19:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:19:50 INFO - Reading from file tmpfile_stdout 04:19:50 INFO - Output received: 04:19:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:19:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:19: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')]} 04:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19: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')]} 04:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ac9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9d60>, '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 0x19aaa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aaef0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', '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 04:19:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:19:50 INFO - Using env: {'DISPLAY': ':0', 04:19:50 INFO - 'HOME': '/home/cltbld', 04:19:50 INFO - 'LANG': 'en_US.UTF-8', 04:19:50 INFO - 'LANGUAGE': 'en_US:en', 04:19:50 INFO - 'LOGNAME': 'cltbld', 04:19:50 INFO - 'MAIL': '/var/mail/cltbld', 04:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:50 INFO - 'MOZ_NO_REMOTE': '1', 04:19:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:19:50 INFO - 'NO_EM_RESTART': '1', 04:19:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:50 INFO - 'PWD': '/builds/slave/test', 04:19:50 INFO - 'SHELL': '/bin/bash', 04:19:50 INFO - 'SHLVL': '1', 04:19:50 INFO - 'TERM': 'linux', 04:19:50 INFO - 'TMOUT': '86400', 04:19:50 INFO - 'USER': 'cltbld', 04:19:50 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:50 INFO - '_': '/tools/buildbot/bin/python'} 04:19:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:19:51 INFO - Downloading/unpacking pip>=1.5 04:19:51 INFO - Running setup.py egg_info for package pip 04:19:51 INFO - warning: no files found matching 'pip/cacert.pem' 04:19:51 INFO - warning: no files found matching '*.html' under directory 'docs' 04:19:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:19:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:19:51 INFO - Installing collected packages: pip 04:19:51 INFO - Found existing installation: pip 1.1 04:19:51 INFO - Uninstalling pip: 04:19:51 INFO - Successfully uninstalled pip 04:19:51 INFO - Running setup.py install for pip 04:19:51 INFO - warning: no files found matching 'pip/cacert.pem' 04:19:51 INFO - warning: no files found matching '*.html' under directory 'docs' 04:19:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:19:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:19:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:19:51 INFO - Successfully installed pip 04:19:51 INFO - Cleaning up... 04:19:51 INFO - Return code: 0 04:19:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x163f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ac9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9d60>, '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 0x19aaa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aaef0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', '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 04:19:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:19:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:19:51 INFO - Using env: {'DISPLAY': ':0', 04:19:51 INFO - 'HOME': '/home/cltbld', 04:19:51 INFO - 'LANG': 'en_US.UTF-8', 04:19:51 INFO - 'LANGUAGE': 'en_US:en', 04:19:51 INFO - 'LOGNAME': 'cltbld', 04:19:51 INFO - 'MAIL': '/var/mail/cltbld', 04:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:51 INFO - 'MOZ_NO_REMOTE': '1', 04:19:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:19:51 INFO - 'NO_EM_RESTART': '1', 04:19:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:51 INFO - 'PWD': '/builds/slave/test', 04:19:51 INFO - 'SHELL': '/bin/bash', 04:19:51 INFO - 'SHLVL': '1', 04:19:51 INFO - 'TERM': 'linux', 04:19:51 INFO - 'TMOUT': '86400', 04:19:51 INFO - 'USER': 'cltbld', 04:19:51 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:51 INFO - '_': '/tools/buildbot/bin/python'} 04:19:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:51 INFO - Downloading/unpacking psutil>=3.1.1 04:19: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 04:19: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 04:19: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 04:19: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 04:19:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:19:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:19:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:19:53 INFO - Installing collected packages: psutil 04:19:53 INFO - Running setup.py install for psutil 04:19:53 INFO - building 'psutil._psutil_linux' extension 04:19:53 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 04:19:53 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 04:19:53 INFO - building 'psutil._psutil_posix' extension 04:19:53 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 04:19:53 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 04:19:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:19:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:19:53 INFO - Successfully installed psutil 04:19:53 INFO - Cleaning up... 04:19:53 INFO - Return code: 0 04:19:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:19: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')]} 04:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19: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')]} 04:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ac9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9d60>, '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 0x19aaa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aaef0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', '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 04:19: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:19: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 mozsystemmonitor==0.3 04:19:53 INFO - Using env: {'DISPLAY': ':0', 04:19:53 INFO - 'HOME': '/home/cltbld', 04:19:53 INFO - 'LANG': 'en_US.UTF-8', 04:19:53 INFO - 'LANGUAGE': 'en_US:en', 04:19:53 INFO - 'LOGNAME': 'cltbld', 04:19:53 INFO - 'MAIL': '/var/mail/cltbld', 04:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:53 INFO - 'MOZ_NO_REMOTE': '1', 04:19:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:19:53 INFO - 'NO_EM_RESTART': '1', 04:19:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:53 INFO - 'PWD': '/builds/slave/test', 04:19:53 INFO - 'SHELL': '/bin/bash', 04:19:53 INFO - 'SHLVL': '1', 04:19:53 INFO - 'TERM': 'linux', 04:19:53 INFO - 'TMOUT': '86400', 04:19:53 INFO - 'USER': 'cltbld', 04:19:53 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:53 INFO - '_': '/tools/buildbot/bin/python'} 04:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:19:53 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 04:19:53 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 04:19:53 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 04:19:53 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 04:19:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:19:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:19:54 INFO - Installing collected packages: mozsystemmonitor 04:19:54 INFO - Running setup.py install for mozsystemmonitor 04:19:55 INFO - Successfully installed mozsystemmonitor 04:19:55 INFO - Cleaning up... 04:19:55 INFO - Return code: 0 04:19:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:19:55 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')]} 04:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x163f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ac9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9d60>, '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 0x19aaa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aaef0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', '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 04:19:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:19:56 INFO - Using env: {'DISPLAY': ':0', 04:19:56 INFO - 'HOME': '/home/cltbld', 04:19:56 INFO - 'LANG': 'en_US.UTF-8', 04:19:56 INFO - 'LANGUAGE': 'en_US:en', 04:19:56 INFO - 'LOGNAME': 'cltbld', 04:19:56 INFO - 'MAIL': '/var/mail/cltbld', 04:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:56 INFO - 'MOZ_NO_REMOTE': '1', 04:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:19:56 INFO - 'NO_EM_RESTART': '1', 04:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:56 INFO - 'PWD': '/builds/slave/test', 04:19:56 INFO - 'SHELL': '/bin/bash', 04:19:56 INFO - 'SHLVL': '1', 04:19:56 INFO - 'TERM': 'linux', 04:19:56 INFO - 'TMOUT': '86400', 04:19:56 INFO - 'USER': 'cltbld', 04:19:56 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:56 INFO - '_': '/tools/buildbot/bin/python'} 04:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:56 INFO - Downloading/unpacking jsonschema==2.5.1 04:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:19:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:57 INFO - Downloading functools32-3.2.3-2.tar.gz 04:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:19:57 INFO - warning: no files found matching '*.txt' 04:19:57 INFO - no previously-included directories found matching 'build' 04:19:57 INFO - no previously-included directories found matching 'dist' 04:19:57 INFO - no previously-included directories found matching '.git*' 04:19:57 INFO - Installing collected packages: jsonschema, functools32 04:19:57 INFO - Running setup.py install for functools32 04:19:57 INFO - warning: no files found matching '*.txt' 04:19:57 INFO - no previously-included directories found matching 'build' 04:19:57 INFO - no previously-included directories found matching 'dist' 04:19:57 INFO - no previously-included directories found matching '.git*' 04:19:57 INFO - Successfully installed jsonschema functools32 04:19:57 INFO - Cleaning up... 04:19:57 INFO - Return code: 0 04:19:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:19:57 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')]} 04:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:57 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')]} 04:19:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:57 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 0x163f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ac9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9d60>, '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 0x19aaa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aaef0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', '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 04:19:57 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 04:19:57 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 04:19:57 INFO - Using env: {'DISPLAY': ':0', 04:19:57 INFO - 'HOME': '/home/cltbld', 04:19:57 INFO - 'LANG': 'en_US.UTF-8', 04:19:57 INFO - 'LANGUAGE': 'en_US:en', 04:19:57 INFO - 'LOGNAME': 'cltbld', 04:19:57 INFO - 'MAIL': '/var/mail/cltbld', 04:19:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:57 INFO - 'MOZ_NO_REMOTE': '1', 04:19:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:19:57 INFO - 'NO_EM_RESTART': '1', 04:19:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:19:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:57 INFO - 'PWD': '/builds/slave/test', 04:19:57 INFO - 'SHELL': '/bin/bash', 04:19:57 INFO - 'SHLVL': '1', 04:19:57 INFO - 'TERM': 'linux', 04:19:57 INFO - 'TMOUT': '86400', 04:19:57 INFO - 'USER': 'cltbld', 04:19:57 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:19:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:57 INFO - '_': '/tools/buildbot/bin/python'} 04:19:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:19:58 INFO - Cleaning up... 04:19:58 INFO - Return code: 0 04:19:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:19:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:58 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 0x163f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ac9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9d60>, '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 0x19aaa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aaef0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', '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 04:19:58 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 04:19:58 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 04:19:58 INFO - Using env: {'DISPLAY': ':0', 04:19:58 INFO - 'HOME': '/home/cltbld', 04:19:58 INFO - 'LANG': 'en_US.UTF-8', 04:19:58 INFO - 'LANGUAGE': 'en_US:en', 04:19:58 INFO - 'LOGNAME': 'cltbld', 04:19:58 INFO - 'MAIL': '/var/mail/cltbld', 04:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:58 INFO - 'MOZ_NO_REMOTE': '1', 04:19:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:19:58 INFO - 'NO_EM_RESTART': '1', 04:19:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:19:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:58 INFO - 'PWD': '/builds/slave/test', 04:19:58 INFO - 'SHELL': '/bin/bash', 04:19:58 INFO - 'SHLVL': '1', 04:19:58 INFO - 'TERM': 'linux', 04:19:58 INFO - 'TMOUT': '86400', 04:19:58 INFO - 'USER': 'cltbld', 04:19:58 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:58 INFO - '_': '/tools/buildbot/bin/python'} 04:19:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:58 INFO - Downloading/unpacking blobuploader==1.2.4 04:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:59 INFO - Downloading blobuploader-1.2.4.tar.gz 04:19:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:19:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:19:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:59 INFO - Downloading docopt-0.6.1.tar.gz 04:19:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:20:00 INFO - Installing collected packages: blobuploader, requests, docopt 04:20:00 INFO - Running setup.py install for blobuploader 04:20:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:20:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:20:00 INFO - Running setup.py install for requests 04:20:00 INFO - Running setup.py install for docopt 04:20:00 INFO - Successfully installed blobuploader requests docopt 04:20:00 INFO - Cleaning up... 04:20:00 INFO - Return code: 0 04:20:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20:00 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')]} 04:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:00 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')]} 04:20:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:00 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 0x163f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ac9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9d60>, '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 0x19aaa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aaef0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', '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 04:20:00 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 04:20:00 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 04:20:00 INFO - Using env: {'DISPLAY': ':0', 04:20:00 INFO - 'HOME': '/home/cltbld', 04:20:00 INFO - 'LANG': 'en_US.UTF-8', 04:20:00 INFO - 'LANGUAGE': 'en_US:en', 04:20:00 INFO - 'LOGNAME': 'cltbld', 04:20:00 INFO - 'MAIL': '/var/mail/cltbld', 04:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:00 INFO - 'MOZ_NO_REMOTE': '1', 04:20:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:00 INFO - 'NO_EM_RESTART': '1', 04:20:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:00 INFO - 'PWD': '/builds/slave/test', 04:20:00 INFO - 'SHELL': '/bin/bash', 04:20:00 INFO - 'SHLVL': '1', 04:20:00 INFO - 'TERM': 'linux', 04:20:00 INFO - 'TMOUT': '86400', 04:20:00 INFO - 'USER': 'cltbld', 04:20:00 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:00 INFO - '_': '/tools/buildbot/bin/python'} 04:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:20:00 INFO - Running setup.py (path:/tmp/pip-7Z0WxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:20:00 INFO - Running setup.py (path:/tmp/pip-ImCWat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:20:01 INFO - Running setup.py (path:/tmp/pip-xbgHpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:20:01 INFO - Running setup.py (path:/tmp/pip-SSKM8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:20:01 INFO - Running setup.py (path:/tmp/pip-t5Jvpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:20:01 INFO - Running setup.py (path:/tmp/pip-NZC2AX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:20:01 INFO - Running setup.py (path:/tmp/pip-9t8rko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:20:01 INFO - Running setup.py (path:/tmp/pip-hoAHeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:20:01 INFO - Running setup.py (path:/tmp/pip-5aeE7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:20:01 INFO - Running setup.py (path:/tmp/pip-7mmwPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:20:01 INFO - Running setup.py (path:/tmp/pip-eoczsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:20:01 INFO - Running setup.py (path:/tmp/pip-WqC_eB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:20:01 INFO - Running setup.py (path:/tmp/pip-Z3lrXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:20:01 INFO - Running setup.py (path:/tmp/pip-ARPoeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:01 INFO - Running setup.py (path:/tmp/pip-Z0H11k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:20:01 INFO - Running setup.py (path:/tmp/pip-8QtAMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:20:01 INFO - Running setup.py (path:/tmp/pip-JT42aM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:20:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:20:02 INFO - Running setup.py install for manifestparser 04:20:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:20:02 INFO - Running setup.py install for mozcrash 04:20:02 INFO - Running setup.py install for mozdebug 04:20:02 INFO - Running setup.py install for mozdevice 04:20:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:20:02 INFO - Running setup.py install for mozfile 04:20:02 INFO - Running setup.py install for mozhttpd 04:20:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:20:02 INFO - Running setup.py install for mozinfo 04:20:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:20:02 INFO - Running setup.py install for mozInstall 04:20:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:20:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:20:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:20:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:20:02 INFO - Running setup.py install for mozleak 04:20:02 INFO - Running setup.py install for mozlog 04:20:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:20:03 INFO - Running setup.py install for moznetwork 04:20:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:20:03 INFO - Running setup.py install for mozprocess 04:20:03 INFO - Running setup.py install for mozprofile 04:20:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:20:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:20:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:20:03 INFO - Running setup.py install for mozrunner 04:20:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:20:03 INFO - Running setup.py install for mozscreenshot 04:20:03 INFO - Running setup.py install for moztest 04:20:03 INFO - Running setup.py install for mozversion 04:20:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:20:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:20:03 INFO - Cleaning up... 04:20:03 INFO - Return code: 0 04:20:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20:03 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')]} 04:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:03 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')]} 04:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:03 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 0x163f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ac9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9d60>, '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 0x19aaa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aaef0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', '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 04:20:03 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 04:20:03 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 04:20:03 INFO - Using env: {'DISPLAY': ':0', 04:20:03 INFO - 'HOME': '/home/cltbld', 04:20:03 INFO - 'LANG': 'en_US.UTF-8', 04:20:03 INFO - 'LANGUAGE': 'en_US:en', 04:20:03 INFO - 'LOGNAME': 'cltbld', 04:20:03 INFO - 'MAIL': '/var/mail/cltbld', 04:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:03 INFO - 'MOZ_NO_REMOTE': '1', 04:20:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:03 INFO - 'NO_EM_RESTART': '1', 04:20:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:03 INFO - 'PWD': '/builds/slave/test', 04:20:03 INFO - 'SHELL': '/bin/bash', 04:20:03 INFO - 'SHLVL': '1', 04:20:03 INFO - 'TERM': 'linux', 04:20:03 INFO - 'TMOUT': '86400', 04:20:03 INFO - 'USER': 'cltbld', 04:20:03 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:03 INFO - '_': '/tools/buildbot/bin/python'} 04:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:20:04 INFO - Running setup.py (path:/tmp/pip-UqADEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:20:04 INFO - Running setup.py (path:/tmp/pip-yXqZBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:20:04 INFO - Running setup.py (path:/tmp/pip-AFqJMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:20:04 INFO - Running setup.py (path:/tmp/pip-eKH8eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:20:04 INFO - Running setup.py (path:/tmp/pip-IO4CwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:20:04 INFO - Running setup.py (path:/tmp/pip-nFJUuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:20:04 INFO - Running setup.py (path:/tmp/pip-23Xgqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:20:04 INFO - Running setup.py (path:/tmp/pip-zx5BTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:20:04 INFO - Running setup.py (path:/tmp/pip-d8PPFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:20:04 INFO - Running setup.py (path:/tmp/pip-dZmPXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:20:04 INFO - Running setup.py (path:/tmp/pip-Ts6BMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:20:04 INFO - Running setup.py (path:/tmp/pip-X8Iegz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:20:04 INFO - Running setup.py (path:/tmp/pip-0JFaxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:20:04 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)) 04:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:20:04 INFO - Running setup.py (path:/tmp/pip-aIazCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:20:05 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)) 04:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:05 INFO - Running setup.py (path:/tmp/pip-18XB4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:20:05 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)) 04:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:20:05 INFO - Running setup.py (path:/tmp/pip-MhNYpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:20:05 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)) 04:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:20:05 INFO - Running setup.py (path:/tmp/pip-oMRWqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:20:05 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)) 04:20:05 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)) 04:20:05 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)) 04:20:05 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)) 04:20:05 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)) 04:20:05 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)) 04:20:05 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)) 04:20:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:20:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:20:06 INFO - Downloading blessings-1.6.tar.gz 04:20:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:20:06 INFO - Installing collected packages: blessings 04:20:06 INFO - Running setup.py install for blessings 04:20:06 INFO - Successfully installed blessings 04:20:06 INFO - Cleaning up... 04:20:06 INFO - Return code: 0 04:20:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:20:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:20:06 INFO - Reading from file tmpfile_stdout 04:20:06 INFO - Current package versions: 04:20:06 INFO - argparse == 1.2.1 04:20:06 INFO - blessings == 1.6 04:20:06 INFO - blobuploader == 1.2.4 04:20:06 INFO - docopt == 0.6.1 04:20:06 INFO - functools32 == 3.2.3-2 04:20:06 INFO - jsonschema == 2.5.1 04:20:06 INFO - manifestparser == 1.1 04:20:06 INFO - mozInstall == 1.13 04:20:06 INFO - mozcrash == 1.0 04:20:06 INFO - mozdebug == 0.1 04:20:06 INFO - mozdevice == 0.51 04:20:06 INFO - mozfile == 1.2 04:20:06 INFO - mozhttpd == 0.7 04:20:06 INFO - mozinfo == 0.10 04:20:06 INFO - mozleak == 0.1 04:20:06 INFO - mozlog == 3.5 04:20:06 INFO - moznetwork == 0.27 04:20:06 INFO - mozprocess == 0.25 04:20:06 INFO - mozprofile == 0.28 04:20:06 INFO - mozrunner == 6.13 04:20:06 INFO - mozscreenshot == 0.1 04:20:06 INFO - mozsystemmonitor == 0.3 04:20:06 INFO - moztest == 0.8 04:20:06 INFO - mozversion == 1.4 04:20:06 INFO - psutil == 3.1.1 04:20:06 INFO - requests == 1.2.3 04:20:06 INFO - wsgiref == 0.1.2 04:20:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:20: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')]} 04:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20: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')]} 04:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:06 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 0x163f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ac9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9d60>, '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 0x19aaa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aaef0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', '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 04:20:06 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 04:20:06 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 04:20:06 INFO - Using env: {'DISPLAY': ':0', 04:20:06 INFO - 'HOME': '/home/cltbld', 04:20:06 INFO - 'LANG': 'en_US.UTF-8', 04:20:06 INFO - 'LANGUAGE': 'en_US:en', 04:20:06 INFO - 'LOGNAME': 'cltbld', 04:20:06 INFO - 'MAIL': '/var/mail/cltbld', 04:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:06 INFO - 'MOZ_NO_REMOTE': '1', 04:20:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:06 INFO - 'NO_EM_RESTART': '1', 04:20:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:06 INFO - 'PWD': '/builds/slave/test', 04:20:06 INFO - 'SHELL': '/bin/bash', 04:20:06 INFO - 'SHLVL': '1', 04:20:06 INFO - 'TERM': 'linux', 04:20:06 INFO - 'TMOUT': '86400', 04:20:06 INFO - 'USER': 'cltbld', 04:20:06 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:06 INFO - '_': '/tools/buildbot/bin/python'} 04:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:07 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)) 04:20:07 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)) 04:20:07 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)) 04:20:07 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)) 04:20:07 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)) 04:20:07 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)) 04:20:07 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)) 04:20:07 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)) 04:20:07 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)) 04:20:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:20:07 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 04:20:07 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 04:20:07 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 04:20:07 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 04:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:20:08 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)) 04:20:08 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)) 04:20:08 INFO - Installing collected packages: simplejson 04:20:08 INFO - Running setup.py install for simplejson 04:20:08 INFO - building 'simplejson._speedups' extension 04:20:08 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 04:20:09 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 04:20:09 INFO - Successfully installed simplejson 04:20:09 INFO - Cleaning up... 04:20:09 INFO - Return code: 0 04:20:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:20:09 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')]} 04:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:20:09 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')]} 04:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:20:09 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 0x163f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ac9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a9d60>, '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 0x19aaa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aaef0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', '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 04:20:09 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 04:20:09 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 04:20:09 INFO - Using env: {'DISPLAY': ':0', 04:20:09 INFO - 'HOME': '/home/cltbld', 04:20:09 INFO - 'LANG': 'en_US.UTF-8', 04:20:09 INFO - 'LANGUAGE': 'en_US:en', 04:20:09 INFO - 'LOGNAME': 'cltbld', 04:20:09 INFO - 'MAIL': '/var/mail/cltbld', 04:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:09 INFO - 'MOZ_NO_REMOTE': '1', 04:20:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:09 INFO - 'NO_EM_RESTART': '1', 04:20:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:09 INFO - 'PWD': '/builds/slave/test', 04:20:09 INFO - 'SHELL': '/bin/bash', 04:20:09 INFO - 'SHLVL': '1', 04:20:09 INFO - 'TERM': 'linux', 04:20:09 INFO - 'TMOUT': '86400', 04:20:09 INFO - 'USER': 'cltbld', 04:20:09 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:09 INFO - '_': '/tools/buildbot/bin/python'} 04:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:20:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:20:09 INFO - Cleaning up... 04:20:09 INFO - Return code: 0 04:20:09 INFO - Running post-action listener: _resource_record_post_action 04:20:09 INFO - Running post-action listener: _start_resource_monitoring 04:20:09 INFO - Starting resource monitoring. 04:20:09 INFO - [mozharness: 2017-09-27 11:20:09.523794Z] Finished create-virtualenv step (success) 04:20:09 INFO - [mozharness: 2017-09-27 11:20:09.524267Z] Running install step. 04:20:09 INFO - Running pre-action listener: _resource_record_pre_action 04:20:09 INFO - Running main action method: install 04:20:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:20:09 INFO - Reading from file tmpfile_stdout 04:20:09 INFO - Detecting whether we're running mozinstall >=1.0... 04:20:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:20:09 INFO - Reading from file tmpfile_stdout 04:20:09 INFO - Output received: 04:20:09 INFO - Usage: mozinstall [options] installer 04:20:09 INFO - Options: 04:20:09 INFO - -h, --help show this help message and exit 04:20:09 INFO - -d DEST, --destination=DEST 04:20:09 INFO - Directory to install application into. [default: 04:20:09 INFO - "/builds/slave/test"] 04:20:09 INFO - --app=APP Application being installed. [default: firefox] 04:20:09 INFO - mkdir: /builds/slave/test/build/application 04:20:09 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'] 04:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:20:27 INFO - Reading from file tmpfile_stdout 04:20:27 INFO - Output received: 04:20:27 INFO - /builds/slave/test/build/application/firefox/firefox 04:20:27 INFO - Running post-action listener: _resource_record_post_action 04:20:27 INFO - [mozharness: 2017-09-27 11:20:27.509843Z] Finished install step (success) 04:20:27 INFO - [mozharness: 2017-09-27 11:20:27.510097Z] Running setup-mitmproxy step. 04:20:27 INFO - Running pre-action listener: _resource_record_pre_action 04:20:27 INFO - Running main action method: setup_mitmproxy 04:20:27 INFO - Skipping: mitmproxy is not required 04:20:27 INFO - Running post-action listener: _resource_record_post_action 04:20:27 INFO - [mozharness: 2017-09-27 11:20:27.511229Z] Finished setup-mitmproxy step (success) 04:20:27 INFO - [mozharness: 2017-09-27 11:20:27.511540Z] Running run-tests step. 04:20:27 INFO - Running pre-action listener: _resource_record_pre_action 04:20:27 INFO - Running pre-action listener: _set_gcov_prefix 04:20:27 INFO - Running main action method: run_tests 04:20:27 WARNING - Try message not found. 04:20:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:20:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:20:27 INFO - Python 2.7.3 04:20:27 INFO - Return code: 0 04:20:27 INFO - grabbing minidump binary from tooltool 04:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:27 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 0x19a9d60>, '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 0x19aaa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19aaef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:20:27 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 04:20:27 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 04:20:27 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 04:20:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:20:27 INFO - Return code: 0 04:20:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:20:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:20:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:20:27 INFO - ENV: RUST_BACKTRACE is now full 04:20:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:20:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:20:27 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/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 04:20:27 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/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --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 04:20:27 INFO - Using env: {'DISPLAY': ':0', 04:20:27 INFO - 'HOME': '/home/cltbld', 04:20:27 INFO - 'LANG': 'en_US.UTF-8', 04:20:27 INFO - 'LANGUAGE': 'en_US:en', 04:20:27 INFO - 'LOGNAME': 'cltbld', 04:20:27 INFO - 'MAIL': '/var/mail/cltbld', 04:20:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:20:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:27 INFO - 'MOZ_NO_REMOTE': '1', 04:20:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:20:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:27 INFO - 'NO_EM_RESTART': '1', 04:20:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:27 INFO - 'PWD': '/builds/slave/test', 04:20:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:20:27 INFO - 'RUST_BACKTRACE': 'full', 04:20:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:20:27 INFO - 'SHELL': '/bin/bash', 04:20:27 INFO - 'SHLVL': '1', 04:20:27 INFO - 'TERM': 'linux', 04:20:27 INFO - 'TMOUT': '86400', 04:20:27 INFO - 'USER': 'cltbld', 04:20:27 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:27 INFO - '_': '/tools/buildbot/bin/python'} 04:20:27 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/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 04:20:27 INFO - mozversion application_buildid: 20170927094813 04:20:27 INFO - mozversion application_changeset: 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 04:20:27 INFO - mozversion application_display_name: Nightly 04:20:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:20:27 INFO - mozversion application_name: Firefox 04:20:27 INFO - mozversion application_remotingname: firefox 04:20:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:20:27 INFO - mozversion application_vendor: Mozilla 04:20:27 INFO - mozversion application_version: 58.0a1 04:20:27 INFO - mozversion platform_buildid: 20170927094813 04:20:27 INFO - mozversion platform_changeset: 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 04:20:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:20:27 INFO - mozversion platform_version: 58.0a1 04:20:27 INFO - using testdate: 1506511227 04:20:27 INFO - actual date: 1506511227 04:20:27 INFO - starting webserver on 'localhost:42653' 04:20:27 INFO - SUITE-START | Running 2 tests 04:20:27 INFO - TEST-START | dromaeo_css 04:20:27 INFO - Initialising browser for dromaeo_css test... 04:20:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42653/getInfo.html -profile /tmp/tmpyH5h3_/profile 04:20:27 INFO - TEST-INFO | started process 14652 (/builds/slave/test/build/application/firefox/firefox http://localhost:42653/getInfo.html) 04:20:33 INFO - TEST-INFO | 14652: exit 0 04:20:33 INFO - Browser initialized. 04:20:33 INFO - Running cycle 1/1 for dromaeo_css test... 04:20:33 INFO - Using env: {'DISPLAY': ':0', 04:20:33 INFO - 'HOME': '/home/cltbld', 04:20:33 INFO - 'JSGC_DISABLE_POISONING': '1', 04:20:33 INFO - 'LANG': 'en_US.UTF-8', 04:20:33 INFO - 'LANGUAGE': 'en_US:en', 04:20:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:20:33 INFO - 'LOGNAME': 'cltbld', 04:20:33 INFO - 'MAIL': '/var/mail/cltbld', 04:20:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:20:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:20:33 INFO - 'MOZ_CRASHREPORTER': '1', 04:20:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:20:33 INFO - 'MOZ_NO_REMOTE': '1', 04:20:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:20:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:20:33 INFO - 'NO_EM_RESTART': '1', 04:20:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:20:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:33 INFO - 'PWD': '/builds/slave/test', 04:20:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:20:33 INFO - 'RUST_BACKTRACE': 'full', 04:20:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:20:33 INFO - 'SHELL': '/bin/bash', 04:20:33 INFO - 'SHLVL': '1', 04:20:33 INFO - 'TERM': 'linux', 04:20:33 INFO - 'TMOUT': '86400', 04:20:33 INFO - 'USER': 'cltbld', 04:20:33 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:20:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:33 INFO - '_': '/tools/buildbot/bin/python'} 04:20:33 INFO - TEST-INFO | started process 14894 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyH5h3_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:20:34 INFO - PID 14894 | 04:20:34 INFO - PID 14894 | (/builds/slave/test/build/application/firefox/firefox:14943): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:20:34 INFO - PID 14894 | 04:20:44 INFO - PID 14894 | RSS: Main: 145248256 04:20:44 INFO - PID 14894 | 04:23:41 INFO - PID 14894 | Cycle 1(1): loaded http://localhost:42653/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42653/tests/dromaeo/cssquery-ext.html) 04:23:42 INFO - PID 14894 | RSS: Main: 146030592 04:23:42 INFO - PID 14894 | 04:26:44 INFO - PID 14894 | Cycle 1(1): loaded http://localhost:42653/tests/dromaeo/cssquery-ext.html (next: http://localhost:42653/tests/dromaeo/cssquery-jquery.html) 04:26:44 INFO - PID 14894 | RSS: Main: 146178048 04:26:44 INFO - PID 14894 | 04:29:46 INFO - PID 14894 | Cycle 1(1): loaded http://localhost:42653/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42653/tests/dromaeo/cssquery-mootools.html) 04:29:46 INFO - PID 14894 | RSS: Main: 148529152 04:29:46 INFO - PID 14894 | 04:32:48 INFO - PID 14894 | Cycle 1(1): loaded http://localhost:42653/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42653/tests/dromaeo/cssquery-prototype.html) 04:32:49 INFO - PID 14894 | RSS: Main: 146587648 04:32:49 INFO - PID 14894 | 04:35:52 INFO - PID 14894 | Cycle 1(1): loaded http://localhost:42653/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42653/tests/dromaeo/cssquery-yui.html) 04:35:52 INFO - PID 14894 | RSS: Main: 146325504 04:35:52 INFO - PID 14894 | 04:38:54 INFO - PID 14894 | Cycle 1(1): loaded http://localhost:42653/tests/dromaeo/cssquery-yui.html (next: http://localhost:42653/tests/dromaeo/cssquery-dojo.html) 04:38:54 INFO - PID 14894 | RSS: Main: 150618112 04:38:54 INFO - PID 14894 | 04:38:54 INFO - PID 14894 | __start_tp_report 04:38:54 INFO - PID 14894 | _x_x_mozilla_page_load 04:38:54 INFO - PID 14894 | _x_x_mozilla_page_load_details 04:38:54 INFO - PID 14894 | |i|pagename|runs| 04:38:54 INFO - PID 14894 | |0;dojo.html;3882.9417558736623;3897.551781545123;3900.609939006099;3907.7459965102285;3945.0531872350625;6275.6548389838545;6282.465990390822;6320.4943604511645;6354.459870910972;6437.581557198779;6344.6827658617;6348.365163483661;6441.581297215677;6447.90328145077;6476.967615161918;5626.9437305626825;5691.430856914317;5710.800121995731;5782.277215348082;5806.7096645167785;172.56650498599103;5675.5459563234845;5717.428257174291;5752.252207213057;5762.164486149506;5590.2732644756325;5591.049521581341;5604.243427137325;5611.410801865806;5676.9497642936185;5566.582506312043;5607.775688972477;5667.3482549506925;5676.886462270772;5690.4025077367105;5581.358143813432;5599.104143337087;5652.152177173457;5663.575231857586;5667.914981275284;6201.16284301618;6207.410296021871;6209.565330426827;6270.937290627081;6271.749130034793;6734.326567343275;6744.460443164535;6781.694823732944;6821.3519715626935;6845.623490708009;6876.896846547305;6901.861962760767;6969.26822683616;6972.686229119702;6972.825679358057;6316.494680425555;6323.525735569805;6490.707918143695;6502.154719886385;6520.282768895465;7097.361237488651;7171.49799514029;7200.387967022757;7203.531770434905;7203.89194162088;6773.254941956381;6829.829254268584;6836.965815170893;6901.896471552931;6919.411849992807;6849.965750171317;6964.825879352956;6969.30306969299;6978.720851165998;6980.616066116412;6740.865182696417;6835.043093966749;6839.897401538981;6864.24493305736;6886.724531018703;6707.463402927754;6791.2869148739655;6797.28628494001;6799.354061364163;6806.081179040865;4864.805407783649;4922.409310882717;4996.950030499721;5003.199488081889;5007.349044624249;4448.1770872388715;4489.304157855538;4498.055408364215;4498.910021799546;4500.729956202638;6160.445559899541;6282.717277722513;6283.120363149163;6288.9056664150075;6301.8109456716375;6873.828154296182;6946.826329341807;6955.374016338554;6975.965120174468;7006.264342244093;6296.464800491918;6297.6536290503545;6365;6372.2671892732005;6385.297617262097;5566.777328906879;5576.302962129734;5624.831255062355;5638.379778224474;5638.830835074954;7088.716451341992;7110.466714996396;7130.03744494497;7143.285671432919;7145.92854071463;6765.424938880054;6942.305769423017;6944.097267355211;6951.5481493703755;6951.991961165602;7028.543144695578;7039.54242974205;7122.5726456412785;7133.714651413885;7161.785146445615;6652.135222421054;6804.55770374924;6814.420774234048;6826.283240259701;6832.795016149524;6871.896921546081;6920.169579650475;6935.965320173367;6959.582425054513;6965.686544105629;6569.605823650405;6633.834154146185;6648.933510664831;6649.966750166218;6654.268030516736;6544.6727663617585;6548.901766473411;6579.539432239889;6653.168353955755;6664.5334826561675;6452.612843229421;6469.353064693493;6545.050967609765;6549.672516374067;6583.967080164569;6732.293109223657;6742.831429214113;6773.491988100814;6790.558613690095;6826.419754320938;6385.201849768779;6391.296957334596;6477.77327793525;6478.967605161944;6484.092227088117 04:38:54 INFO - PID 14894 | |1;ext.html;947.9241660667143;980.7548112971756;1006.6476733143396;1007.531497853498;1015.0306457333245;43848;44514.7774261129;44697.329540056926;44705.552944470546;44717.77641111794;41999.370009449885;45485.545144548545;46100.53899461004;46228.306575401315;46276.76861615696;8239.835203295916;8329.50022998619;8362.414630975834;8377.49735015901;8380.287675547586;8252.628631711557;8469.915300846975;8518.105598912118;8525.403221774479;8529.74410767678;8519.233269005788;8538;8547.401681882271;8563.400561960665;8567.614457349404;8431.28334091603;8451.23938845507;8510.276626486788;8517.276031537327;8535.914640853605;8222.301104406133;8243.546604936726;8293.668253269861;8308.086110527836;8336;41737.791311043555;41798.582014179774;42205.57794422078;42350;42433.78783106065;3708.7589306694977;3745.494358261627;3749;3757.511523501955;3779.527559055118;33857;33997.660023399694;34007.829960850286;34190;34275.14312142191;5108.897822043576;5269.6311258211945;5300;5346.812861549866;5398.433164517748;230463;230885;231149.9999999983;231228;231588;52192.47807521952;52678.10270435163;52852.20721689178;52894.73552632289;52944.47055529473;55817;56030;56218;56334.43665563374;56358.7182064087;15730.29213685364;15730.370785168465;15739;15786;16794.58013549671;12398.75202495945;12406.62780116598;12417.068719846047;12421.068419868547;12423.937880310721;14351.712965740835;14759.335829887681;14788.408463661548;14807.333669984877;14991;5503.724813759376;5563.721813909288;5571.860703482445;5644.407337229614;5694.572907031907;1078.6710053433778;1082.5128692088592;1091.8143915534379;1098.4727330881221;1113.693734223079;25240;25603.61594576083;25898.611520827202;26006.609900851123;26164.084257050912;1169.0325659072025;1172.9120315976336;1178.752461982976;1186.5194596188558;1188.8751681073363;13226.272555009375;13556.864431355758;13588.796168057486;13778.65553361174;13828.170309781446;290516;291355;291759;292698;293003;58544.70727646335;58861.117083243786;63112.36887631065;63906;64130.67934660297;1518.825335086457;1536.1934984133195;1542.591213328469;1545.8376870428442;1557.6573153906122;30264.84867575648;30970.070897873098;31594.52608210833;31806.522902156023;32183;42276.1544769109;42315.57684423116;42387.78806106073;42401.57598423976;42475.36286955636;253228;253349;253653;254842;254962;35253;36185.27629447449;36407.817960910026;36903;36992.81503592573;45523.544764553255;47284.52715472801;47289.763551182026;47647.28529072136;47802;34844.65155348414;35376.29247415089;35411.46882796709;35506;35671.82164089163;34903.82548087345;35018;35345.823270883484;35421;35497.64502355047;41363.79318103511;41690.1661966765;41707.582924171584;42025.15949680928;42336.78831605822;33972.83013584916;34102.317953641286;34312.656873431944;34400;34416;25490.34312769664;26083.73916260813;26120.8693956529;26468.735312646626;26797 04:38:54 INFO - PID 14894 | |2;jquery.html;605.3704147686401;607.5812976699407;618.5237367227232;627.6234259444333;647.5014239035943;22821.771782282172;23300.417489562748;24282.392940176505;24302.87848560759;24411.389715257126;25714.6142807858;26235.606465903027;26249.212523624275;26265.47469050618;26266.737332626668;6533.8366540836505;6625.867482650344;6671.933280667204;6687.063811066455;6691.565048271871;6675.66621668917;6930.688119034656;6983.057287266231;7005.579665220078;7009.894851577231;6926.792196234121;6945.409640180591;6946.930530694679;6952.895706564405;6953.096097507317;6637.701303441357;6849.623270720109;6927.411170050552;6974.232834388214;6995.790126296169;6489.253735820394;6519.413252807222;6525.543211975164;6525.902111468332;6546.345365463416;29598.408031839244;30129.096127116223;30185.849070754724;30192.396152077054;30208.8489557553;2204.2175027865037;2205.228170140454;2242.9102835886547;2252.4594097416502;2253.5943530164564;18856.34002809895;19122;19215.135318910543;19269.80730192708;19344;2575.961360579593;2583.7287084856193;2631.158029430583;2655.3494393873625;2661.3479697474245;139846;151690;160060;162783;162843;15871.841281587267;16666.166691665367;16882.15589220534;16990;17269.740953885703;26486;26508.204753857415;26646;26661.46677066448;26700.465990680466;10796.244262901522;10848.566057357617;10852.294600850919;10893.727656808485;10988.945055274673;9826.4104153751;9889.258305627107;9924.60301587943;9967.750806229757;10121.898781012243;9523.714288571355;9713.805723885484;9744.220462362993;9818.705438836847;10147.289689721649;2960.8237945110327;2971.494845876163;2980.6423229212633;3006.428778532072;3025.3495498467937;1033.7105610429091;1043.8956104389652;1051.153821173947;1056.3186744549723;1059.4067322299536;21416.357509274436;21641.458963526038;21794;21881.12475500994;22067.11731530721;1093.6227001684565;1110;1121.0975164992096;1124.9887501125047;1130.7060164357326;11676;12063.155579109378;12074.69813254658;12077.818832717516;12157.574484892986;601502;609300;614453;616755;627785;129470;133811;137841;138007;138503;1111.766529028913;1158;1195.6114262864603;1214.2714371377103;1259.9622011339675;21810.909454526893;22234.332970010924;22316.553668926204;22345.217917372815;22355.217567385065;61476.69261653663;61687;62476.687616563446;63674.681626593425;64254;77311;77696.61151694205;78046.21953780389;78293.60853195697;79438.6028069856;15847;15905.125218113115;16040.43858464948;16196.271167797242;16225;24514.14200502974;24585.385365365295;24842.25473235806;24910.37724056913;25042.749572504043;19857.205711771367;20014.89918054521;20062.899685501478;20221.6966745496;20311.79688203099;18070.728939065662;18144.274229030692;18373.448796536126;18490.63018739591;18559.81440185581;20472.897635511727;20512.38462846117;20560;20587.79412205859;20723.171073156904;15819.762703559685;15831.129287888816;15887.920560397586;15930.920345398663;15979.201039948188;19547;19585.70621440708;19700.802991969897;19768.110435030747;19857.900710496357 04:38:54 INFO - PID 14894 | |3;mootools.html;1439.3738723655208;1464.6850927050682;1472.4257539559567;1480.178500931983;1488.203810961136;16740.414085506993;17459.952402855837;17461.912690436548;17483.475495735118;17505.124743762826;17257.654846903053;17370.913145434286;17383.82616173838;17385.73921391177;17400.564985875328;2819.746222839944;2944.175630823373;2945.145907686779;2947.3515826518237;2952.793304468688;2869.34005178809;2917.868695908689;2917.9562306565417;2920.4305160493714;2922.839243841588;2808.241774720824;2884.048264072851;2899.246195989037;2903.5499497577907;2909.6508418989647;2918.9270268243254;2922.810017348876;2954.852257387133;2957.2311199088176;2961.7778666599984;2866.3264132928743;2898.2029941765973;2928.5314349703945;2935.442265969459;2946.454905842426;16386.26261818221;16731.247093880684;16741.66516669672;16742.665146696996;16790.83209167905;8773.736787896374;9021.594028268744;9029.683961061397;9035.864462033074;9042.186203241674;5571.749271282802;5600.355959064719;5737.827865164052;5773.278340207474;5815.621984571032;2155.2380437825846;2162.8702277863376;2196.9231076912233;2226.7884550967633;2235.1394713035434;30948.690513095033;31226.843865780753;31253.843730781202;31371.84314078438;31513.84243078793;7437.293457121565;7460.403167746567;7463.104427468738;7498.4001279897475;7677.539347639159;20339.8983005084;20365.08357123933;20395.082221300076;20458.283960061326;20509.487262818548;8479.745607631781;8681;8758.649654013896;8824.8676269856;8883.955580222058;7734.651940662684;7863.488873223222;7870.055593328837;7894.565798881002;7934.444588878723;6015.157877897098;6174.073888916699;6179.721912513947;6232.532560058014;6299.181106456132;1798.307651554135;1831.09360866372;1870.289290069792;1924.2771478358247;1955.217912834849;933.4679232837353;974.3520558828442;977.2182254196614;978.5254151736402;986.6250824686573;9008.63965441388;9088.54557272147;9127.543622818832;9300.11648893354;9560;855.2815634866741;890.8995997912032;1054.2883553601441;1054.9050585447346;1062.3678911047914;5325.920111198335;5804.390538993429;5856.765729370863;5872.823815285548;5950.9107363389585;23589.764102358757;24511.142110026063;24661.38346541351;24732.876335618566;24750;24466.877665611915;24472.265832025067;24694.13570525059;24738;24747.62878556823;12102.152849300464;12134.453949572291;12165.391730413621;12231.571894983634;12258.14193006481;9048.90951090507;9071.863922041306;9087.909120908706;9166.862497062682;9175.862362064709;20415.59168816645;20607.278745243846;21164.259250926145;21389.25137620176;21592;21758.564828703653;21785.4553636154;22033.245844269466;22066.668999965335;22363;13440.798388024383;13799.86200138026;13812.86187138156;13826.101303415246;13827;15269.160196189316;15274.618134546725;15330.616734581725;15392.153431561372;15506;11829.76340473203;12041.277523348626;12098.09264305163;12353;12493.93753031229;12305;12486.687832804253;12644;12760.29818359999;12868.485260589472;10143;10304.587816487257;10318.329308594917;10319.896801031893;10324;7978.3218426434405;8042.517448953082;8060.919390806017;8143.42995990275;8191.631376588187;7667.88498172539;7710.074791025113;7783.805404864924;7840.803979900548;7914.881276780968 04:38:54 INFO - PID 14894 | |4;prototype.html;553.5018483364971;571.9828405147846;575.2435547255361;585.853536615846;591.7455494137521;6259.4977205470705;6268.623882567043;6294.43350098491;6294.464970477515;6367.458766004896;6175.197224360838;6175.413335733109;6185.381461853824;6229.314775374705;6243.375662433755;4872.707637541741;4963.578095861856;5082.593392528607;5098.476254254131;5100.642954993152;4972.900541989158;4986.526280003394;5001.874953126165;5005.5233706056615;5009.148444764381;4945.307656928033;4952.405711314647;4955.08330958772;4964.429090654585;4969.285596469213;4944.60443164546;4952;4958.628102892298;4972.5027497249985;4975.407869481767;4505.535700897189;4535.682502224846;4539.931901021488;4560.589546940758;4572.725636461823;6167.814965551041;6172.629642221436;6178.165947597062;6200.658963756991;6208.441240288395;5711.428857114297;5737.483626473596;5783.132530120515;5787.71061446926;5820.592558520905;6505.316941721146;6510.479161667055;6517.608943463408;6544.312847151028;6549.67251637421;4831.782569784333;5005.57452616528;5006.5744411725045;5013.623978201613;5022.924656130161;6592;6659.733610655568;6681.832954176136;6798.2182049064;6828.453723702092;5108.514691104339;5162.328897243335;5163.509466600667;5167.199084141964;5172.663776854492;5282.181261904411;5284.762185701653;5291.603129765283;5295.602829787781;5299.920501192485;5837.803250333672;5847.298324201123;5848.561357898089;5849.3858144894175;5862.736176872051;6467.288598254188;6512.706928188243;6558.1802274715665;6561.901571476432;6584.86830263392;1785.81248968859;1808.5569035586366;1811.7101263797729;1816.1554876982266;1816.4096668582763;1238.5417395563518;1252.8371311729418;1271.319843883518;1272.5355245335386;1293.805929110641;5972.343042265347;6029.909551356734;6092.7562897484495;6100.901837669262;6114.052321890114;6682.83292917681;6695.397414232742;6699.665016749143;6711.3288671132495;6751.3586209311015;5945.791897283574;5964.284285885721;6088.878222435526;6089.665068421191;6120.816375508742;3545.858165673396;3552.2540266544315;3573.2317551726505;3585;3594.5506811648543;6806.8298292542095;6946.51374403797;6973.546719463218;6975.686094125776;6997.1253593300835;6869.553479023847;6884.827879302958;6890.311873591002;6920.8961865572055;6937.79186624392;6817.795466136024;6832.795016149424;6834.726610935507;6845.315468453115;6919.861602768017;6618.569792963442;6726.764563240263;6726.8654626908165;6756.966215169089;6776.848765324148;6541.182352205974;6617.702203400762;6643.435307998874;6683.365080317362;6703.966480167568;6753.561018533779;6769;6819.761308354184;6864.176298844171;6877.65611719422;5548.944510555004;5565.220869078414;5567.5545956322585;5601;5618.213450116905;6509.153810004669;6700.128983232149;6735.360140786617;6740;6766;6255.53083518747;6488.935110648833;6519.934800651933;6584.604923704593;6600.405963463311;6410.583312084699;6425.614463132227;6484.870302593828;6488.805335839932;6537.28089910119;6309.652969086561;6373.5857169283845;6393.328700486568;6410.4871610272;6444.258910225384;6354.332795056453;6412.871742565216;6433.9678301610065;6503.674816259263;6651.667416629246;6262.686865656608;6283.937160628335;6309.810705678837;6319.96840015797;6404.679766011774 04:38:54 INFO - PID 14894 | |5;yui.html;969.3699095587863;969.7284760267125;982.7985263021081;986.9309148359617;988.74786929333;20436.284730034433;20960.580788384224;20999.790002099973;21049.684254736152;21289.893550532244;19507.902460487712;19580.41258762236;19647.31234406796;19688.409347719593;19733.605327893434;1027.7944411117787;1045.158647288936;1047.6490375724104;1048.182417714184;1051.6582110813977;1061.5010944855906;1070.6306324318098;1072.635303996641;1074.2211896375104;1074.8280275155976;1062.288266861201;1113.6881673131536;1117.8155604325277;1120.1430905357408;1123.8988491035805;1108.866935967685;1109.2401704832175;1111.5109351885226;1111.6164923101599;1113.6325012745815;811.4238890387832;812.3907069697726;815.2825513548099;815.2866242038217;815.6329651656719;21292.467688307763;21314.680279795815;21388.41821987435;21453.463663408384;21458.356249312546;23633.172838950555;24162.154324598556;24327.391815204584;24330.878345608337;24340.756592434027;18579.628407431777;18734.531636709056;18948.14733337003;18962.81037189624;18971;3823.9044023899346;3929.862454814068;3935.193285376491;3939.2909276330247;3957.426173204898;126753;142488;156955;160256;162410;18821.435356939313;18858.81141188598;19290;19297.807021929602;19347.709784352963;30464.69535304663;30476.847615762224;31236;31576;31619.683803162134;10143.23925705575;10279.94860025695;10303.896961030294;10316.071553560065;10357.067863892285;4874.951250487521;4969.826056088092;4978.203487442064;4983.302337672729;4984.177610694195;5911.935851546763;6008.369121242206;6044.879102418015;6077.969610151921;6090.878182436327;1278.2586100061942;1287.266258232817;1306.4643496166716;1308.2216081431534;1308.941097650608;712.7042277454901;714.9928500715031;723.7105157936867;732.3152852083319;747.704656660616;12075.57735479254;12115.818262726067;12545.372731363395;12900.677483062811;13177.604671859859;720.1250480666016;723.5984028863926;730.8757511223105;731.6122455098806;742.5173637135905;5554.666719996732;5568.220449137124;5594.244776955059;5652.463016013472;5701.857453563694;150847;151281;153179;154124;154737.45262547454;130701;133889.33055334855;135193.6480635201;137025;137582;109173;111122.44438777755;113149.43425282821;113170;114526.42736786263;13863.514776982756;14374.137551746511;14430.206338651475;14517.854821452072;14537.34581943794;52223;53408.73295633497;54124.729376352865;54275.72862135664;54891.45108549023;67525.32474675387;93292;95925.52037239769;96948;97715.51142244243;21587.46031349166;21648;21657.350279491642;21668.783312166677;21857.12571497186;21821.563568728852;22169;23695.644565331877;23829.761702383446;24135.63796543018;21399.8930005349;22680.432989175402;23182.1886233981;23206.76793232046;23280.185193518144;20082.39752807418;20325.59348813045;20531.28140515075;20581.279655211994;20686.896565517076;20524.38426847197;21053.684194736787;21430.571388572454;21580.67628985535;21704.240351587618;13851.86148138546;14244.857551424353;14365.569032929028;14544;14639.48761793332;15404.38382464689;16467.752983705013;16560.337586496888;16592.253348599585;16597.834021660114 04:38:54 INFO - PID 14894 | __end_tp_report 04:38:54 INFO - PID 14894 | __start_cc_report 04:38:54 INFO - PID 14894 | _x_x_mozilla_cycle_collect,267 04:38:54 INFO - PID 14894 | __end_cc_report 04:38:54 INFO - PID 14894 | __startTimestamp1506512334683__endTimestamp 04:38:54 INFO - PID 14894 | 04:38:54 INFO - PID 14894 | ------- Summary: start ------- 04:38:54 INFO - PID 14894 | Number of tests: 6 04:38:54 INFO - PID 14894 | 04:38:54 INFO - PID 14894 | [#0] dojo.html Cycles:165 Average:6281.43 Median:6544.67 stddev:902.78 (13.8%) stddev-sans-first:885.72 04:38:54 INFO - PID 14894 | Values: 3882.9 3897.6 3900.6 3907.7 3945.1 6275.7 6282.5 6320.5 6354.5 6437.6 6344.7 6348.4 6441.6 6447.9 6477.0 5626.9 5691.4 5710.8 5782.3 5806.7 172.6 5675.5 5717.4 5752.3 5762.2 5590.3 5591.0 5604.2 5611.4 5676.9 5566.6 5607.8 5667.3 5676.9 5690.4 5581.4 5599.1 5652.2 5663.6 5667.9 6201.2 6207.4 6209.6 6270.9 6271.7 6734.3 6744.5 6781.7 6821.4 6845.6 6876.9 6901.9 6969.3 6972.7 6972.8 6316.5 6323.5 6490.7 6502.2 6520.3 7097.4 7171.5 7200.4 7203.5 7203.9 6773.3 6829.8 6837.0 6901.9 6919.4 6850.0 6964.8 6969.3 6978.7 6980.6 6740.9 6835.0 6839.9 6864.2 6886.7 6707.5 6791.3 6797.3 6799.4 6806.1 4864.8 4922.4 4997.0 5003.2 5007.3 4448.2 4489.3 4498.1 4498.9 4500.7 6160.4 6282.7 6283.1 6288.9 6301.8 6873.8 6946.8 6955.4 6976.0 7006.3 6296.5 6297.7 6365.0 6372.3 6385.3 5566.8 5576.3 5624.8 5638.4 5638.8 7088.7 7110.5 7130.0 7143.3 7145.9 6765.4 6942.3 6944.1 6951.5 6952.0 7028.5 7039.5 7122.6 7133.7 7161.8 6652.1 6804.6 6814.4 6826.3 6832.8 6871.9 6920.2 6936.0 6959.6 6965.7 6569.6 6633.8 6648.9 6650.0 6654.3 6544.7 6548.9 6579.5 6653.2 6664.5 6452.6 6469.4 6545.1 6549.7 6584.0 6732.3 6742.8 6773.5 6790.6 6826.4 6385.2 6391.3 6477.8 6479.0 6484.1 04:38:54 INFO - PID 14894 | 04:38:54 INFO - PID 14894 | [#1] ext.html Cycles:180 Average:44096.67 Median:36655.41 stddev:67710.47 (184.7%) stddev-sans-first:67822.91 04:38:54 INFO - PID 14894 | Values: 947.9 980.8 1006.6 1007.5 1015.0 43848.0 44514.8 44697.3 44705.6 44717.8 41999.4 45485.5 46100.5 46228.3 46276.8 8239.8 8329.5 8362.4 8377.5 8380.3 8252.6 8469.9 8518.1 8525.4 8529.7 8519.2 8538.0 8547.4 8563.4 8567.6 8431.3 8451.2 8510.3 8517.3 8535.9 8222.3 8243.5 8293.7 8308.1 8336.0 41737.8 41798.6 42205.6 42350.0 42433.8 3708.8 3745.5 3749.0 3757.5 3779.5 33857.0 33997.7 34007.8 34190.0 34275.1 5108.9 5269.6 5300.0 5346.8 5398.4 230463.0 230885.0 231150.0 231228.0 231588.0 52192.5 52678.1 52852.2 52894.7 52944.5 55817.0 56030.0 56218.0 56334.4 56358.7 15730.3 15730.4 15739.0 15786.0 16794.6 12398.8 12406.6 12417.1 12421.1 12423.9 14351.7 14759.3 14788.4 14807.3 14991.0 5503.7 5563.7 5571.9 5644.4 5694.6 1078.7 1082.5 1091.8 1098.5 1113.7 25240.0 25603.6 25898.6 26006.6 26164.1 1169.0 1172.9 1178.8 1186.5 1188.9 13226.3 13556.9 13588.8 13778.7 13828.2 290516.0 291355.0 291759.0 292698.0 293003.0 58544.7 58861.1 63112.4 63906.0 64130.7 1518.8 1536.2 1542.6 1545.8 1557.7 30264.8 30970.1 31594.5 31806.5 32183.0 42276.2 42315.6 42387.8 42401.6 42475.4 253228.0 253349.0 253653.0 254842.0 254962.0 35253.0 36185.3 36407.8 36903.0 36992.8 45523.5 47284.5 47289.8 47647.3 47802.0 34844.7 35376.3 35411.5 35506.0 35671.8 34903.8 35018.0 35345.8 35421.0 35497.6 41363.8 41690.2 41707.6 42025.2 42336.8 33972.8 34102.3 34312.7 34400.0 34416.0 25490.3 26083.7 26120.9 26468.7 26797.0 04:38:54 INFO - PID 14894 | 04:38:54 INFO - PID 14894 | [#2] jquery.html Cycles:180 Average:40445.68 Median:2253.03 stddev:102844.72 (4564.7%) stddev-sans-first:103089.72 04:38:54 INFO - PID 14894 | Values: 605.4 607.6 618.5 627.6 647.5 22821.8 23300.4 24282.4 24302.9 24411.4 25714.6 26235.6 26249.2 26265.5 26266.7 6533.8 6625.9 6671.9 6687.1 6691.6 6675.7 6930.7 6983.1 7005.6 7009.9 6926.8 6945.4 6946.9 6952.9 6953.1 6637.7 6849.6 6927.4 6974.2 6995.8 6489.3 6519.4 6525.5 6525.9 6546.3 29598.4 30129.1 30185.8 30192.4 30208.8 2204.2 2205.2 2242.9 2252.5 2253.6 18856.3 19122.0 19215.1 19269.8 19344.0 2576.0 2583.7 2631.2 2655.3 2661.3 139846.0 151690.0 160060.0 162783.0 162843.0 15871.8 16666.2 16882.2 16990.0 17269.7 26486.0 26508.2 26646.0 26661.5 26700.5 10796.2 10848.6 10852.3 10893.7 10988.9 9826.4 9889.3 9924.6 9967.8 10121.9 9523.7 9713.8 9744.2 9818.7 10147.3 2960.8 2971.5 2980.6 3006.4 3025.3 1033.7 1043.9 1051.2 1056.3 1059.4 21416.4 21641.5 21794.0 21881.1 22067.1 1093.6 1110.0 1121.1 1125.0 1130.7 11676.0 12063.2 12074.7 12077.8 12157.6 601502.0 609300.0 614453.0 616755.0 627785.0 129470.0 133811.0 137841.0 138007.0 138503.0 1111.8 1158.0 1195.6 1214.3 1260.0 21810.9 22234.3 22316.6 22345.2 22355.2 61476.7 61687.0 62476.7 63674.7 64254.0 77311.0 77696.6 78046.2 78293.6 79438.6 15847.0 15905.1 16040.4 16196.3 16225.0 24514.1 24585.4 24842.3 24910.4 25042.7 19857.2 20014.9 20062.9 20221.7 20311.8 18070.7 18144.3 18373.4 18490.6 18559.8 20472.9 20512.4 20560.0 20587.8 20723.2 15819.8 15831.1 15887.9 15930.9 15979.2 19547.0 19585.7 19700.8 19768.1 19857.9 04:38:54 INFO - PID 14894 | 04:38:54 INFO - PID 14894 | [#3] mootools.html Cycles:180 Average:10512.74 Median:2867.83 stddev:7731.53 (269.6%) stddev-sans-first:7723.16 04:38:54 INFO - PID 14894 | Values: 1439.4 1464.7 1472.4 1480.2 1488.2 16740.4 17460.0 17461.9 17483.5 17505.1 17257.7 17370.9 17383.8 17385.7 17400.6 2819.7 2944.2 2945.1 2947.4 2952.8 2869.3 2917.9 2918.0 2920.4 2922.8 2808.2 2884.0 2899.2 2903.5 2909.7 2918.9 2922.8 2954.9 2957.2 2961.8 2866.3 2898.2 2928.5 2935.4 2946.5 16386.3 16731.2 16741.7 16742.7 16790.8 8773.7 9021.6 9029.7 9035.9 9042.2 5571.7 5600.4 5737.8 5773.3 5815.6 2155.2 2162.9 2196.9 2226.8 2235.1 30948.7 31226.8 31253.8 31371.8 31513.8 7437.3 7460.4 7463.1 7498.4 7677.5 20339.9 20365.1 20395.1 20458.3 20509.5 8479.7 8681.0 8758.6 8824.9 8884.0 7734.7 7863.5 7870.1 7894.6 7934.4 6015.2 6174.1 6179.7 6232.5 6299.2 1798.3 1831.1 1870.3 1924.3 1955.2 933.5 974.4 977.2 978.5 986.6 9008.6 9088.5 9127.5 9300.1 9560.0 855.3 890.9 1054.3 1054.9 1062.4 5325.9 5804.4 5856.8 5872.8 5950.9 23589.8 24511.1 24661.4 24732.9 24750.0 24466.9 24472.3 24694.1 24738.0 24747.6 12102.2 12134.5 12165.4 12231.6 12258.1 9048.9 9071.9 9087.9 9166.9 9175.9 20415.6 20607.3 21164.3 21389.3 21592.0 21758.6 21785.5 22033.2 22066.7 22363.0 13440.8 13799.9 13812.9 13826.1 13827.0 15269.2 15274.6 15330.6 15392.2 15506.0 11829.8 12041.3 12098.1 12353.0 12493.9 12305.0 12486.7 12644.0 12760.3 12868.5 10143.0 10304.6 10318.3 10319.9 10324.0 7978.3 8042.5 8060.9 8143.4 8191.6 7667.9 7710.1 7783.8 7840.8 7914.9 04:38:54 INFO - PID 14894 | 04:38:54 INFO - PID 14894 | [#4] prototype.html Cycles:180 Average:5591.08 Median:6193.02 stddev:1543.81 (24.9%) stddev-sans-first:1501.13 04:38:54 INFO - PID 14894 | Values: 553.5 572.0 575.2 585.9 591.7 6259.5 6268.6 6294.4 6294.5 6367.5 6175.2 6175.4 6185.4 6229.3 6243.4 4872.7 4963.6 5082.6 5098.5 5100.6 4972.9 4986.5 5001.9 5005.5 5009.1 4945.3 4952.4 4955.1 4964.4 4969.3 4944.6 4952.0 4958.6 4972.5 4975.4 4505.5 4535.7 4539.9 4560.6 4572.7 6167.8 6172.6 6178.2 6200.7 6208.4 5711.4 5737.5 5783.1 5787.7 5820.6 6505.3 6510.5 6517.6 6544.3 6549.7 4831.8 5005.6 5006.6 5013.6 5022.9 6592.0 6659.7 6681.8 6798.2 6828.5 5108.5 5162.3 5163.5 5167.2 5172.7 5282.2 5284.8 5291.6 5295.6 5299.9 5837.8 5847.3 5848.6 5849.4 5862.7 6467.3 6512.7 6558.2 6561.9 6584.9 1785.8 1808.6 1811.7 1816.2 1816.4 1238.5 1252.8 1271.3 1272.5 1293.8 5972.3 6029.9 6092.8 6100.9 6114.1 6682.8 6695.4 6699.7 6711.3 6751.4 5945.8 5964.3 6088.9 6089.7 6120.8 3545.9 3552.3 3573.2 3585.0 3594.6 6806.8 6946.5 6973.5 6975.7 6997.1 6869.6 6884.8 6890.3 6920.9 6937.8 6817.8 6832.8 6834.7 6845.3 6919.9 6618.6 6726.8 6726.9 6757.0 6776.8 6541.2 6617.7 6643.4 6683.4 6704.0 6753.6 6769.0 6819.8 6864.2 6877.7 5548.9 5565.2 5567.6 5601.0 5618.2 6509.2 6700.1 6735.4 6740.0 6766.0 6255.5 6488.9 6519.9 6584.6 6600.4 6410.6 6425.6 6484.9 6488.8 6537.3 6309.7 6373.6 6393.3 6410.5 6444.3 6354.3 6412.9 6434.0 6503.7 6651.7 6262.7 6283.9 6309.8 6320.0 6404.7 04:38:54 INFO - PID 14894 | 04:38:54 INFO - PID 14894 | [#5] yui.html Cycles:180 Average:29317.70 Median:20634.09 stddev:42283.77 (204.9%) stddev-sans-first:42348.81 04:38:54 INFO - PID 14894 | Values: 969.4 969.7 982.8 986.9 988.7 20436.3 20960.6 20999.8 21049.7 21289.9 19507.9 19580.4 19647.3 19688.4 19733.6 1027.8 1045.2 1047.6 1048.2 1051.7 1061.5 1070.6 1072.6 1074.2 1074.8 1062.3 1113.7 1117.8 1120.1 1123.9 1108.9 1109.2 1111.5 1111.6 1113.6 811.4 812.4 815.3 815.3 815.6 21292.5 21314.7 21388.4 21453.5 21458.4 23633.2 24162.2 24327.4 24330.9 24340.8 18579.6 18734.5 18948.1 18962.8 18971.0 3823.9 3929.9 3935.2 3939.3 3957.4 126753.0 142488.0 156955.0 160256.0 162410.0 18821.4 18858.8 19290.0 19297.8 19347.7 30464.7 30476.8 31236.0 31576.0 31619.7 10143.2 10279.9 10303.9 10316.1 10357.1 4875.0 4969.8 4978.2 4983.3 4984.2 5911.9 6008.4 6044.9 6078.0 6090.9 1278.3 1287.3 1306.5 1308.2 1308.9 712.7 715.0 723.7 732.3 747.7 12075.6 12115.8 12545.4 12900.7 13177.6 720.1 723.6 730.9 731.6 742.5 5554.7 5568.2 5594.2 5652.5 5701.9 150847.0 151281.0 153179.0 154124.0 154737.5 130701.0 133889.3 135193.6 137025.0 137582.0 109173.0 111122.4 113149.4 113170.0 114526.4 13863.5 14374.1 14430.2 14517.9 14537.3 52223.0 53408.7 54124.7 54275.7 54891.5 67525.3 93292.0 95925.5 96948.0 97715.5 21587.5 21648.0 21657.4 21668.8 21857.1 21821.6 22169.0 23695.6 23829.8 24135.6 21399.9 22680.4 23182.2 23206.8 23280.2 20082.4 20325.6 20531.3 20581.3 20686.9 20524.4 21053.7 21430.6 21580.7 21704.2 13851.9 14244.9 14365.6 14544.0 14639.5 15404.4 16467.8 16560.3 16592.3 16597.8 04:38:54 INFO - PID 14894 | -------- Summary: end -------- 04:38:54 INFO - PID 14894 | 04:38:55 INFO - TEST-INFO | 14894: exit 0 04:38:55 INFO - TEST-OK | dromaeo_css | took 1107493ms 04:38:55 INFO - TEST-START | kraken 04:38:55 INFO - Initialising browser for kraken test... 04:38:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42653/getInfo.html -profile /tmp/tmpEZYoGV/profile 04:38:55 INFO - TEST-INFO | started process 15264 (/builds/slave/test/build/application/firefox/firefox http://localhost:42653/getInfo.html) 04:39:00 INFO - TEST-INFO | 15264: exit 0 04:39:00 INFO - Browser initialized. 04:39:00 INFO - Running cycle 1/1 for kraken test... 04:39:00 INFO - Using env: {'DISPLAY': ':0', 04:39:00 INFO - 'HOME': '/home/cltbld', 04:39:00 INFO - 'JSGC_DISABLE_POISONING': '1', 04:39:00 INFO - 'LANG': 'en_US.UTF-8', 04:39:00 INFO - 'LANGUAGE': 'en_US:en', 04:39:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:39:00 INFO - 'LOGNAME': 'cltbld', 04:39:00 INFO - 'MAIL': '/var/mail/cltbld', 04:39:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:39:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:39:00 INFO - 'MOZ_CRASHREPORTER': '1', 04:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:39:00 INFO - 'MOZ_NO_REMOTE': '1', 04:39:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:39:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:00 INFO - 'NO_EM_RESTART': '1', 04:39:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:00 INFO - 'PWD': '/builds/slave/test', 04:39:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:39:00 INFO - 'RUST_BACKTRACE': 'full', 04:39:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:39:00 INFO - 'SHELL': '/bin/bash', 04:39:00 INFO - 'SHLVL': '1', 04:39:00 INFO - 'TERM': 'linux', 04:39:00 INFO - 'TMOUT': '86400', 04:39:00 INFO - 'USER': 'cltbld', 04:39:00 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239', 04:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:00 INFO - '_': '/tools/buildbot/bin/python'} 04:39:00 INFO - TEST-INFO | started process 15503 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEZYoGV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:39:01 INFO - PID 15503 | 04:39:01 INFO - PID 15503 | (/builds/slave/test/build/application/firefox/firefox:15553): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:39:01 INFO - PID 15503 | 04:39:11 INFO - PID 15503 | RSS: Main: 144965632 04:39:11 INFO - PID 15503 | 04:39:14 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42653/tests/kraken/driver.html?testName=audio-beat-detection) 04:39:15 INFO - PID 15503 | RSS: Main: 151535616 04:39:15 INFO - PID 15503 | 04:39:17 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42653/tests/kraken/driver.html?testName=audio-dft) 04:39:18 INFO - PID 15503 | RSS: Main: 152301568 04:39:18 INFO - PID 15503 | 04:39:21 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42653/tests/kraken/driver.html?testName=audio-fft) 04:39:21 INFO - PID 15503 | RSS: Main: 148430848 04:39:21 INFO - PID 15503 | 04:39:23 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42653/tests/kraken/driver.html?testName=audio-oscillator) 04:39:24 INFO - PID 15503 | RSS: Main: 148869120 04:39:24 INFO - PID 15503 | 04:39:26 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42653/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:39:27 INFO - PID 15503 | RSS: Main: 148410368 04:39:27 INFO - PID 15503 | 04:39:30 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42653/tests/kraken/driver.html?testName=imaging-darkroom) 04:39:30 INFO - PID 15503 | RSS: Main: 148344832 04:39:30 INFO - PID 15503 | 04:39:34 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42653/tests/kraken/driver.html?testName=imaging-desaturate) 04:39:35 INFO - PID 15503 | RSS: Main: 149635072 04:39:35 INFO - PID 15503 | 04:39:38 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42653/tests/kraken/driver.html?testName=json-parse-financial) 04:39:38 INFO - PID 15503 | RSS: Main: 151527424 04:39:38 INFO - PID 15503 | 04:39:40 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42653/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:39:40 INFO - PID 15503 | RSS: Main: 151298048 04:39:40 INFO - PID 15503 | 04:39:42 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42653/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:39:42 INFO - PID 15503 | RSS: Main: 151539712 04:39:42 INFO - PID 15503 | 04:39:44 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42653/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:39:45 INFO - PID 15503 | RSS: Main: 148578304 04:39:45 INFO - PID 15503 | 04:39:48 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42653/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:39:48 INFO - PID 15503 | RSS: Main: 148516864 04:39:48 INFO - PID 15503 | 04:39:51 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42653/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:39:52 INFO - PID 15503 | RSS: Main: 149204992 04:39:52 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | Cycle 1(1): loaded http://localhost:42653/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42653/tests/kraken/driver.html?testName=ai-astar) 04:39:53 INFO - PID 15503 | RSS: Main: 150454272 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | __start_tp_report 04:39:53 INFO - PID 15503 | _x_x_mozilla_page_load 04:39:53 INFO - PID 15503 | _x_x_mozilla_page_load_details 04:39:53 INFO - PID 15503 | |i|pagename|runs| 04:39:53 INFO - PID 15503 | |0;ai-astar;106;104;105;106;104;106;106;106;105;106 04:39:53 INFO - PID 15503 | |1;audio-beat-detection;113;111;116;148;116;114;117;113;139;111 04:39:53 INFO - PID 15503 | |2;audio-dft;162;162;164;163;162;160;162;164;165;174 04:39:53 INFO - PID 15503 | |3;audio-fft;73;73;74;73;79;74;73;75;74;73 04:39:53 INFO - PID 15503 | |4;audio-oscillator;80;79;79;84;78;78;78;79;78;80 04:39:53 INFO - PID 15503 | |5;imaging-gaussian-blur;114;113;114;113;114;115;111;115;116;114 04:39:53 INFO - PID 15503 | |6;imaging-darkroom;150;150;150;150;151;157;154;150;151;150 04:39:53 INFO - PID 15503 | |7;imaging-desaturate;95;96;95;96;101;95;94;96;95;96 04:39:53 INFO - PID 15503 | |8;json-parse-financial;44;44;45;45;46;44;46;45;44;44 04:39:53 INFO - PID 15503 | |9;json-stringify-tinderbox;37;37;37;37;37;38;37;37;38;38 04:39:53 INFO - PID 15503 | |10;stanford-crypto-aes;75;72;72;71;78;75;78;71;73;72 04:39:53 INFO - PID 15503 | |11;stanford-crypto-ccm;110;105;105;106;109;112;111;113;109;117 04:39:53 INFO - PID 15503 | |12;stanford-crypto-pbkdf2;159;157;150;157;163;150;152;166;148;147 04:39:53 INFO - PID 15503 | |13;stanford-crypto-sha256-iterative;64;60;58;68;59;60;61;59;59;58 04:39:53 INFO - PID 15503 | __end_tp_report 04:39:53 INFO - PID 15503 | __start_cc_report 04:39:53 INFO - PID 15503 | _x_x_mozilla_cycle_collect,696 04:39:53 INFO - PID 15503 | __end_cc_report 04:39:53 INFO - PID 15503 | __startTimestamp1506512393969__endTimestamp 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | ------- Summary: start ------- 04:39:53 INFO - PID 15503 | Number of tests: 14 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#0] ai-astar Cycles:10 Average:105.40 Median:106.00 stddev:0.84 (0.8%) stddev-sans-first:0.87 04:39:53 INFO - PID 15503 | Values: 106.0 104.0 105.0 106.0 104.0 106.0 106.0 106.0 105.0 106.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#1] audio-beat-detection Cycles:10 Average:119.80 Median:116.00 stddev:12.83 (11.1%) stddev-sans-first:13.37 04:39:53 INFO - PID 15503 | Values: 113.0 111.0 116.0 148.0 116.0 114.0 117.0 113.0 139.0 111.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.50 stddev:3.85 (2.4%) stddev-sans-first:4.03 04:39:53 INFO - PID 15503 | Values: 162.0 162.0 164.0 163.0 162.0 160.0 162.0 164.0 165.0 174.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#3] audio-fft Cycles:10 Average:74.10 Median:74.00 stddev:1.85 (2.5%) stddev-sans-first:1.92 04:39:53 INFO - PID 15503 | Values: 73.0 73.0 74.0 73.0 79.0 74.0 73.0 75.0 74.0 73.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:79.00 stddev:1.83 (2.3%) stddev-sans-first:1.92 04:39:53 INFO - PID 15503 | Values: 80.0 79.0 79.0 84.0 78.0 78.0 78.0 79.0 78.0 80.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:1.37 (1.2%) stddev-sans-first:1.45 04:39:53 INFO - PID 15503 | Values: 114.0 113.0 114.0 113.0 114.0 115.0 111.0 115.0 116.0 114.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:150.50 stddev:2.36 (1.6%) stddev-sans-first:2.46 04:39:53 INFO - PID 15503 | Values: 150.0 150.0 150.0 150.0 151.0 157.0 154.0 150.0 151.0 150.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:95.50 stddev:1.91 (2.0%) stddev-sans-first:2.00 04:39:53 INFO - PID 15503 | Values: 95.0 96.0 95.0 96.0 101.0 95.0 94.0 96.0 95.0 96.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#8] json-parse-financial Cycles:10 Average:44.70 Median:45.00 stddev:0.82 (1.8%) stddev-sans-first:0.83 04:39:53 INFO - PID 15503 | Values: 44.0 44.0 45.0 45.0 46.0 44.0 46.0 45.0 44.0 44.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#9] json-stringify-tinderbox Cycles:10 Average:37.30 Median:37.00 stddev:0.48 (1.3%) stddev-sans-first:0.50 04:39:53 INFO - PID 15503 | Values: 37.0 37.0 37.0 37.0 37.0 38.0 37.0 37.0 38.0 38.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#10] stanford-crypto-aes Cycles:10 Average:73.70 Median:74.00 stddev:2.67 (3.6%) stddev-sans-first:2.79 04:39:53 INFO - PID 15503 | Values: 75.0 72.0 72.0 71.0 78.0 75.0 78.0 71.0 73.0 72.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#11] stanford-crypto-ccm Cycles:10 Average:109.70 Median:110.50 stddev:3.80 (3.4%) stddev-sans-first:4.03 04:39:53 INFO - PID 15503 | Values: 110.0 105.0 105.0 106.0 109.0 112.0 111.0 113.0 109.0 117.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.90 Median:157.00 stddev:6.51 (4.1%) stddev-sans-first:6.73 04:39:53 INFO - PID 15503 | Values: 159.0 157.0 150.0 157.0 163.0 150.0 152.0 166.0 148.0 147.0 04:39:53 INFO - PID 15503 | 04:39:53 INFO - PID 15503 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.60 Median:60.00 stddev:3.13 (5.2%) stddev-sans-first:3.07 04:39:53 INFO - PID 15503 | Values: 64.0 60.0 58.0 68.0 59.0 60.0 61.0 59.0 59.0 58.0 04:39:53 INFO - PID 15503 | -------- Summary: end -------- 04:39:53 INFO - PID 15503 | 04:39:54 INFO - PID 15503 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:39:54 INFO - TEST-INFO | 15503: exit 0 04:39:54 INFO - TEST-OK | kraken | took 59267ms 04:39:54 INFO - SUITE-END | took 1166s 04:39:55 INFO - Completed test suite (00:19:27) 04:39:55 INFO - javascript benchmark 04:39:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9206.053138260972, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3882.9417558736623, 3897.551781545123, 3900.609939006099, 3907.7459965102285, 3945.0531872350625, 6275.6548389838545, 6282.465990390822, 6320.4943604511645, 6354.459870910972, 6437.581557198779, 6344.6827658617, 6348.365163483661, 6441.581297215677, 6447.90328145077, 6476.967615161918, 5626.9437305626825, 5691.430856914317, 5710.800121995731, 5782.277215348082, 5806.7096645167785, 172.56650498599103, 5675.5459563234845, 5717.428257174291, 5752.252207213057, 5762.164486149506, 5590.2732644756325, 5591.049521581341, 5604.243427137325, 5611.410801865806, 5676.9497642936185, 5566.582506312043, 5607.775688972477, 5667.3482549506925, 5676.886462270772, 5690.4025077367105, 5581.358143813432, 5599.104143337087, 5652.152177173457, 5663.575231857586, 5667.914981275284, 6201.16284301618, 6207.410296021871, 6209.565330426827, 6270.937290627081, 6271.749130034793, 6734.326567343275, 6744.460443164535, 6781.694823732944, 6821.3519715626935, 6845.623490708009, 6876.896846547305, 6901.861962760767, 6969.26822683616, 6972.686229119702, 6972.825679358057, 6316.494680425555, 6323.525735569805, 6490.707918143695, 6502.154719886385, 6520.282768895465, 7097.361237488651, 7171.49799514029, 7200.387967022757, 7203.531770434905, 7203.89194162088, 6773.254941956381, 6829.829254268584, 6836.965815170893, 6901.896471552931, 6919.411849992807, 6849.965750171317, 6964.825879352956, 6969.30306969299, 6978.720851165998, 6980.616066116412, 6740.865182696417, 6835.043093966749, 6839.897401538981, 6864.24493305736, 6886.724531018703, 6707.463402927754, 6791.2869148739655, 6797.28628494001, 6799.354061364163, 6806.081179040865, 4864.805407783649, 4922.409310882717, 4996.950030499721, 5003.199488081889, 5007.349044624249, 4448.1770872388715, 4489.304157855538, 4498.055408364215, 4498.910021799546, 4500.729956202638, 6160.445559899541, 6282.717277722513, 6283.120363149163, 6288.9056664150075, 6301.8109456716375, 6873.828154296182, 6946.826329341807, 6955.374016338554, 6975.965120174468, 7006.264342244093, 6296.464800491918, 6297.6536290503545, 6365.0, 6372.2671892732005, 6385.297617262097, 5566.777328906879, 5576.302962129734, 5624.831255062355, 5638.379778224474, 5638.830835074954, 7088.716451341992, 7110.466714996396, 7130.03744494497, 7143.285671432919, 7145.92854071463, 6765.424938880054, 6942.305769423017, 6944.097267355211, 6951.5481493703755, 6951.991961165602, 7028.543144695578, 7039.54242974205, 7122.5726456412785, 7133.714651413885, 7161.785146445615, 6652.135222421054, 6804.55770374924, 6814.420774234048, 6826.283240259701, 6832.795016149524, 6871.896921546081, 6920.169579650475, 6935.965320173367, 6959.582425054513, 6965.686544105629, 6569.605823650405, 6633.834154146185, 6648.933510664831, 6649.966750166218, 6654.268030516736, 6544.6727663617585, 6548.901766473411, 6579.539432239889, 6653.168353955755, 6664.5334826561675, 6452.612843229421, 6469.353064693493, 6545.050967609765, 6549.672516374067, 6583.967080164569, 6732.293109223657, 6742.831429214113, 6773.491988100814, 6790.558613690095, 6826.419754320938, 6385.201849768779, 6391.296957334596, 6477.77327793525, 6478.967605161944, 6484.092227088117], "value": 6221.056454645221, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [947.9241660667143, 980.7548112971756, 1006.6476733143396, 1007.531497853498, 1015.0306457333245, 43848.0, 44514.7774261129, 44697.329540056926, 44705.552944470546, 44717.77641111794, 41999.370009449885, 45485.545144548545, 46100.53899461004, 46228.306575401315, 46276.76861615696, 8239.835203295916, 8329.50022998619, 8362.414630975834, 8377.49735015901, 8380.287675547586, 8252.628631711557, 8469.915300846975, 8518.105598912118, 8525.403221774479, 8529.74410767678, 8519.233269005788, 8538.0, 8547.401681882271, 8563.400561960665, 8567.614457349404, 8431.28334091603, 8451.23938845507, 8510.276626486788, 8517.276031537327, 8535.914640853605, 8222.301104406133, 8243.546604936726, 8293.668253269861, 8308.086110527836, 8336.0, 41737.791311043555, 41798.582014179774, 42205.57794422078, 42350.0, 42433.78783106065, 3708.7589306694977, 3745.494358261627, 3749.0, 3757.511523501955, 3779.527559055118, 33857.0, 33997.660023399694, 34007.829960850286, 34190.0, 34275.14312142191, 5108.897822043576, 5269.6311258211945, 5300.0, 5346.812861549866, 5398.433164517748, 230463.0, 230885.0, 231149.9999999983, 231228.0, 231588.0, 52192.47807521952, 52678.10270435163, 52852.20721689178, 52894.73552632289, 52944.47055529473, 55817.0, 56030.0, 56218.0, 56334.43665563374, 56358.7182064087, 15730.29213685364, 15730.370785168465, 15739.0, 15786.0, 16794.58013549671, 12398.75202495945, 12406.62780116598, 12417.068719846047, 12421.068419868547, 12423.937880310721, 14351.712965740835, 14759.335829887681, 14788.408463661548, 14807.333669984877, 14991.0, 5503.724813759376, 5563.721813909288, 5571.860703482445, 5644.407337229614, 5694.572907031907, 1078.6710053433778, 1082.5128692088592, 1091.8143915534379, 1098.4727330881221, 1113.693734223079, 25240.0, 25603.61594576083, 25898.611520827202, 26006.609900851123, 26164.084257050912, 1169.0325659072025, 1172.9120315976336, 1178.752461982976, 1186.5194596188558, 1188.8751681073363, 13226.272555009375, 13556.864431355758, 13588.796168057486, 13778.65553361174, 13828.170309781446, 290516.0, 291355.0, 291759.0, 292698.0, 293003.0, 58544.70727646335, 58861.117083243786, 63112.36887631065, 63906.0, 64130.67934660297, 1518.825335086457, 1536.1934984133195, 1542.591213328469, 1545.8376870428442, 1557.6573153906122, 30264.84867575648, 30970.070897873098, 31594.52608210833, 31806.522902156023, 32183.0, 42276.1544769109, 42315.57684423116, 42387.78806106073, 42401.57598423976, 42475.36286955636, 253228.0, 253349.0, 253653.0, 254842.0, 254962.0, 35253.0, 36185.27629447449, 36407.817960910026, 36903.0, 36992.81503592573, 45523.544764553255, 47284.52715472801, 47289.763551182026, 47647.28529072136, 47802.0, 34844.65155348414, 35376.29247415089, 35411.46882796709, 35506.0, 35671.82164089163, 34903.82548087345, 35018.0, 35345.823270883484, 35421.0, 35497.64502355047, 41363.79318103511, 41690.1661966765, 41707.582924171584, 42025.15949680928, 42336.78831605822, 33972.83013584916, 34102.317953641286, 34312.656873431944, 34400.0, 34416.0, 25490.34312769664, 26083.73916260813, 26120.8693956529, 26468.735312646626, 26797.0], "value": 18908.88288653595, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.3704147686401, 607.5812976699407, 618.5237367227232, 627.6234259444333, 647.5014239035943, 22821.771782282172, 23300.417489562748, 24282.392940176505, 24302.87848560759, 24411.389715257126, 25714.6142807858, 26235.606465903027, 26249.212523624275, 26265.47469050618, 26266.737332626668, 6533.8366540836505, 6625.867482650344, 6671.933280667204, 6687.063811066455, 6691.565048271871, 6675.66621668917, 6930.688119034656, 6983.057287266231, 7005.579665220078, 7009.894851577231, 6926.792196234121, 6945.409640180591, 6946.930530694679, 6952.895706564405, 6953.096097507317, 6637.701303441357, 6849.623270720109, 6927.411170050552, 6974.232834388214, 6995.790126296169, 6489.253735820394, 6519.413252807222, 6525.543211975164, 6525.902111468332, 6546.345365463416, 29598.408031839244, 30129.096127116223, 30185.849070754724, 30192.396152077054, 30208.8489557553, 2204.2175027865037, 2205.228170140454, 2242.9102835886547, 2252.4594097416502, 2253.5943530164564, 18856.34002809895, 19122.0, 19215.135318910543, 19269.80730192708, 19344.0, 2575.961360579593, 2583.7287084856193, 2631.158029430583, 2655.3494393873625, 2661.3479697474245, 139846.0, 151690.0, 160060.0, 162783.0, 162843.0, 15871.841281587267, 16666.166691665367, 16882.15589220534, 16990.0, 17269.740953885703, 26486.0, 26508.204753857415, 26646.0, 26661.46677066448, 26700.465990680466, 10796.244262901522, 10848.566057357617, 10852.294600850919, 10893.727656808485, 10988.945055274673, 9826.4104153751, 9889.258305627107, 9924.60301587943, 9967.750806229757, 10121.898781012243, 9523.714288571355, 9713.805723885484, 9744.220462362993, 9818.705438836847, 10147.289689721649, 2960.8237945110327, 2971.494845876163, 2980.6423229212633, 3006.428778532072, 3025.3495498467937, 1033.7105610429091, 1043.8956104389652, 1051.153821173947, 1056.3186744549723, 1059.4067322299536, 21416.357509274436, 21641.458963526038, 21794.0, 21881.12475500994, 22067.11731530721, 1093.6227001684565, 1110.0, 1121.0975164992096, 1124.9887501125047, 1130.7060164357326, 11676.0, 12063.155579109378, 12074.69813254658, 12077.818832717516, 12157.574484892986, 601502.0, 609300.0, 614453.0, 616755.0, 627785.0, 129470.0, 133811.0, 137841.0, 138007.0, 138503.0, 1111.766529028913, 1158.0, 1195.6114262864603, 1214.2714371377103, 1259.9622011339675, 21810.909454526893, 22234.332970010924, 22316.553668926204, 22345.217917372815, 22355.217567385065, 61476.69261653663, 61687.0, 62476.687616563446, 63674.681626593425, 64254.0, 77311.0, 77696.61151694205, 78046.21953780389, 78293.60853195697, 79438.6028069856, 15847.0, 15905.125218113115, 16040.43858464948, 16196.271167797242, 16225.0, 24514.14200502974, 24585.385365365295, 24842.25473235806, 24910.37724056913, 25042.749572504043, 19857.205711771367, 20014.89918054521, 20062.899685501478, 20221.6966745496, 20311.79688203099, 18070.728939065662, 18144.274229030692, 18373.448796536126, 18490.63018739591, 18559.81440185581, 20472.897635511727, 20512.38462846117, 20560.0, 20587.79412205859, 20723.171073156904, 15819.762703559685, 15831.129287888816, 15887.920560397586, 15930.920345398663, 15979.201039948188, 19547.0, 19585.70621440708, 19700.802991969897, 19768.110435030747, 19857.900710496357], "value": 13002.90719058195, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.3738723655208, 1464.6850927050682, 1472.4257539559567, 1480.178500931983, 1488.203810961136, 16740.414085506993, 17459.952402855837, 17461.912690436548, 17483.475495735118, 17505.124743762826, 17257.654846903053, 17370.913145434286, 17383.82616173838, 17385.73921391177, 17400.564985875328, 2819.746222839944, 2944.175630823373, 2945.145907686779, 2947.3515826518237, 2952.793304468688, 2869.34005178809, 2917.868695908689, 2917.9562306565417, 2920.4305160493714, 2922.839243841588, 2808.241774720824, 2884.048264072851, 2899.246195989037, 2903.5499497577907, 2909.6508418989647, 2918.9270268243254, 2922.810017348876, 2954.852257387133, 2957.2311199088176, 2961.7778666599984, 2866.3264132928743, 2898.2029941765973, 2928.5314349703945, 2935.442265969459, 2946.454905842426, 16386.26261818221, 16731.247093880684, 16741.66516669672, 16742.665146696996, 16790.83209167905, 8773.736787896374, 9021.594028268744, 9029.683961061397, 9035.864462033074, 9042.186203241674, 5571.749271282802, 5600.355959064719, 5737.827865164052, 5773.278340207474, 5815.621984571032, 2155.2380437825846, 2162.8702277863376, 2196.9231076912233, 2226.7884550967633, 2235.1394713035434, 30948.690513095033, 31226.843865780753, 31253.843730781202, 31371.84314078438, 31513.84243078793, 7437.293457121565, 7460.403167746567, 7463.104427468738, 7498.4001279897475, 7677.539347639159, 20339.8983005084, 20365.08357123933, 20395.082221300076, 20458.283960061326, 20509.487262818548, 8479.745607631781, 8681.0, 8758.649654013896, 8824.8676269856, 8883.955580222058, 7734.651940662684, 7863.488873223222, 7870.055593328837, 7894.565798881002, 7934.444588878723, 6015.157877897098, 6174.073888916699, 6179.721912513947, 6232.532560058014, 6299.181106456132, 1798.307651554135, 1831.09360866372, 1870.289290069792, 1924.2771478358247, 1955.217912834849, 933.4679232837353, 974.3520558828442, 977.2182254196614, 978.5254151736402, 986.6250824686573, 9008.63965441388, 9088.54557272147, 9127.543622818832, 9300.11648893354, 9560.0, 855.2815634866741, 890.8995997912032, 1054.2883553601441, 1054.9050585447346, 1062.3678911047914, 5325.920111198335, 5804.390538993429, 5856.765729370863, 5872.823815285548, 5950.9107363389585, 23589.764102358757, 24511.142110026063, 24661.38346541351, 24732.876335618566, 24750.0, 24466.877665611915, 24472.265832025067, 24694.13570525059, 24738.0, 24747.62878556823, 12102.152849300464, 12134.453949572291, 12165.391730413621, 12231.571894983634, 12258.14193006481, 9048.90951090507, 9071.863922041306, 9087.909120908706, 9166.862497062682, 9175.862362064709, 20415.59168816645, 20607.278745243846, 21164.259250926145, 21389.25137620176, 21592.0, 21758.564828703653, 21785.4553636154, 22033.245844269466, 22066.668999965335, 22363.0, 13440.798388024383, 13799.86200138026, 13812.86187138156, 13826.101303415246, 13827.0, 15269.160196189316, 15274.618134546725, 15330.616734581725, 15392.153431561372, 15506.0, 11829.76340473203, 12041.277523348626, 12098.09264305163, 12353.0, 12493.93753031229, 12305.0, 12486.687832804253, 12644.0, 12760.29818359999, 12868.485260589472, 10143.0, 10304.587816487257, 10318.329308594917, 10319.896801031893, 10324.0, 7978.3218426434405, 8042.517448953082, 8060.919390806017, 8143.42995990275, 8191.631376588187, 7667.88498172539, 7710.074791025113, 7783.805404864924, 7840.803979900548, 7914.881276780968], "value": 7421.931370534791, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.5018483364971, 571.9828405147846, 575.2435547255361, 585.853536615846, 591.7455494137521, 6259.4977205470705, 6268.623882567043, 6294.43350098491, 6294.464970477515, 6367.458766004896, 6175.197224360838, 6175.413335733109, 6185.381461853824, 6229.314775374705, 6243.375662433755, 4872.707637541741, 4963.578095861856, 5082.593392528607, 5098.476254254131, 5100.642954993152, 4972.900541989158, 4986.526280003394, 5001.874953126165, 5005.5233706056615, 5009.148444764381, 4945.307656928033, 4952.405711314647, 4955.08330958772, 4964.429090654585, 4969.285596469213, 4944.60443164546, 4952.0, 4958.628102892298, 4972.5027497249985, 4975.407869481767, 4505.535700897189, 4535.682502224846, 4539.931901021488, 4560.589546940758, 4572.725636461823, 6167.814965551041, 6172.629642221436, 6178.165947597062, 6200.658963756991, 6208.441240288395, 5711.428857114297, 5737.483626473596, 5783.132530120515, 5787.71061446926, 5820.592558520905, 6505.316941721146, 6510.479161667055, 6517.608943463408, 6544.312847151028, 6549.67251637421, 4831.782569784333, 5005.57452616528, 5006.5744411725045, 5013.623978201613, 5022.924656130161, 6592.0, 6659.733610655568, 6681.832954176136, 6798.2182049064, 6828.453723702092, 5108.514691104339, 5162.328897243335, 5163.509466600667, 5167.199084141964, 5172.663776854492, 5282.181261904411, 5284.762185701653, 5291.603129765283, 5295.602829787781, 5299.920501192485, 5837.803250333672, 5847.298324201123, 5848.561357898089, 5849.3858144894175, 5862.736176872051, 6467.288598254188, 6512.706928188243, 6558.1802274715665, 6561.901571476432, 6584.86830263392, 1785.81248968859, 1808.5569035586366, 1811.7101263797729, 1816.1554876982266, 1816.4096668582763, 1238.5417395563518, 1252.8371311729418, 1271.319843883518, 1272.5355245335386, 1293.805929110641, 5972.343042265347, 6029.909551356734, 6092.7562897484495, 6100.901837669262, 6114.052321890114, 6682.83292917681, 6695.397414232742, 6699.665016749143, 6711.3288671132495, 6751.3586209311015, 5945.791897283574, 5964.284285885721, 6088.878222435526, 6089.665068421191, 6120.816375508742, 3545.858165673396, 3552.2540266544315, 3573.2317551726505, 3585.0, 3594.5506811648543, 6806.8298292542095, 6946.51374403797, 6973.546719463218, 6975.686094125776, 6997.1253593300835, 6869.553479023847, 6884.827879302958, 6890.311873591002, 6920.8961865572055, 6937.79186624392, 6817.795466136024, 6832.795016149424, 6834.726610935507, 6845.315468453115, 6919.861602768017, 6618.569792963442, 6726.764563240263, 6726.8654626908165, 6756.966215169089, 6776.848765324148, 6541.182352205974, 6617.702203400762, 6643.435307998874, 6683.365080317362, 6703.966480167568, 6753.561018533779, 6769.0, 6819.761308354184, 6864.176298844171, 6877.65611719422, 5548.944510555004, 5565.220869078414, 5567.5545956322585, 5601.0, 5618.213450116905, 6509.153810004669, 6700.128983232149, 6735.360140786617, 6740.0, 6766.0, 6255.53083518747, 6488.935110648833, 6519.934800651933, 6584.604923704593, 6600.405963463311, 6410.583312084699, 6425.614463132227, 6484.870302593828, 6488.805335839932, 6537.28089910119, 6309.652969086561, 6373.5857169283845, 6393.328700486568, 6410.4871610272, 6444.258910225384, 6354.332795056453, 6412.871742565216, 6433.9678301610065, 6503.674816259263, 6651.667416629246, 6262.686865656608, 6283.937160628335, 6309.810705678837, 6319.96840015797, 6404.679766011774], "value": 5149.216467758032, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [969.3699095587863, 969.7284760267125, 982.7985263021081, 986.9309148359617, 988.74786929333, 20436.284730034433, 20960.580788384224, 20999.790002099973, 21049.684254736152, 21289.893550532244, 19507.902460487712, 19580.41258762236, 19647.31234406796, 19688.409347719593, 19733.605327893434, 1027.7944411117787, 1045.158647288936, 1047.6490375724104, 1048.182417714184, 1051.6582110813977, 1061.5010944855906, 1070.6306324318098, 1072.635303996641, 1074.2211896375104, 1074.8280275155976, 1062.288266861201, 1113.6881673131536, 1117.8155604325277, 1120.1430905357408, 1123.8988491035805, 1108.866935967685, 1109.2401704832175, 1111.5109351885226, 1111.6164923101599, 1113.6325012745815, 811.4238890387832, 812.3907069697726, 815.2825513548099, 815.2866242038217, 815.6329651656719, 21292.467688307763, 21314.680279795815, 21388.41821987435, 21453.463663408384, 21458.356249312546, 23633.172838950555, 24162.154324598556, 24327.391815204584, 24330.878345608337, 24340.756592434027, 18579.628407431777, 18734.531636709056, 18948.14733337003, 18962.81037189624, 18971.0, 3823.9044023899346, 3929.862454814068, 3935.193285376491, 3939.2909276330247, 3957.426173204898, 126753.0, 142488.0, 156955.0, 160256.0, 162410.0, 18821.435356939313, 18858.81141188598, 19290.0, 19297.807021929602, 19347.709784352963, 30464.69535304663, 30476.847615762224, 31236.0, 31576.0, 31619.683803162134, 10143.23925705575, 10279.94860025695, 10303.896961030294, 10316.071553560065, 10357.067863892285, 4874.951250487521, 4969.826056088092, 4978.203487442064, 4983.302337672729, 4984.177610694195, 5911.935851546763, 6008.369121242206, 6044.879102418015, 6077.969610151921, 6090.878182436327, 1278.2586100061942, 1287.266258232817, 1306.4643496166716, 1308.2216081431534, 1308.941097650608, 712.7042277454901, 714.9928500715031, 723.7105157936867, 732.3152852083319, 747.704656660616, 12075.57735479254, 12115.818262726067, 12545.372731363395, 12900.677483062811, 13177.604671859859, 720.1250480666016, 723.5984028863926, 730.8757511223105, 731.6122455098806, 742.5173637135905, 5554.666719996732, 5568.220449137124, 5594.244776955059, 5652.463016013472, 5701.857453563694, 150847.0, 151281.0, 153179.0, 154124.0, 154737.45262547454, 130701.0, 133889.33055334855, 135193.6480635201, 137025.0, 137582.0, 109173.0, 111122.44438777755, 113149.43425282821, 113170.0, 114526.42736786263, 13863.514776982756, 14374.137551746511, 14430.206338651475, 14517.854821452072, 14537.34581943794, 52223.0, 53408.73295633497, 54124.729376352865, 54275.72862135664, 54891.45108549023, 67525.32474675387, 93292.0, 95925.52037239769, 96948.0, 97715.51142244243, 21587.46031349166, 21648.0, 21657.350279491642, 21668.783312166677, 21857.12571497186, 21821.563568728852, 22169.0, 23695.644565331877, 23829.761702383446, 24135.63796543018, 21399.8930005349, 22680.432989175402, 23182.1886233981, 23206.76793232046, 23280.185193518144, 20082.39752807418, 20325.59348813045, 20531.28140515075, 20581.279655211994, 20686.896565517076, 20524.38426847197, 21053.684194736787, 21430.571388572454, 21580.67628985535, 21704.240351587618, 13851.86148138546, 14244.857551424353, 14365.569032929028, 14544.0, 14639.48761793332, 15404.38382464689, 16467.752983705013, 16560.337586496888, 16592.253348599585, 16597.834021660114], "value": 10413.20978577731, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1384.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 104.0, 105.0, 106.0, 104.0, 106.0, 106.0, 106.0, 105.0, 106.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 116.0, 148.0, 116.0, 114.0, 117.0, 113.0, 139.0, 111.0], "value": 119.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 164.0, 163.0, 162.0, 160.0, 162.0, 164.0, 165.0, 174.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 73.0, 79.0, 74.0, 73.0, 75.0, 74.0, 73.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 84.0, 78.0, 78.0, 78.0, 79.0, 78.0, 80.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 113.0, 114.0, 115.0, 111.0, 115.0, 116.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 150.0, 151.0, 157.0, 154.0, 150.0, 151.0, 150.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 101.0, 95.0, 94.0, 96.0, 95.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 44.0, 45.0, 45.0, 46.0, 44.0, 46.0, 45.0, 44.0, 44.0], "value": 44.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 37.0, 37.0, 38.0, 37.0, 37.0, 38.0, 38.0], "value": 37.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 72.0, 72.0, 71.0, 78.0, 75.0, 78.0, 71.0, 73.0, 72.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 105.0, 106.0, 109.0, 112.0, 111.0, 113.0, 109.0, 117.0], "value": 109.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 157.0, 150.0, 157.0, 163.0, 150.0, 152.0, 166.0, 148.0, 147.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 60.0, 58.0, 68.0, 59.0, 60.0, 61.0, 59.0, 59.0, 58.0], "value": 60.6, "unit": "score"}]}]} 04:39:55 INFO - Return code: 0 04:39:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:39:55 INFO - # TBPL SUCCESS # 04:39:55 INFO - Running post-action listener: _package_coverage_data 04:39:55 INFO - Running post-action listener: _resource_record_post_action 04:39:55 INFO - [mozharness: 2017-09-27 11:39:55.243904Z] Finished run-tests step (success) 04:39:55 INFO - Running post-run listener: _resource_record_post_run 04:39:55 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 331194368; Read time: 12; Write time: 217260 04:39:55 INFO - TinderboxPrint: CPU usage
13.1% 04:39:55 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 04:39:55 INFO - TinderboxPrint: I/O write bytes / time
331,194,368 / 217,260 04:39:55 INFO - TinderboxPrint: CPU idle
8,211.7 (86.9%) 04:39:55 INFO - TinderboxPrint: CPU user
1,186.9 (12.6%) 04:39:55 INFO - TinderboxPrint: Swap in / out
0 / 0 04:39:55 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12668928; Read time: 0; Write time: 4996 04:39:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:39:55 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 4096; Write bytes: 317911040; Read time: 12; Write time: 212264 04:39:55 INFO - Running post-run listener: _upload_blobber_files 04:39:55 INFO - Blob upload gear active. 04:39:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:39:55 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:39:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:39:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:39:56 INFO - (blobuploader) - INFO - Open directory for files ... 04:39:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:39:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:39:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:39:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:39:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:39:57 INFO - (blobuploader) - INFO - Done attempting. 04:39:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:39:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:39:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:40:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:05 INFO - (blobuploader) - INFO - Done attempting. 04:40:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:40:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:40:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:05 INFO - (blobuploader) - INFO - Done attempting. 04:40:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:40:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:40:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:06 INFO - (blobuploader) - INFO - Done attempting. 04:40:06 INFO - (blobuploader) - INFO - Iteration through files over. 04:40:06 INFO - Return code: 0 04:40:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:40:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:40:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d011dc5bd5ce586e28c0c88f113ec7410535dcb283793de8a4e910f97fcc60534eff2defd9b20b6d1f48d79231530acee5293a393a1984e8916625f8848f0fb3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4cf3032d380cdfc1d493c209d844968b5b044e01344080b4482d65e24349ef2037337ed76e22670c5bc7ff81ca2498b8c4dfcbe51ef46ce05eb5e3bdf130ddc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9819ff17a124280b517b8c43391f7f247e8b13ba8cf2002d61eb6e6c18e027b7087f955c38e693694591bede1918673a5fe02512826a30a717aece8c6dea921", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7818982ccfc544dc2f3079a60bbaee53c63196839f653a142f6235e1e57f8e232694ee220f16d02c34402700c96cd0aa2940f1a106b1f11d4ccbbecb4995c38c"} 04:40:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:40:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:40:06 INFO - Contents: 04:40:06 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d011dc5bd5ce586e28c0c88f113ec7410535dcb283793de8a4e910f97fcc60534eff2defd9b20b6d1f48d79231530acee5293a393a1984e8916625f8848f0fb3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4cf3032d380cdfc1d493c209d844968b5b044e01344080b4482d65e24349ef2037337ed76e22670c5bc7ff81ca2498b8c4dfcbe51ef46ce05eb5e3bdf130ddc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9819ff17a124280b517b8c43391f7f247e8b13ba8cf2002d61eb6e6c18e027b7087f955c38e693694591bede1918673a5fe02512826a30a717aece8c6dea921", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7818982ccfc544dc2f3079a60bbaee53c63196839f653a142f6235e1e57f8e232694ee220f16d02c34402700c96cd0aa2940f1a106b1f11d4ccbbecb4995c38c"} 04:40:06 INFO - Running post-run listener: copy_logs_to_upload_dir 04:40:06 INFO - Copying logs to upload dir... 04:40:06 INFO - mkdir: /builds/slave/test/build/upload/logs 04:40:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.654704 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-27 04:40:06.982566) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 04:40:06.986296) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239 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/d011dc5bd5ce586e28c0c88f113ec7410535dcb283793de8a4e910f97fcc60534eff2defd9b20b6d1f48d79231530acee5293a393a1984e8916625f8848f0fb3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4cf3032d380cdfc1d493c209d844968b5b044e01344080b4482d65e24349ef2037337ed76e22670c5bc7ff81ca2498b8c4dfcbe51ef46ce05eb5e3bdf130ddc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9819ff17a124280b517b8c43391f7f247e8b13ba8cf2002d61eb6e6c18e027b7087f955c38e693694591bede1918673a5fe02512826a30a717aece8c6dea921", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7818982ccfc544dc2f3079a60bbaee53c63196839f653a142f6235e1e57f8e232694ee220f16d02c34402700c96cd0aa2940f1a106b1f11d4ccbbecb4995c38c"} build_url:https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/NUZcoITBQvu1HCNBvdTH4w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d011dc5bd5ce586e28c0c88f113ec7410535dcb283793de8a4e910f97fcc60534eff2defd9b20b6d1f48d79231530acee5293a393a1984e8916625f8848f0fb3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4cf3032d380cdfc1d493c209d844968b5b044e01344080b4482d65e24349ef2037337ed76e22670c5bc7ff81ca2498b8c4dfcbe51ef46ce05eb5e3bdf130ddc", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e9819ff17a124280b517b8c43391f7f247e8b13ba8cf2002d61eb6e6c18e027b7087f955c38e693694591bede1918673a5fe02512826a30a717aece8c6dea921", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7818982ccfc544dc2f3079a60bbaee53c63196839f653a142f6235e1e57f8e232694ee220f16d02c34402700c96cd0aa2940f1a106b1f11d4ccbbecb4995c38c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 04:40:07.014827) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:40:07.015160) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1506510840.157627-1399931239 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004457 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:40:07.098612) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 04:40:07.099182) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 04:40:07.099833) ========= ========= Total master_lag: 0.20 =========