builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-026 starttime: 1506279926.07 results: success (0) revision: 877c02446745576ac97f181dd9eeec78ef605451 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.071172) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.081116) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.081559) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.099927) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.100309) ========= 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=41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-24 12:05:26-- 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% 54.1M=0s 2017-09-24 12:05:26 (54.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075898 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.198501) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.202561) ========= 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=41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020518 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.269518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.269814) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 877c02446745576ac97f181dd9eeec78ef605451 --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 877c02446745576ac97f181dd9eeec78ef605451 --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=41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-24 12:05:26,236 truncating revision to first 12 chars 2017-09-24 12:05:26,236 Setting DEBUG logging. 2017-09-24 12:05:26,236 attempt 1/10 2017-09-24 12:05:26,236 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/877c02446745?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-24 12:05:26,614 unpacking tar archive at: mozilla-central-877c02446745/testing/mozharness/ program finished with exit code 0 elapsedTime=0.672448 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.964873) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.968192) ========= script_repo_revision: 877c02446745576ac97f181dd9eeec78ef605451 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.968618) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.968874) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-24 12:05:26.982409) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-24 12:05:26.982789) ========= /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=41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:05:27 INFO - MultiFileLogger online at 20170924 12:05:27 in /builds/slave/test 12:05:27 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 12:05:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:05:27 INFO - {'append_to_log': False, 12:05:27 INFO - 'base_work_dir': '/builds/slave/test', 12:05:27 INFO - 'blob_upload_branch': 'Firefox', 12:05:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:05:27 INFO - 'branch': 'Firefox', 12:05:27 INFO - 'buildbot_json_path': 'buildprops.json', 12:05:27 INFO - 'code_coverage': False, 12:05:27 INFO - 'config_files': ('talos/linux_config.py',), 12:05:27 INFO - 'default_actions': ('clobber', 12:05:27 INFO - 'read-buildbot-config', 12:05:27 INFO - 'download-and-extract', 12:05:27 INFO - 'populate-webroot', 12:05:27 INFO - 'create-virtualenv', 12:05:27 INFO - 'install', 12:05:27 INFO - 'setup-mitmproxy', 12:05:27 INFO - 'run-tests'), 12:05:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:05:27 INFO - 'disable_ccov_upload': False, 12:05:27 INFO - 'disable_stylo': False, 12:05:27 INFO - 'download_minidump_stackwalk': True, 12:05:27 INFO - 'download_symbols': 'ondemand', 12:05:27 INFO - 'enable_stylo': False, 12:05:27 INFO - 'enable_webrender': False, 12:05:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:05:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:05:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:05:27 INFO - 'gecko_profile': False, 12:05:27 INFO - 'gecko_profile_interval': 0, 12:05:27 INFO - 'installer_path': 'installer.exe', 12:05:27 INFO - 'log_level': 'info', 12:05:27 INFO - 'log_name': 'talos', 12:05:27 INFO - 'log_to_console': True, 12:05:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:05:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:05:27 INFO - 'opt_config_files': (), 12:05:27 INFO - 'pip_index': False, 12:05:27 INFO - 'suite': 'dromaeojs-e10s', 12:05:27 INFO - 'system_bits': '32', 12:05:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:05:27 INFO - 'title': 'talos-linux64-ix-026', 12:05:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:05:27 INFO - 'use_talos_json': True, 12:05:27 INFO - 'verify': 'False', 12:05:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:05:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:05:27 INFO - 'work_dir': 'build'} 12:05:27 INFO - [mozharness: 2017-09-24 19:05:27.085611Z] Running clobber step. 12:05:27 INFO - Running pre-action listener: _resource_record_pre_action 12:05:27 INFO - Running main action method: clobber 12:05:27 INFO - rmtree: /builds/slave/test/build 12:05:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:05:28 INFO - Running post-action listener: _resource_record_post_action 12:05:28 INFO - [mozharness: 2017-09-24 19:05:28.624195Z] Finished clobber step (success) 12:05:28 INFO - [mozharness: 2017-09-24 19:05:28.624294Z] Running read-buildbot-config step. 12:05:28 INFO - Running pre-action listener: _resource_record_pre_action 12:05:28 INFO - Running main action method: read_buildbot_config 12:05:28 INFO - Using buildbot properties: 12:05:28 INFO - { 12:05:28 INFO - "project": "", 12:05:28 INFO - "product": "firefox", 12:05:28 INFO - "who": "ffxbld@noreply.mozilla.org", 12:05:28 INFO - "installer_path": "public/build/target.tar.bz2", 12:05:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:05:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:05:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 12:05:28 INFO - "stage_platform": "linux64-pgo", 12:05:28 INFO - "basedir": "/builds/slave/test", 12:05:28 INFO - "buildnumber": 204, 12:05:28 INFO - "platform": "ubuntu64_hw", 12:05:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:05:28 INFO - "slavebuilddir": "test", 12:05:28 INFO - "taskId": "EugXfgqiS5CzSQnUJDPdsg", 12:05:28 INFO - "branch": "mozilla-central", 12:05:28 INFO - "script_repo_revision": "production", 12:05:28 INFO - "revision": "877c02446745576ac97f181dd9eeec78ef605451", 12:05:28 INFO - "slavename": "talos-linux64-ix-026", 12:05:28 INFO - "repo_path": "mozilla-central" 12:05:28 INFO - } 12:05:28 INFO - Finding installer, test and symbols from parent task. 12:05:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EugXfgqiS5CzSQnUJDPdsg'}, attempt #1 12:05:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EugXfgqiS5CzSQnUJDPdsg'}, attempt #1 12:05:29 INFO - Task dependencies: GuT1PCFFRDO-QtxXURDi3Q 12:05:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q'}, attempt #1 12:05:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.tar.bz2 12:05:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.test_packages.json 12:05:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.crashreporter-symbols.zip 12:05:29 INFO - Running post-action listener: _resource_record_post_action 12:05:29 INFO - [mozharness: 2017-09-24 19:05:29.978806Z] Finished read-buildbot-config step (success) 12:05:29 INFO - [mozharness: 2017-09-24 19:05:29.979019Z] Running download-and-extract step. 12:05:29 INFO - Running pre-action listener: _resource_record_pre_action 12:05:29 INFO - Running main action method: download_and_extract 12:05:29 INFO - mkdir: /builds/slave/test/build/tests 12:05:29 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')]} 12:05:29 INFO - trying https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.test_packages.json 12:05:29 INFO - Downloading https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:05:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:05:30 INFO - Downloaded 989 bytes. 12:05:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:05:30 INFO - Using the following test package requirements: 12:05:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:05:30 INFO - u'common': [u'target.common.tests.zip'], 12:05:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:05:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:05:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:05:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:05:30 INFO - u'mozbase': [u'target.common.tests.zip'], 12:05:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:05:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:05:30 INFO - u'web-platform': [u'target.common.tests.zip', 12:05:30 INFO - u'target.web-platform.tests.tar.gz'], 12:05:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:05:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:05:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.common.tests.zip 12:05:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:05:30 INFO - Fetch https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.common.tests.zip into memory 12:05:32 INFO - Content-Length response header: 55342846 12:05:32 INFO - Bytes received: 55342846 12:05:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.talos.tests.zip 12:05:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:05:42 INFO - Fetch https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.talos.tests.zip into memory 12:05:44 INFO - Content-Length response header: 14513823 12:05:44 INFO - Bytes received: 14513823 12:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:44 INFO - trying https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.tar.bz2 12:05:44 INFO - Downloading https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:05:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:05:47 INFO - Downloaded 62437120 bytes. 12:05:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.tar.bz2 12:05:47 INFO - mkdir: /builds/slave/test/properties 12:05:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:05:47 INFO - Writing to file /builds/slave/test/properties/build_url 12:05:47 INFO - Contents: 12:05:47 INFO - build_url:https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.tar.bz2 12:05:47 INFO - Running post-action listener: _resource_record_post_action 12:05:47 INFO - Running post-action listener: find_tests_for_verification 12:05:47 INFO - Running post-action listener: set_extra_try_arguments 12:05:47 INFO - [mozharness: 2017-09-24 19:05:47.392699Z] Finished download-and-extract step (success) 12:05:47 INFO - [mozharness: 2017-09-24 19:05:47.393035Z] Running populate-webroot step. 12:05:47 INFO - Running pre-action listener: _resource_record_pre_action 12:05:47 INFO - Running main action method: populate_webroot 12:05:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:05:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['tresize', 12:05:47 INFO - 'tcanvasmark']}, 12:05:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:05:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['dromaeo_css', 12:05:47 INFO - 'kraken']}, 12:05:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:05:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:47 INFO - 'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:05:47 INFO - 'tests': ['damp', 'tps']}, 12:05:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:47 INFO - 'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['damp', 'tps']}, 12:05:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:05:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['dromaeo_dom']}, 12:05:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:05:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['basic_compositor_video', 12:05:47 INFO - 'glvideo']}, 12:05:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:05:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:05:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:47 INFO - 'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['ts_paint_webext', 12:05:47 INFO - 'tp5o_webext']}, 12:05:47 INFO - 'other-e10s': {'tests': ['a11yr', 12:05:47 INFO - 'ts_paint', 12:05:47 INFO - 'tpaint', 12:05:47 INFO - 'sessionrestore', 12:05:47 INFO - 'sessionrestore_many_windows', 12:05:47 INFO - 'sessionrestore_no_auto_restore', 12:05:47 INFO - 'tabpaint']}, 12:05:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['a11yr', 12:05:47 INFO - 'ts_paint', 12:05:47 INFO - 'tpaint', 12:05:47 INFO - 'sessionrestore', 12:05:47 INFO - 'sessionrestore_many_windows', 12:05:47 INFO - 'sessionrestore_no_auto_restore', 12:05:47 INFO - 'tabpaint']}, 12:05:47 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 12:05:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:05:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['perf_reftest_singletons']}, 12:05:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['perf_reftest']}, 12:05:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:05:47 INFO - 'tsvgr_opacity', 12:05:47 INFO - 'tart', 12:05:47 INFO - 'tscrollx', 12:05:47 INFO - 'tsvg_static']}, 12:05:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['tsvgx', 12:05:47 INFO - 'tsvgr_opacity', 12:05:47 INFO - 'tart', 12:05:47 INFO - 'tscrollx', 12:05:47 INFO - 'tsvg_static']}, 12:05:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:05:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:47 INFO - 'talos_options': ['--disable-stylo'], 12:05:47 INFO - 'tests': ['tp5o']}, 12:05:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:05:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:05:47 INFO - 'talos_options': ['--mitmproxy', 12:05:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:05:47 INFO - '--firstNonBlankPaint'], 12:05:47 INFO - 'tests': ['tp6_google', 12:05:47 INFO - 'tp6_youtube', 12:05:47 INFO - 'tp6_amazon', 12:05:47 INFO - 'tp6_facebook']}, 12:05:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:05:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:05:47 INFO - 'talos_options': ['--disable-stylo', 12:05:47 INFO - '--mitmproxy', 12:05:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:05:47 INFO - '--firstNonBlankPaint'], 12:05:47 INFO - 'tests': ['tp6_google', 12:05:47 INFO - 'tp6_youtube', 12:05:47 INFO - 'tp6_amazon', 12:05:47 INFO - 'tp6_facebook']}, 12:05:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:05:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:05:47 INFO - 'talos_options': ['--stylo-threads=1', 12:05:47 INFO - '--mitmproxy', 12:05:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:05:47 INFO - '--firstNonBlankPaint'], 12:05:47 INFO - 'tests': ['tp6_google', 12:05:47 INFO - 'tp6_youtube', 12:05:47 INFO - 'tp6_amazon', 12:05:47 INFO - 'tp6_facebook']}, 12:05:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:05:47 INFO - 'talos_options': ['--xperf_path', 12:05:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:47 INFO - 'tests': ['tp5n']}, 12:05:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:47 INFO - 'talos_options': ['--disable-stylo', 12:05:47 INFO - '--xperf_path', 12:05:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:47 INFO - 'tests': ['tp5n']}}} 12:05:47 INFO - Running post-action listener: _resource_record_post_action 12:05:47 INFO - [mozharness: 2017-09-24 19:05:47.410723Z] Finished populate-webroot step (success) 12:05:47 INFO - [mozharness: 2017-09-24 19:05:47.410824Z] Running create-virtualenv step. 12:05:47 INFO - Running pre-action listener: _resource_record_pre_action 12:05:47 INFO - Running main action method: create_virtualenv 12:05:47 INFO - Creating virtualenv /builds/slave/test/build/venv 12:05:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:05:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:05:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:05:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:05:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:05:48 INFO - Installing distribute.............................................................................................................................................................................................done. 12:05:48 INFO - Installing pip...............done. 12:05:48 INFO - Return code: 0 12:05:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:05:48 INFO - Reading from file tmpfile_stdout 12:05:48 INFO - Output received: 12:05:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:05:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29249c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2921d60>, '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 0x2922a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2922ef0>, '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': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', '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 12:05:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:05:48 INFO - Using env: {'DISPLAY': ':0', 12:05:48 INFO - 'HOME': '/home/cltbld', 12:05:48 INFO - 'LANG': 'en_US.UTF-8', 12:05:48 INFO - 'LANGUAGE': 'en_US:en', 12:05:48 INFO - 'LOGNAME': 'cltbld', 12:05:48 INFO - 'MAIL': '/var/mail/cltbld', 12:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:48 INFO - 'MOZ_NO_REMOTE': '1', 12:05:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:48 INFO - 'NO_EM_RESTART': '1', 12:05:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:48 INFO - 'PWD': '/builds/slave/test', 12:05:48 INFO - 'SHELL': '/bin/bash', 12:05:48 INFO - 'SHLVL': '1', 12:05:48 INFO - 'TERM': 'linux', 12:05:48 INFO - 'TMOUT': '86400', 12:05:48 INFO - 'USER': 'cltbld', 12:05:48 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:48 INFO - '_': '/tools/buildbot/bin/python'} 12:05:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:05:49 INFO - Downloading/unpacking pip>=1.5 12:05:49 INFO - Running setup.py egg_info for package pip 12:05:49 INFO - warning: no files found matching 'pip/cacert.pem' 12:05:49 INFO - warning: no files found matching '*.html' under directory 'docs' 12:05:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:05:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:05:49 INFO - Installing collected packages: pip 12:05:49 INFO - Found existing installation: pip 1.1 12:05:49 INFO - Uninstalling pip: 12:05:49 INFO - Successfully uninstalled pip 12:05:49 INFO - Running setup.py install for pip 12:05:49 INFO - warning: no files found matching 'pip/cacert.pem' 12:05:49 INFO - warning: no files found matching '*.html' under directory 'docs' 12:05:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:05:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:05:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:05:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:05:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:05:49 INFO - Successfully installed pip 12:05:49 INFO - Cleaning up... 12:05:49 INFO - Return code: 0 12:05:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29249c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2921d60>, '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 0x2922a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2922ef0>, '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': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', '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 12:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:05:49 INFO - Using env: {'DISPLAY': ':0', 12:05:49 INFO - 'HOME': '/home/cltbld', 12:05:49 INFO - 'LANG': 'en_US.UTF-8', 12:05:49 INFO - 'LANGUAGE': 'en_US:en', 12:05:49 INFO - 'LOGNAME': 'cltbld', 12:05:49 INFO - 'MAIL': '/var/mail/cltbld', 12:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:49 INFO - 'MOZ_NO_REMOTE': '1', 12:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:49 INFO - 'NO_EM_RESTART': '1', 12:05:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:49 INFO - 'PWD': '/builds/slave/test', 12:05:49 INFO - 'SHELL': '/bin/bash', 12:05:49 INFO - 'SHLVL': '1', 12:05:49 INFO - 'TERM': 'linux', 12:05:49 INFO - 'TMOUT': '86400', 12:05:49 INFO - 'USER': 'cltbld', 12:05:49 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:49 INFO - '_': '/tools/buildbot/bin/python'} 12:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:49 INFO - Downloading/unpacking psutil>=3.1.1 12:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:05:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:51 INFO - Installing collected packages: psutil 12:05:51 INFO - Running setup.py install for psutil 12:05:51 INFO - building 'psutil._psutil_linux' extension 12:05:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:05:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:05:51 INFO - building 'psutil._psutil_posix' extension 12:05:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:05:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:05:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:51 INFO - Successfully installed psutil 12:05:51 INFO - Cleaning up... 12:05:51 INFO - Return code: 0 12:05:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:05: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')]} 12:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05: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')]} 12:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29249c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2921d60>, '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 0x2922a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2922ef0>, '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': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', '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 12:05:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:05:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:05:51 INFO - Using env: {'DISPLAY': ':0', 12:05:51 INFO - 'HOME': '/home/cltbld', 12:05:51 INFO - 'LANG': 'en_US.UTF-8', 12:05:51 INFO - 'LANGUAGE': 'en_US:en', 12:05:51 INFO - 'LOGNAME': 'cltbld', 12:05:51 INFO - 'MAIL': '/var/mail/cltbld', 12:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:51 INFO - 'MOZ_NO_REMOTE': '1', 12:05:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:51 INFO - 'NO_EM_RESTART': '1', 12:05:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:51 INFO - 'PWD': '/builds/slave/test', 12:05:51 INFO - 'SHELL': '/bin/bash', 12:05:51 INFO - 'SHLVL': '1', 12:05:51 INFO - 'TERM': 'linux', 12:05:51 INFO - 'TMOUT': '86400', 12:05:51 INFO - 'USER': 'cltbld', 12:05:51 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:51 INFO - '_': '/tools/buildbot/bin/python'} 12:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:05: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 12:05: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 12:05: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 12:05: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 12:05:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:05:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:05:53 INFO - Installing collected packages: mozsystemmonitor 12:05:53 INFO - Running setup.py install for mozsystemmonitor 12:05:53 INFO - Successfully installed mozsystemmonitor 12:05:53 INFO - Cleaning up... 12:05:53 INFO - Return code: 0 12:05:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:05: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')]} 12:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05: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')]} 12:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29249c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2921d60>, '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 0x2922a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2922ef0>, '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': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', '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 12:05:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:05:53 INFO - Using env: {'DISPLAY': ':0', 12:05:53 INFO - 'HOME': '/home/cltbld', 12:05:53 INFO - 'LANG': 'en_US.UTF-8', 12:05:53 INFO - 'LANGUAGE': 'en_US:en', 12:05:53 INFO - 'LOGNAME': 'cltbld', 12:05:53 INFO - 'MAIL': '/var/mail/cltbld', 12:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:53 INFO - 'MOZ_NO_REMOTE': '1', 12:05:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:53 INFO - 'NO_EM_RESTART': '1', 12:05:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:53 INFO - 'PWD': '/builds/slave/test', 12:05:53 INFO - 'SHELL': '/bin/bash', 12:05:53 INFO - 'SHLVL': '1', 12:05:53 INFO - 'TERM': 'linux', 12:05:53 INFO - 'TMOUT': '86400', 12:05:53 INFO - 'USER': 'cltbld', 12:05:53 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:53 INFO - '_': '/tools/buildbot/bin/python'} 12:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:53 INFO - Downloading/unpacking jsonschema==2.5.1 12:05: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 12:05: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 12:05: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 12:05: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 12:05:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:05:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:55 INFO - Downloading functools32-3.2.3-2.tar.gz 12:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:05:55 INFO - warning: no files found matching '*.txt' 12:05:55 INFO - no previously-included directories found matching 'build' 12:05:55 INFO - no previously-included directories found matching 'dist' 12:05:55 INFO - no previously-included directories found matching '.git*' 12:05:55 INFO - Installing collected packages: jsonschema, functools32 12:05:55 INFO - Running setup.py install for functools32 12:05:55 INFO - warning: no files found matching '*.txt' 12:05:55 INFO - no previously-included directories found matching 'build' 12:05:55 INFO - no previously-included directories found matching 'dist' 12:05:55 INFO - no previously-included directories found matching '.git*' 12:05:55 INFO - Successfully installed jsonschema functools32 12:05:55 INFO - Cleaning up... 12:05:55 INFO - Return code: 0 12:05:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:05: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')]} 12:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05: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')]} 12:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:55 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 0x25b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29249c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2921d60>, '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 0x2922a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2922ef0>, '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': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', '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 12:05:55 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 12:05:55 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 12:05:55 INFO - Using env: {'DISPLAY': ':0', 12:05:55 INFO - 'HOME': '/home/cltbld', 12:05:55 INFO - 'LANG': 'en_US.UTF-8', 12:05:55 INFO - 'LANGUAGE': 'en_US:en', 12:05:55 INFO - 'LOGNAME': 'cltbld', 12:05:55 INFO - 'MAIL': '/var/mail/cltbld', 12:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:55 INFO - 'MOZ_NO_REMOTE': '1', 12:05:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:55 INFO - 'NO_EM_RESTART': '1', 12:05:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:55 INFO - 'PWD': '/builds/slave/test', 12:05:55 INFO - 'SHELL': '/bin/bash', 12:05:55 INFO - 'SHLVL': '1', 12:05:55 INFO - 'TERM': 'linux', 12:05:55 INFO - 'TMOUT': '86400', 12:05:55 INFO - 'USER': 'cltbld', 12:05:55 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:55 INFO - '_': '/tools/buildbot/bin/python'} 12:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:05:56 INFO - Cleaning up... 12:05:56 INFO - Return code: 0 12:05:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:05: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')]} 12:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05: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')]} 12:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29249c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2921d60>, '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 0x2922a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2922ef0>, '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': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', '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 12:05:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:05:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:05:56 INFO - Using env: {'DISPLAY': ':0', 12:05:56 INFO - 'HOME': '/home/cltbld', 12:05:56 INFO - 'LANG': 'en_US.UTF-8', 12:05:56 INFO - 'LANGUAGE': 'en_US:en', 12:05:56 INFO - 'LOGNAME': 'cltbld', 12:05:56 INFO - 'MAIL': '/var/mail/cltbld', 12:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:56 INFO - 'MOZ_NO_REMOTE': '1', 12:05:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:56 INFO - 'NO_EM_RESTART': '1', 12:05:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:56 INFO - 'PWD': '/builds/slave/test', 12:05:56 INFO - 'SHELL': '/bin/bash', 12:05:56 INFO - 'SHLVL': '1', 12:05:56 INFO - 'TERM': 'linux', 12:05:56 INFO - 'TMOUT': '86400', 12:05:56 INFO - 'USER': 'cltbld', 12:05:56 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:56 INFO - '_': '/tools/buildbot/bin/python'} 12:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:56 INFO - Downloading/unpacking blobuploader==1.2.4 12:05: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 12:05: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 12:05: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 12:05: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 12:05:57 INFO - Downloading blobuploader-1.2.4.tar.gz 12:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:05:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:05: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 12:05: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 12:05: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 12:05: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 12:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:05:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:05: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 12:05: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 12:05: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 12:05: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 12:05:57 INFO - Downloading docopt-0.6.1.tar.gz 12:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:05:58 INFO - Installing collected packages: blobuploader, requests, docopt 12:05:58 INFO - Running setup.py install for blobuploader 12:05:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:05:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:05:58 INFO - Running setup.py install for requests 12:05:58 INFO - Running setup.py install for docopt 12:05:58 INFO - Successfully installed blobuploader requests docopt 12:05:58 INFO - Cleaning up... 12:05:58 INFO - Return code: 0 12:05:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05: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')]} 12:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05: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')]} 12:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29249c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2921d60>, '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 0x2922a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2922ef0>, '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': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', '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 12:05:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:05:58 INFO - Using env: {'DISPLAY': ':0', 12:05:58 INFO - 'HOME': '/home/cltbld', 12:05:58 INFO - 'LANG': 'en_US.UTF-8', 12:05:58 INFO - 'LANGUAGE': 'en_US:en', 12:05:58 INFO - 'LOGNAME': 'cltbld', 12:05:58 INFO - 'MAIL': '/var/mail/cltbld', 12:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:58 INFO - 'MOZ_NO_REMOTE': '1', 12:05:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:58 INFO - 'NO_EM_RESTART': '1', 12:05:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:58 INFO - 'PWD': '/builds/slave/test', 12:05:58 INFO - 'SHELL': '/bin/bash', 12:05:58 INFO - 'SHLVL': '1', 12:05:58 INFO - 'TERM': 'linux', 12:05:58 INFO - 'TMOUT': '86400', 12:05:58 INFO - 'USER': 'cltbld', 12:05:58 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:58 INFO - '_': '/tools/buildbot/bin/python'} 12:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:05:58 INFO - Running setup.py (path:/tmp/pip-dgHVkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:05:58 INFO - Running setup.py (path:/tmp/pip-jhEZVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:05:58 INFO - Running setup.py (path:/tmp/pip-kzRwJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:05:59 INFO - Running setup.py (path:/tmp/pip-MyOMsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:05:59 INFO - Running setup.py (path:/tmp/pip-OT4qX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:05:59 INFO - Running setup.py (path:/tmp/pip-028Qhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:05:59 INFO - Running setup.py (path:/tmp/pip-vyCIXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:05:59 INFO - Running setup.py (path:/tmp/pip-QB3Wbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:05:59 INFO - Running setup.py (path:/tmp/pip-1Nvqfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:05:59 INFO - Running setup.py (path:/tmp/pip-y6lZMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:05:59 INFO - Running setup.py (path:/tmp/pip-9fKEB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:05:59 INFO - Running setup.py (path:/tmp/pip-gS1cv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:05:59 INFO - Running setup.py (path:/tmp/pip-sVJUhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:05:59 INFO - Running setup.py (path:/tmp/pip-o3RDTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:59 INFO - Running setup.py (path:/tmp/pip-OmcJi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:05:59 INFO - Running setup.py (path:/tmp/pip-HaoSPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:05:59 INFO - Running setup.py (path:/tmp/pip-rnpMsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:06:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:06:00 INFO - Running setup.py install for manifestparser 12:06:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Running setup.py install for mozcrash 12:06:00 INFO - Running setup.py install for mozdebug 12:06:00 INFO - Running setup.py install for mozdevice 12:06:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Running setup.py install for mozfile 12:06:00 INFO - Running setup.py install for mozhttpd 12:06:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Running setup.py install for mozinfo 12:06:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Running setup.py install for mozInstall 12:06:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Running setup.py install for mozleak 12:06:00 INFO - Running setup.py install for mozlog 12:06:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Running setup.py install for moznetwork 12:06:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Running setup.py install for mozprocess 12:06:01 INFO - Running setup.py install for mozprofile 12:06:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Running setup.py install for mozrunner 12:06:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Running setup.py install for mozscreenshot 12:06:01 INFO - Running setup.py install for moztest 12:06:01 INFO - Running setup.py install for mozversion 12:06:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:06:01 INFO - Cleaning up... 12:06:01 INFO - Return code: 0 12:06:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:01 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 0x25b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29249c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2921d60>, '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 0x2922a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2922ef0>, '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': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', '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 12:06:01 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 12:06:01 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 12:06:01 INFO - Using env: {'DISPLAY': ':0', 12:06:01 INFO - 'HOME': '/home/cltbld', 12:06:01 INFO - 'LANG': 'en_US.UTF-8', 12:06:01 INFO - 'LANGUAGE': 'en_US:en', 12:06:01 INFO - 'LOGNAME': 'cltbld', 12:06:01 INFO - 'MAIL': '/var/mail/cltbld', 12:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:01 INFO - 'MOZ_NO_REMOTE': '1', 12:06:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:01 INFO - 'NO_EM_RESTART': '1', 12:06:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:01 INFO - 'PWD': '/builds/slave/test', 12:06:01 INFO - 'SHELL': '/bin/bash', 12:06:01 INFO - 'SHLVL': '1', 12:06:01 INFO - 'TERM': 'linux', 12:06:01 INFO - 'TMOUT': '86400', 12:06:01 INFO - 'USER': 'cltbld', 12:06:01 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:01 INFO - '_': '/tools/buildbot/bin/python'} 12:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:06:02 INFO - Running setup.py (path:/tmp/pip-IGyVfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:06:02 INFO - Running setup.py (path:/tmp/pip-2nqxMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:06:02 INFO - Running setup.py (path:/tmp/pip-d2w9j4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:06:02 INFO - Running setup.py (path:/tmp/pip-x615EH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:06:02 INFO - Running setup.py (path:/tmp/pip-iNhMbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:06:02 INFO - Running setup.py (path:/tmp/pip-cKD9dN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:06:02 INFO - Running setup.py (path:/tmp/pip-NwUpzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:06:02 INFO - Running setup.py (path:/tmp/pip-gbq9Co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:06:02 INFO - Running setup.py (path:/tmp/pip-NsvKjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:06:02 INFO - Running setup.py (path:/tmp/pip-qandyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:06:02 INFO - Running setup.py (path:/tmp/pip-fhgQ7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:06:02 INFO - Running setup.py (path:/tmp/pip-aGGk_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:06:02 INFO - Running setup.py (path:/tmp/pip-XfIswG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:06:02 INFO - Running setup.py (path:/tmp/pip-rneLTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:03 INFO - Running setup.py (path:/tmp/pip-rJU5s9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:06:03 INFO - Running setup.py (path:/tmp/pip-kWAJfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:06:03 INFO - Running setup.py (path:/tmp/pip-RUP7Ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:06:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:04 INFO - Downloading blessings-1.6.tar.gz 12:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:06:04 INFO - Installing collected packages: blessings 12:06:04 INFO - Running setup.py install for blessings 12:06:04 INFO - Successfully installed blessings 12:06:04 INFO - Cleaning up... 12:06:04 INFO - Return code: 0 12:06:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:06:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:06:05 INFO - Reading from file tmpfile_stdout 12:06:05 INFO - Current package versions: 12:06:05 INFO - argparse == 1.2.1 12:06:05 INFO - blessings == 1.6 12:06:05 INFO - blobuploader == 1.2.4 12:06:05 INFO - docopt == 0.6.1 12:06:05 INFO - functools32 == 3.2.3-2 12:06:05 INFO - jsonschema == 2.5.1 12:06:05 INFO - manifestparser == 1.1 12:06:05 INFO - mozInstall == 1.13 12:06:05 INFO - mozcrash == 1.0 12:06:05 INFO - mozdebug == 0.1 12:06:05 INFO - mozdevice == 0.51 12:06:05 INFO - mozfile == 1.2 12:06:05 INFO - mozhttpd == 0.7 12:06:05 INFO - mozinfo == 0.10 12:06:05 INFO - mozleak == 0.1 12:06:05 INFO - mozlog == 3.5 12:06:05 INFO - moznetwork == 0.27 12:06:05 INFO - mozprocess == 0.25 12:06:05 INFO - mozprofile == 0.28 12:06:05 INFO - mozrunner == 6.13 12:06:05 INFO - mozscreenshot == 0.1 12:06:05 INFO - mozsystemmonitor == 0.3 12:06:05 INFO - moztest == 0.8 12:06:05 INFO - mozversion == 1.4 12:06:05 INFO - psutil == 3.1.1 12:06:05 INFO - requests == 1.2.3 12:06:05 INFO - wsgiref == 0.1.2 12:06:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29249c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2921d60>, '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 0x2922a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2922ef0>, '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': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', '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 12:06:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:06:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:06:05 INFO - Using env: {'DISPLAY': ':0', 12:06:05 INFO - 'HOME': '/home/cltbld', 12:06:05 INFO - 'LANG': 'en_US.UTF-8', 12:06:05 INFO - 'LANGUAGE': 'en_US:en', 12:06:05 INFO - 'LOGNAME': 'cltbld', 12:06:05 INFO - 'MAIL': '/var/mail/cltbld', 12:06:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:05 INFO - 'MOZ_NO_REMOTE': '1', 12:06:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:05 INFO - 'NO_EM_RESTART': '1', 12:06:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:05 INFO - 'PWD': '/builds/slave/test', 12:06:05 INFO - 'SHELL': '/bin/bash', 12:06:05 INFO - 'SHLVL': '1', 12:06:05 INFO - 'TERM': 'linux', 12:06:05 INFO - 'TMOUT': '86400', 12:06:05 INFO - 'USER': 'cltbld', 12:06:05 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:06:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:05 INFO - '_': '/tools/buildbot/bin/python'} 12:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:06:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:06:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:06: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 12:06: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 12:06: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 12:06: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 12:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:06:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:06:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:06:06 INFO - Installing collected packages: simplejson 12:06:06 INFO - Running setup.py install for simplejson 12:06:06 INFO - building 'simplejson._speedups' extension 12:06:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 12:06:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 12:06:07 INFO - Successfully installed simplejson 12:06:07 INFO - Cleaning up... 12:06:07 INFO - Return code: 0 12:06:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x277fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29249c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2921d60>, '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 0x2922a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2922ef0>, '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': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', '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 12:06:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:06:07 INFO - Using env: {'DISPLAY': ':0', 12:06:07 INFO - 'HOME': '/home/cltbld', 12:06:07 INFO - 'LANG': 'en_US.UTF-8', 12:06:07 INFO - 'LANGUAGE': 'en_US:en', 12:06:07 INFO - 'LOGNAME': 'cltbld', 12:06:07 INFO - 'MAIL': '/var/mail/cltbld', 12:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:07 INFO - 'MOZ_NO_REMOTE': '1', 12:06:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:07 INFO - 'NO_EM_RESTART': '1', 12:06:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:07 INFO - 'PWD': '/builds/slave/test', 12:06:07 INFO - 'SHELL': '/bin/bash', 12:06:07 INFO - 'SHLVL': '1', 12:06:07 INFO - 'TERM': 'linux', 12:06:07 INFO - 'TMOUT': '86400', 12:06:07 INFO - 'USER': 'cltbld', 12:06:07 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:07 INFO - '_': '/tools/buildbot/bin/python'} 12:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:06:07 INFO - Cleaning up... 12:06:07 INFO - Return code: 0 12:06:07 INFO - Running post-action listener: _resource_record_post_action 12:06:07 INFO - Running post-action listener: _start_resource_monitoring 12:06:07 INFO - Starting resource monitoring. 12:06:07 INFO - [mozharness: 2017-09-24 19:06:07.738995Z] Finished create-virtualenv step (success) 12:06:07 INFO - [mozharness: 2017-09-24 19:06:07.739511Z] Running install step. 12:06:07 INFO - Running pre-action listener: _resource_record_pre_action 12:06:07 INFO - Running main action method: install 12:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:06:07 INFO - Reading from file tmpfile_stdout 12:06:07 INFO - Detecting whether we're running mozinstall >=1.0... 12:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:06:08 INFO - Reading from file tmpfile_stdout 12:06:08 INFO - Output received: 12:06:08 INFO - Usage: mozinstall [options] installer 12:06:08 INFO - Options: 12:06:08 INFO - -h, --help show this help message and exit 12:06:08 INFO - -d DEST, --destination=DEST 12:06:08 INFO - Directory to install application into. [default: 12:06:08 INFO - "/builds/slave/test"] 12:06:08 INFO - --app=APP Application being installed. [default: firefox] 12:06:08 INFO - mkdir: /builds/slave/test/build/application 12:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:06:25 INFO - Reading from file tmpfile_stdout 12:06:25 INFO - Output received: 12:06:25 INFO - /builds/slave/test/build/application/firefox/firefox 12:06:25 INFO - Running post-action listener: _resource_record_post_action 12:06:25 INFO - [mozharness: 2017-09-24 19:06:25.691662Z] Finished install step (success) 12:06:25 INFO - [mozharness: 2017-09-24 19:06:25.691808Z] Running setup-mitmproxy step. 12:06:25 INFO - Running pre-action listener: _resource_record_pre_action 12:06:25 INFO - Running main action method: setup_mitmproxy 12:06:25 INFO - Skipping: mitmproxy is not required 12:06:25 INFO - Running post-action listener: _resource_record_post_action 12:06:25 INFO - [mozharness: 2017-09-24 19:06:25.692342Z] Finished setup-mitmproxy step (success) 12:06:25 INFO - [mozharness: 2017-09-24 19:06:25.692445Z] Running run-tests step. 12:06:25 INFO - Running pre-action listener: _resource_record_pre_action 12:06:25 INFO - Running pre-action listener: _set_gcov_prefix 12:06:25 INFO - Running main action method: run_tests 12:06:25 WARNING - Try message not found. 12:06:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:06:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:06:25 INFO - Python 2.7.3 12:06:25 INFO - Return code: 0 12:06:25 INFO - grabbing minidump binary from tooltool 12:06:25 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')]} 12:06:25 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 0x2921d60>, '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 0x2922a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2922ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:06:25 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 12:06:25 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 12:06:25 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 12:06:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:06:25 INFO - Return code: 0 12:06:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:06:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:06:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:06:25 INFO - ENV: RUST_BACKTRACE is now full 12:06:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:06:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:06:25 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/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 12:06:25 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/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-026 --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 12:06:25 INFO - Using env: {'DISPLAY': ':0', 12:06:25 INFO - 'HOME': '/home/cltbld', 12:06:25 INFO - 'LANG': 'en_US.UTF-8', 12:06:25 INFO - 'LANGUAGE': 'en_US:en', 12:06:25 INFO - 'LOGNAME': 'cltbld', 12:06:25 INFO - 'MAIL': '/var/mail/cltbld', 12:06:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:06:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:25 INFO - 'MOZ_NO_REMOTE': '1', 12:06:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:06:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:25 INFO - 'NO_EM_RESTART': '1', 12:06:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:25 INFO - 'PWD': '/builds/slave/test', 12:06:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:06:25 INFO - 'RUST_BACKTRACE': 'full', 12:06:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:06:25 INFO - 'SHELL': '/bin/bash', 12:06:25 INFO - 'SHLVL': '1', 12:06:25 INFO - 'TERM': 'linux', 12:06:25 INFO - 'TMOUT': '86400', 12:06:25 INFO - 'USER': 'cltbld', 12:06:25 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:25 INFO - '_': '/tools/buildbot/bin/python'} 12:06:25 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/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 12:06:26 INFO - mozversion application_buildid: 20170924174343 12:06:26 INFO - mozversion application_changeset: 877c02446745576ac97f181dd9eeec78ef605451 12:06:26 INFO - mozversion application_display_name: Nightly 12:06:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:06:26 INFO - mozversion application_name: Firefox 12:06:26 INFO - mozversion application_remotingname: firefox 12:06:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:06:26 INFO - mozversion application_vendor: Mozilla 12:06:26 INFO - mozversion application_version: 58.0a1 12:06:26 INFO - mozversion platform_buildid: 20170924174343 12:06:26 INFO - mozversion platform_changeset: 877c02446745576ac97f181dd9eeec78ef605451 12:06:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:06:26 INFO - mozversion platform_version: 58.0a1 12:06:26 INFO - using testdate: 1506279986 12:06:26 INFO - actual date: 1506279986 12:06:26 INFO - starting webserver on 'localhost:54825' 12:06:26 INFO - SUITE-START | Running 2 tests 12:06:26 INFO - TEST-START | dromaeo_css 12:06:26 INFO - Initialising browser for dromaeo_css test... 12:06:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54825/getInfo.html -profile /tmp/tmpcS2wPp/profile 12:06:26 INFO - TEST-INFO | started process 3607 (/builds/slave/test/build/application/firefox/firefox http://localhost:54825/getInfo.html) 12:06:31 INFO - TEST-INFO | 3607: exit 0 12:06:31 INFO - Browser initialized. 12:06:31 INFO - Running cycle 1/1 for dromaeo_css test... 12:06:31 INFO - Using env: {'DISPLAY': ':0', 12:06:31 INFO - 'HOME': '/home/cltbld', 12:06:31 INFO - 'JSGC_DISABLE_POISONING': '1', 12:06:31 INFO - 'LANG': 'en_US.UTF-8', 12:06:31 INFO - 'LANGUAGE': 'en_US:en', 12:06:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:06:31 INFO - 'LOGNAME': 'cltbld', 12:06:31 INFO - 'MAIL': '/var/mail/cltbld', 12:06:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:06:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:06:31 INFO - 'MOZ_CRASHREPORTER': '1', 12:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:06:31 INFO - 'MOZ_NO_REMOTE': '1', 12:06:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:31 INFO - 'NO_EM_RESTART': '1', 12:06:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:31 INFO - 'PWD': '/builds/slave/test', 12:06:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:06:31 INFO - 'RUST_BACKTRACE': 'full', 12:06:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:06:31 INFO - 'SHELL': '/bin/bash', 12:06:31 INFO - 'SHLVL': '1', 12:06:31 INFO - 'TERM': 'linux', 12:06:31 INFO - 'TMOUT': '86400', 12:06:31 INFO - 'USER': 'cltbld', 12:06:31 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:31 INFO - '_': '/tools/buildbot/bin/python'} 12:06:31 INFO - TEST-INFO | started process 3845 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcS2wPp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:06:32 INFO - PID 3845 | 12:06:32 INFO - PID 3845 | (/builds/slave/test/build/application/firefox/firefox:3894): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:06:32 INFO - PID 3845 | 12:06:42 INFO - PID 3845 | RSS: Main: 144850944 12:06:42 INFO - PID 3845 | 12:09:40 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:54825/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54825/tests/dromaeo/cssquery-ext.html) 12:09:41 INFO - PID 3845 | RSS: Main: 146911232 12:09:41 INFO - PID 3845 | 12:12:43 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:54825/tests/dromaeo/cssquery-ext.html (next: http://localhost:54825/tests/dromaeo/cssquery-jquery.html) 12:12:44 INFO - PID 3845 | RSS: Main: 146550784 12:12:44 INFO - PID 3845 | 12:15:45 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:54825/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54825/tests/dromaeo/cssquery-mootools.html) 12:15:46 INFO - PID 3845 | RSS: Main: 148971520 12:15:46 INFO - PID 3845 | 12:18:47 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:54825/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54825/tests/dromaeo/cssquery-prototype.html) 12:18:48 INFO - PID 3845 | RSS: Main: 147369984 12:18:48 INFO - PID 3845 | 12:21:51 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:54825/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54825/tests/dromaeo/cssquery-yui.html) 12:21:52 INFO - PID 3845 | RSS: Main: 148459520 12:21:52 INFO - PID 3845 | 12:24:53 INFO - PID 3845 | Cycle 1(1): loaded http://localhost:54825/tests/dromaeo/cssquery-yui.html (next: http://localhost:54825/tests/dromaeo/cssquery-dojo.html) 12:24:53 INFO - PID 3845 | RSS: Main: 151126016 12:24:53 INFO - PID 3845 | 12:24:53 INFO - PID 3845 | __start_tp_report 12:24:53 INFO - PID 3845 | _x_x_mozilla_page_load 12:24:53 INFO - PID 3845 | _x_x_mozilla_page_load_details 12:24:53 INFO - PID 3845 | |i|pagename|runs| 12:24:53 INFO - PID 3845 | |0;dojo.html;3901.687864970803;3915.549711783145;3959.7426167299104;3971.6624086952606;3974.642282194602;6339.175907132067;6400.775972840952;6453.22561292645;6460.257070436901;6511.511636627249;6459.031145328191;6462.967685161568;6477.190351206099;6489.545731798776;6504.024396340551;5723.685197314146;5737.483626473617;5773.740181691824;5780.2196703444915;5824.825255242349;5785.450382213704;5818.7963421280265;5827.446392592697;5828.591998560102;5880.794172203974;5735.311762588495;5764.394738552433;5850.6782126983;5854.385289544601;5857.092150716646;5598.748056337475;5727.770889164429;5768.451997060272;5795.985702502087;5797.304323481167;5697.202391665171;5707.971460142673;5764.337101233328;5783.942160578383;5801.941980580183;6303.747850085991;6367.140436041122;6407.231132264158;6431;6464.353564643545;6898.551594146365;6908.1364829396325;6922.861542769167;6930.61881596512;7000.824979375506;7050.894236586455;7090.432765378809;7109.6089715065655;7118.359347658736;7122.109736282965;6488.156539649863;6521.902171467432;6544.509161812883;6593.011048342739;6595.967020164869;7039;7147.53541019832;7150.499465037501;7175.928240717631;7270.200277969419;6793.898091528532;6858.3141685832015;6877.518573699893;6881.174259088942;6953.860922781516;7046.823829404306;7067.293270672994;7075;7085.43316534676;7092.503524753321;6903.792886213421;6927.653617319113;7022.719091236293;7025.016497690327;7026.543274687129;6684.632345220963;6773.830654233585;6910.44716422685;6914.101166848278;7021.192562855234;5073.010762901273;5073.822416215415;5081.212412076134;5091.541761241506;5129.12804823181;4598.26427771555;4615.376924115269;4657.278121891112;4740.028294199681;4763.618910487153;6393.264774550986;6410.647414392253;6482.254540727782;6496.577722448026;6511.348865113546;6756.527043107034;6947.756828510978;6968.651567421609;7034.788956331318;7083.822904427328;6337.7781777637565;6354.936450635527;6403.519736019817;6406.4554512866;6425.775097871646;5636.85907852307;5660.29246344207;5664.631798933056;5704.572157088152;5707.771689132472;7209.963950180216;7214.567125972353;7229.060222171084;7311.89032164518;7322.011528443699;6882.8623427530165;6940.028396024561;6968.79093627192;6979.755708550177;6982.7206911725;7275;7319;7320.304571065741;7331.926680733124;7333.046703928455;6843.692033858588;6928.722851086101;6946.687399066953;7015.649217539;7018.649067546704;6895.103636527219;6949.513534052568;7026.543274687129;7030.367266945999;7066.258042905421;6721.966390168018;6780.423663988616;6791.04925310447;6792.320767923168;6795.524313298021;6713.731450741916;6717.328267173243;6810.6594670265295;6812.455003599799;6813.693383797642;6531.085648009377;6603.471722262304;6637.303083176197;6641.501887358564;6645.036469711961;6745.730170793114;6788.728450862108;6798.184217893884;6798.286179951034;6813.2846051163915;6522.836929076811;6610.4050635443045;6619.569727967667;6623.072769812326;6639.136912201383 12:24:53 INFO - PID 3845 | |1;ext.html;948.8292107420664;974.2108891797644;977.4721650308836;977.9608815647379;978.0170928217141;39645.2070958581;43934.780326098364;43988.78005609976;44046.11907761843;44120.3381949271;41267;41406;41475.17049659005;41516;41598.58401415985;7503.249675032494;7593.582352970584;7635.923640763604;8323.209295116954;8690.652373905035;8494.617742201584;8634.784130396727;8634.956825215864;8638.784030399258;8675.826483470359;8629;8632.309415246766;8643.870341944876;8672.392932494728;8698.260647844942;8637.265832404253;8662.999999999969;8668.78328041804;8673.089325620782;8678.652853885837;8353.916460835375;8367.539785311805;8417.831643367159;8423.915760842374;8457.238848503664;41433.79283103596;41557.79221103875;41591.1681766366;41821.581784182075;41841.581584184074;3684.778913265213;3692.335379631665;3701.0747313171705;3710.9814450927574;3723.2181241939224;33210.50184247238;33222.16944576381;33329.333413331595;33414.16464588381;33432.33135337304;5528.198411230349;5537.446255374471;5563.36021357545;5576.386597474275;5593.32880054392;220965;221564;222143;222615;222864;45975;51461.48538514567;51789.223161652604;52189;52222.47777522176;49549;56461.71769141128;57787.71106144526;57849.710751446815;58115;16595.585110372336;16656.75014874778;16716.41492547755;16778.161091945352;16856.91571542159;13531.323433828466;13610.59168224955;13614.659633509242;13621.318934053257;13630.18218906849;15444.305006274744;15482.84517154814;15511.5346539604;15530.534083977498;15600.765988509955;5890.204822349014;6088.203979825962;6127.724252408652;6185.134081228632;6191.087511426576;1099.7140743406612;1106.983395249072;1116.3525155409843;1118.222835129577;1123.1407972900738;25225;25401.61897571538;25468.363290917874;25577.232683019538;25681.871590642302;1187.1037366788191;1206.8129439936824;1234.0682784497646;1234.370471059748;1237.07219585311;13204.735905281841;13347.866521334856;13439.059265851492;13500.379954405338;13565;289292;289805;289816;290010;290155;56407.71796140993;56536.71731641315;56571;56702.71648641813;56807.14789278164;1516.9772453413207;1529.6481809184172;1565.71034358644;1565.8590726834639;1581.8734501239874;25045.49909001846;25226.621600675637;25604.61593076068;25634.615480768178;25828.612570811827;40045.1990960185;40102.39846402248;40169.79915100406;40221;40253.597464024984;245030;245739;247063;247432;248580;35496.29007419785;37884;38516;38719.80640096781;38762.224755505296;49852;50446.24330635117;50532.24201637052;50938.490615093375;51174.488255118464;37182.628173719;37250.25499490049;37292.627073728916;37513;37642.43536346902;36665.26669466649;37008.814955925045;37032.62967370292;37241;37313.44029839609;43569;43819.56180438155;43910.78044609757;44287.778561106985;44292.11415771602;35016.47475287822;35488;35595.46606800952;35809;35907.102322441104;26354.73645263523;27028.45943081167;27161;27338.179854604394;27511.86244068767 12:24:53 INFO - PID 3845 | |2;jquery.html;599.3180517185594;617.7405489694329;621.4220774679549;623.1929651101823;634.3814780588926;22764.65853012202;23289.417764555896;24134.275971720854;24139.879300603516;24152.999999999956;21493.785062149374;22996.425089372733;23027.42431439215;23033.654495182545;23035.539289214248;6715.764948226813;6775.1531058617675;6797.11637487126;6813.148356455443;6844.076049733272;6606.207255129391;6849.41779948705;6877.312268773133;6884.655767211644;6891.448684105285;6823.454123670119;6829.214640316352;6839.418649414805;6839.692213850364;6842.384185423311;6784.253732089466;6811.386975172209;6842.384185423336;6845.965770171118;6847.417969472601;6321.0834429008;6338.429541341256;6377.8405539861415;6378.138951241615;6385.648789316586;28932;29949.101526954226;30298.69701303003;30440.54339184914;30525.542116868266;2339.2865176121277;2348.272035668931;2362.7282862470865;2371.253055287596;2372.727136379304;20587.4853128673;21616;21661;21675.34973950784;21682.457938551506;2446.645236440706;2500.062476571286;2538.695356557225;2539.2382285314334;2566.230130960704;132146;136336.31831840877;153214;160252;167132;15875.364985400454;16427.83572164287;16525;16971.490855274362;16986.490405287863;25708.100216492334;25869.35326616812;25881.09416170425;25933.22200333993;25974.480510390065;10849.891501084889;10944.17918656104;10991.945040274748;11177.66467005991;11182.608608698656;9854.11312981835;9996.550155243032;10030.097291243823;10062.899371006197;10149.188064954933;9555;10089.545970431349;10199.603093273076;10226;10313.484325783831;2898.5507246376847;2929.770359907555;2972.0043785331723;3068.7238148566735;3083.7070478304527;1047.418682631147;1049.228816819637;1049.3022140276782;1050.7635781949152;1061.8991195836384;20739;20922.267720629705;21064.473388165112;21083.367498975054;21463.570728585342;1110.622388387952;1114.053328944936;1115.8214685650257;1117.664700589836;1124.8537690100234;11505.252158609663;11566.248193867372;11632.302061876146;11648.767024659459;11744.765104697859;583518;590081;592826;597361;597627;128236;132949;134104;136238;136510;1251.69959209789;1256.9497220111093;1264.3551788587886;1280.564608033273;1297.6496345986532;18528.258869645062;18646.44060678182;18712.438626841216;18729;18924.905375473034;59869;60820.78358432895;62257;63372;63429.68285158544;79326.6033669851;79832.20167798248;79921.60039199766;80078.59960700393;80649.59675201586;15437.768433473282;15682.800087602158;15818.525444236691;15828;15830.208489575705;23233.419164521023;23298.65052024187;23407;23754.643680344463;24008.759912400652;19358.128884200527;19412.417627471197;19530;19638.214471421554;19731.704024439932;16011.519654410386;17470.30118795234;17651.205695743465;17954.820451795316;18043.368482103062;19073.332433364765;19569.902150489157;19746.506337341107;19827.801721982596;19918.90040549788;15371;15373.077615343114;15405.537833865003;15410.69178616399;15497.690046199239;18918.621627567645;19161.233550657817;19213.23147074157;19362.90318548398;19401.2239510418 12:24:53 INFO - PID 3845 | |3;mootools.html;1432.7707566789313;1497.8352381238062;1510.1014896136794;1526.8854835887312;1527.6486408126132;17126.314947402105;17532.737008944878;17653.20560574774;17743.822561774377;17832;17508.649827003454;17585.208665610047;17592.120393980313;17601.55996100095;17623.471295861145;2653.9336516587045;2685.1541764344265;2720.91837244883;2727.972720272792;2728.2224565998695;2756.503829310723;2786.888524459029;2799.566067259578;2809.1853362524844;2815.5635876439183;2714.9185524434297;2731.617573539706;2798.8320700757918;2801.0476438011074;2805.0322638689618;2741.300968253103;2749.8350098994124;2757.241758516404;2767.4188420431633;2769.2938300733185;2508.385445565848;2606.504764094816;2612.203278000209;2715.0633031604066;2715.5383584790434;17341.219645116;17354.045527495982;17361.218745156373;17362.39231626887;17397.304107835673;8193.918060819435;8426.78933026673;8457.281131103802;8465.111163327885;8472;5347.518723314882;5451.618386712932;5509.807156749534;5555.694436805974;5560.7219639017885;2111.5249068959515;2121.9151233950624;2129.606022885771;2158.7733288004847;2177.499175189715;31444.685553144634;31453.84273078643;31495.685043149504;31505.21236969071;31535.053948381585;7191.316824901626;7828.138904720477;8034.15641357649;8041.396895232764;8067.798305042421;19879.60240795176;19943.202271909253;19952.800471995386;20120.094595743227;20149.39551813448;8317.96025496813;8517;8535.189157030072;8558.61486233121;8577.742667719978;7781.144074151839;7850.960745196237;7874.685012599546;7885.960570197227;7906.683732650745;5482.17767334903;5611.663300202001;5613.1587326859235;5650.378458369576;5658.773649054074;1552;1566.7963164788505;1567.388718399825;1573.858352748258;1581.3595493825019;933.1042199488567;941.9199368053655;954.9638642073428;963.2438535749543;967.2213867836459;8483.872741908875;8487.448315859448;8528.872066919;8631.136886311318;8631.827363452696;777.5917643237345;805.1706742055692;819.6885183630183;819.8852160697508;824.5959479854948;5394;5462.672239665632;5533.142362933751;5592.888142237214;5916.230889984275;24184;24995;25105.87447062753;25642.74357256441;25651.10221142251;24827.62758558587;25017.249482515555;25209.495810084063;25614.487710245692;25651.230463086136;12763.425645845959;12885.355732213351;12908.483660653657;12948.935255323662;12961.935190324177;9621.27840411958;9708.85436718464;9745.12293893553;9866.013398660076;9953.850692239477;21358.466038349165;21568.568628627025;21666.35000949974;22053.779462205173;22379.216727414463;21888.78111218931;22357.441063973532;22386.440338991655;22414.103435863035;22574.774252257266;12584.748305033665;12622.810657840324;12673.30296833683;12677.429515671633;12682.429290682127;15469.613259668598;15594.298256578708;15732.528024158835;15877.285522151762;15903.443379481663;12177.086718496326;12414.317212553397;12477.438515266653;12509.061820363691;12775.61673149807;11572.537098515964;11691.649250522498;11773.470193841127;11898.643040708792;12062.457189426672;10673.092787113183;10870.347779133277;10901.727456813644;10904.182186336215;10908.290961087503;8258.710945116893;8361.790955225924;8383.28742056932;8426.705065322683;8486.40595158333;8286.46138001028;8345.374096942633;8354.248117669438;8492.61783219769;8531.786705332417 12:24:53 INFO - PID 3845 | |4;prototype.html;542.427738735634;568.5962966293931;570.549266079797;578.3118089473526;586.2173997713055;6325.4307112359875;6349.904751428732;6354.714037868295;6369.681515924209;6381.393767592077;6178.845528861781;6179;6212.62724236545;6237.220347456568;6266.780662676807;5121.897562048747;5132.589392848563;5142.640015198937;5168.974155129219;5196.740162991854;4826.1795494765975;4966.553010229079;5140.280360749498;5167.147420675584;5174.948250517485;4923.261510773395;4949.257611358289;4953.108440480712;4996.725180115111;5005.399352077738;4956.306117143603;4969.180085285923;4998.325226094504;4999.200127979506;5001.549860512571;4507.008458139204;4513.201814874798;4522.977385113053;4525.094981003813;4585.816567337302;6177.289611694623;6212.689365531705;6218.409251121137;6226;6233.657148856811;5855.268091488564;5890.823275301748;5896.911546326808;5920.437558431942;5938.346781853993;6578.111954886077;6605.834854128637;6618.768343107968;6678.09845670838;6687.297833727486;5000.449950505442;5033.245013248042;5044.798208071673;5072.873178170538;5103.18349064148;6618.635975021371;6724.562903411263;6786.864262714718;6853.143357080365;6895.931130674753;5188.766505507261;5200.557952574073;5206.71363075027;5211.270422140903;5252.002119597264;5273.446288139767;5276.630635855453;5277.1292736698015;5291.920621190685;5302.628815982845;5842.883142337129;5868.911966320508;5901.793437229677;5909.586328957018;5917.585768996215;6594.769183078665;6604.207495100619;6700.363465470772;6706.853806781881;6782.117600019972;1784.1168621532424;1792.1755992243714;1804.621029583802;1809.6380723855282;1829.2500074969198;1251.9874801252054;1282.7947528395412;1290.9031822613342;1302.7524770293614;1305.771489989248;6122.724477398528;6155.661438620829;6189.442950134419;6198.535109866778;6204.906926396107;6482.870342593216;6667.299933507009;6724.764633237911;6741.460683145337;6752;6076.149339092531;6093.908591371133;6100.206973093558;6109.84725381869;6112.694365281718;3505.964940350615;3509.1402606361094;3518.313928783914;3531.94702079469;3565.625609311037;6973.651317434108;7001.824954376181;7090.858182836314;7127.643617819088;7146.964265178744;6860.588364698134;6869.7595584155315;6875.862482750317;6956.756513522002;6993.685284162123;6878.621675807779;6950.8609827804175;7006.544574602634;7024.26245244242;7038.718451261892;6623.801285961236;6714.966425167843;6722.462203023844;6773.05177275172;6789.355011273921;6672.766453174309;6676.899846502403;6746.527743057939;6786.660666966731;6811.182658080864;6918.792436226921;6957.1651401832105;6969.372756451944;6982.860342793218;6993.510454268152;5119.385673719177;5158.664686795346;5175.534201921845;5194.35070616173;5209.905919756742;6579.6052236864025;6731.32686731323;6740.831479212863;6774.356436138559;6779.457643388616;6371.331010243857;6549.967250163719;6620.801375958729;6623.370779775913;6627.3372662733345;6575.994321731918;6576.506761992774;6582.124094633006;6613.107230523818;6615.933840661532;6394.4564712;6429.38920802523;6440;6451.258105317948;6463.547551671338;6524.042787163737;6552.313551443885;6562.52343222771;6604.801855944329;6669.46644268467;6256.405641464054;6371.681415929278;6464.385883341075;6467.741290348334;6492.513061520499 12:24:53 INFO - PID 3845 | |5;yui.html;959.9424034557925;962.3936919740562;977.4428575711843;987.9160271376933;1002.6841544913352;18977.71533426998;19216;19338.323158689447;19879;20281;18636.81363186368;18819.341323053697;18876.433706988777;18889.905550472264;18995.715064274013;1043.7547176413518;1063.5161001744227;1066.3974854207386;1069.6951368859877;1071.9571217151345;1081.783643271345;1082.5723839083557;1084.701707030565;1085.207798307236;1085.2132204151967;1107.457345900511;1133.0878642692628;1133.4162906103363;1136.4374634555866;1144.31341195283;1144.3705961721103;1145.0553293532828;1145.2098052343856;1145.4330106597204;1146.6731981385308;793.8610743119931;814.4461765999177;822.5599304372132;828.337330135895;831.5925196653596;15846.841531584652;15994.360225590963;15998.200089995453;16102.43641472543;17295.827041729674;22617.77382226173;22883.771162288496;23155.76844231553;23217.767822321897;23376.883115584315;18278.634427311514;18730.34443794461;18768.155433005548;18897.62204755911;19034.61930761377;3873.399623058402;3888.047428380065;3917.902052448683;3933.0757272041046;3936.940945885814;124864;158045;158615;161251;162079.1896040524;18720.438386848415;18730.531736706693;19039.333623323117;19065;19196.328128515714;33874.32251354959;34685.47971780425;34730.82634586811;34830.82584587061;35190.120246993516;9955.30312878106;10029;10098.192144628412;10129.341592796445;10144.695659130239;4676.55572720591;4695.43654761424;4710.9764451177525;4723.881902952454;4757.405324334458;6069.3627169147485;6163.322034576193;6171.9691401543605;6184;6200.627962322275;1154.272808130889;1197.9520819167135;1198.532572296805;1208.9335086570147;1218.1229514749366;734.328089797829;752.3304259209308;766.5745511241315;771.8224808294124;774.8527779721948;12042.036637069014;12231.571894983634;12381.876181238253;13145.60563183106;13363.665908352175;741.2661465149463;743.0785825576354;759.9620018999028;761.46316846623;768.5657603454142;6140.416660417253;6152.569320147636;6247.562670613104;6266.154069200691;6290.559660823698;142147;142280;144224;147698;148849;125749;127334.1799746009;130107.34946325207;131212;135020;104369;108674.45662771635;110092.4495377518;110715;110973.44513277382;13106.344682766014;13467.3939672713;13530.72938541204;13618.319084045559;13629.659258518617;52590.73704631453;53427.73286133545;53530.732346338016;54379;54482.455175447736;67747.66126169535;68230.65884670743;68483;68613;68740.31259687539;18944.62110757805;19228.807711922702;19318.4204473866;19324.227030918606;19385.612287753884;22091.226807061677;22147;22151.003204856144;22210;22245.332640020824;18951.810481895554;19119.23523059062;19132.139053742336;19255.8074419254;19438.125284361955;17770.822291776916;17940.91029544844;18042;18052.54868628295;18066.638667226318;19276.132574033923;19332.613347733248;19420.902895485433;19527.511812204808;19643.901780491007;13713.657158571115;13951.790723138958;14093.929530352283;14402.351894164578;14495.492657756928;14641.633959151106;14797.482088126864;14907.254637267877;14960.700785984001;14964.850351496345 12:24:53 INFO - PID 3845 | __end_tp_report 12:24:53 INFO - PID 3845 | __start_cc_report 12:24:53 INFO - PID 3845 | _x_x_mozilla_cycle_collect,272 12:24:53 INFO - PID 3845 | __end_cc_report 12:24:53 INFO - PID 3845 | __startTimestamp1506281093865__endTimestamp 12:24:53 INFO - PID 3845 | 12:24:53 INFO - PID 3845 | ------- Summary: start ------- 12:24:53 INFO - PID 3845 | Number of tests: 6 12:24:53 INFO - PID 3845 | 12:24:53 INFO - PID 3845 | [#0] dojo.html Cycles:165 Average:6422.32 Median:6639.14 stddev:765.46 (11.5%) stddev-sans-first:741.83 12:24:53 INFO - PID 3845 | Values: 3901.7 3915.5 3959.7 3971.7 3974.6 6339.2 6400.8 6453.2 6460.3 6511.5 6459.0 6463.0 6477.2 6489.5 6504.0 5723.7 5737.5 5773.7 5780.2 5824.8 5785.5 5818.8 5827.4 5828.6 5880.8 5735.3 5764.4 5850.7 5854.4 5857.1 5598.7 5727.8 5768.5 5796.0 5797.3 5697.2 5708.0 5764.3 5783.9 5801.9 6303.7 6367.1 6407.2 6431.0 6464.4 6898.6 6908.1 6922.9 6930.6 7000.8 7050.9 7090.4 7109.6 7118.4 7122.1 6488.2 6521.9 6544.5 6593.0 6596.0 7039.0 7147.5 7150.5 7175.9 7270.2 6793.9 6858.3 6877.5 6881.2 6953.9 7046.8 7067.3 7075.0 7085.4 7092.5 6903.8 6927.7 7022.7 7025.0 7026.5 6684.6 6773.8 6910.4 6914.1 7021.2 5073.0 5073.8 5081.2 5091.5 5129.1 4598.3 4615.4 4657.3 4740.0 4763.6 6393.3 6410.6 6482.3 6496.6 6511.3 6756.5 6947.8 6968.7 7034.8 7083.8 6337.8 6354.9 6403.5 6406.5 6425.8 5636.9 5660.3 5664.6 5704.6 5707.8 7210.0 7214.6 7229.1 7311.9 7322.0 6882.9 6940.0 6968.8 6979.8 6982.7 7275.0 7319.0 7320.3 7331.9 7333.0 6843.7 6928.7 6946.7 7015.6 7018.6 6895.1 6949.5 7026.5 7030.4 7066.3 6722.0 6780.4 6791.0 6792.3 6795.5 6713.7 6717.3 6810.7 6812.5 6813.7 6531.1 6603.5 6637.3 6641.5 6645.0 6745.7 6788.7 6798.2 6798.3 6813.3 6522.8 6610.4 6619.6 6623.1 6639.1 12:24:53 INFO - PID 3845 | 12:24:53 INFO - PID 3845 | [#1] ext.html Cycles:180 Average:43439.70 Median:37763.22 stddev:66127.28 (175.1%) stddev-sans-first:66235.82 12:24:53 INFO - PID 3845 | Values: 948.8 974.2 977.5 978.0 978.0 39645.2 43934.8 43988.8 44046.1 44120.3 41267.0 41406.0 41475.2 41516.0 41598.6 7503.2 7593.6 7635.9 8323.2 8690.7 8494.6 8634.8 8635.0 8638.8 8675.8 8629.0 8632.3 8643.9 8672.4 8698.3 8637.3 8663.0 8668.8 8673.1 8678.7 8353.9 8367.5 8417.8 8423.9 8457.2 41433.8 41557.8 41591.2 41821.6 41841.6 3684.8 3692.3 3701.1 3711.0 3723.2 33210.5 33222.2 33329.3 33414.2 33432.3 5528.2 5537.4 5563.4 5576.4 5593.3 220965.0 221564.0 222143.0 222615.0 222864.0 45975.0 51461.5 51789.2 52189.0 52222.5 49549.0 56461.7 57787.7 57849.7 58115.0 16595.6 16656.8 16716.4 16778.2 16856.9 13531.3 13610.6 13614.7 13621.3 13630.2 15444.3 15482.8 15511.5 15530.5 15600.8 5890.2 6088.2 6127.7 6185.1 6191.1 1099.7 1107.0 1116.4 1118.2 1123.1 25225.0 25401.6 25468.4 25577.2 25681.9 1187.1 1206.8 1234.1 1234.4 1237.1 13204.7 13347.9 13439.1 13500.4 13565.0 289292.0 289805.0 289816.0 290010.0 290155.0 56407.7 56536.7 56571.0 56702.7 56807.1 1517.0 1529.6 1565.7 1565.9 1581.9 25045.5 25226.6 25604.6 25634.6 25828.6 40045.2 40102.4 40169.8 40221.0 40253.6 245030.0 245739.0 247063.0 247432.0 248580.0 35496.3 37884.0 38516.0 38719.8 38762.2 49852.0 50446.2 50532.2 50938.5 51174.5 37182.6 37250.3 37292.6 37513.0 37642.4 36665.3 37008.8 37032.6 37241.0 37313.4 43569.0 43819.6 43910.8 44287.8 44292.1 35016.5 35488.0 35595.5 35809.0 35907.1 26354.7 27028.5 27161.0 27338.2 27511.9 12:24:53 INFO - PID 3845 | 12:24:53 INFO - PID 3845 | [#2] jquery.html Cycles:180 Average:39346.39 Median:21668.17 stddev:99324.72 (458.4%) stddev-sans-first:99560.75 12:24:53 INFO - PID 3845 | Values: 599.3 617.7 621.4 623.2 634.4 22764.7 23289.4 24134.3 24139.9 24153.0 21493.8 22996.4 23027.4 23033.7 23035.5 6715.8 6775.2 6797.1 6813.1 6844.1 6606.2 6849.4 6877.3 6884.7 6891.4 6823.5 6829.2 6839.4 6839.7 6842.4 6784.3 6811.4 6842.4 6846.0 6847.4 6321.1 6338.4 6377.8 6378.1 6385.6 28932.0 29949.1 30298.7 30440.5 30525.5 2339.3 2348.3 2362.7 2371.3 2372.7 20587.5 21616.0 21661.0 21675.3 21682.5 2446.6 2500.1 2538.7 2539.2 2566.2 132146.0 136336.3 153214.0 160252.0 167132.0 15875.4 16427.8 16525.0 16971.5 16986.5 25708.1 25869.4 25881.1 25933.2 25974.5 10849.9 10944.2 10991.9 11177.7 11182.6 9854.1 9996.6 10030.1 10062.9 10149.2 9555.0 10089.5 10199.6 10226.0 10313.5 2898.6 2929.8 2972.0 3068.7 3083.7 1047.4 1049.2 1049.3 1050.8 1061.9 20739.0 20922.3 21064.5 21083.4 21463.6 1110.6 1114.1 1115.8 1117.7 1124.9 11505.3 11566.2 11632.3 11648.8 11744.8 583518.0 590081.0 592826.0 597361.0 597627.0 128236.0 132949.0 134104.0 136238.0 136510.0 1251.7 1256.9 1264.4 1280.6 1297.6 18528.3 18646.4 18712.4 18729.0 18924.9 59869.0 60820.8 62257.0 63372.0 63429.7 79326.6 79832.2 79921.6 80078.6 80649.6 15437.8 15682.8 15818.5 15828.0 15830.2 23233.4 23298.7 23407.0 23754.6 24008.8 19358.1 19412.4 19530.0 19638.2 19731.7 16011.5 17470.3 17651.2 17954.8 18043.4 19073.3 19569.9 19746.5 19827.8 19918.9 15371.0 15373.1 15405.5 15410.7 15497.7 18918.6 19161.2 19213.2 19362.9 19401.2 12:24:53 INFO - PID 3845 | 12:24:53 INFO - PID 3845 | [#3] mootools.html Cycles:180 Average:10553.16 Median:2714.99 stddev:7924.44 (291.9%) stddev-sans-first:7917.05 12:24:53 INFO - PID 3845 | Values: 1432.8 1497.8 1510.1 1526.9 1527.6 17126.3 17532.7 17653.2 17743.8 17832.0 17508.6 17585.2 17592.1 17601.6 17623.5 2653.9 2685.2 2720.9 2728.0 2728.2 2756.5 2786.9 2799.6 2809.2 2815.6 2714.9 2731.6 2798.8 2801.0 2805.0 2741.3 2749.8 2757.2 2767.4 2769.3 2508.4 2606.5 2612.2 2715.1 2715.5 17341.2 17354.0 17361.2 17362.4 17397.3 8193.9 8426.8 8457.3 8465.1 8472.0 5347.5 5451.6 5509.8 5555.7 5560.7 2111.5 2121.9 2129.6 2158.8 2177.5 31444.7 31453.8 31495.7 31505.2 31535.1 7191.3 7828.1 8034.2 8041.4 8067.8 19879.6 19943.2 19952.8 20120.1 20149.4 8318.0 8517.0 8535.2 8558.6 8577.7 7781.1 7851.0 7874.7 7886.0 7906.7 5482.2 5611.7 5613.2 5650.4 5658.8 1552.0 1566.8 1567.4 1573.9 1581.4 933.1 941.9 955.0 963.2 967.2 8483.9 8487.4 8528.9 8631.1 8631.8 777.6 805.2 819.7 819.9 824.6 5394.0 5462.7 5533.1 5592.9 5916.2 24184.0 24995.0 25105.9 25642.7 25651.1 24827.6 25017.2 25209.5 25614.5 25651.2 12763.4 12885.4 12908.5 12948.9 12961.9 9621.3 9708.9 9745.1 9866.0 9953.9 21358.5 21568.6 21666.4 22053.8 22379.2 21888.8 22357.4 22386.4 22414.1 22574.8 12584.7 12622.8 12673.3 12677.4 12682.4 15469.6 15594.3 15732.5 15877.3 15903.4 12177.1 12414.3 12477.4 12509.1 12775.6 11572.5 11691.6 11773.5 11898.6 12062.5 10673.1 10870.3 10901.7 10904.2 10908.3 8258.7 8361.8 8383.3 8426.7 8486.4 8286.5 8345.4 8354.2 8492.6 8531.8 12:24:53 INFO - PID 3845 | 12:24:53 INFO - PID 3845 | [#4] prototype.html Cycles:180 Average:5635.81 Median:6222.20 stddev:1571.05 (25.2%) stddev-sans-first:1528.24 12:24:53 INFO - PID 3845 | Values: 542.4 568.6 570.5 578.3 586.2 6325.4 6349.9 6354.7 6369.7 6381.4 6178.8 6179.0 6212.6 6237.2 6266.8 5121.9 5132.6 5142.6 5169.0 5196.7 4826.2 4966.6 5140.3 5167.1 5174.9 4923.3 4949.3 4953.1 4996.7 5005.4 4956.3 4969.2 4998.3 4999.2 5001.5 4507.0 4513.2 4523.0 4525.1 4585.8 6177.3 6212.7 6218.4 6226.0 6233.7 5855.3 5890.8 5896.9 5920.4 5938.3 6578.1 6605.8 6618.8 6678.1 6687.3 5000.4 5033.2 5044.8 5072.9 5103.2 6618.6 6724.6 6786.9 6853.1 6895.9 5188.8 5200.6 5206.7 5211.3 5252.0 5273.4 5276.6 5277.1 5291.9 5302.6 5842.9 5868.9 5901.8 5909.6 5917.6 6594.8 6604.2 6700.4 6706.9 6782.1 1784.1 1792.2 1804.6 1809.6 1829.3 1252.0 1282.8 1290.9 1302.8 1305.8 6122.7 6155.7 6189.4 6198.5 6204.9 6482.9 6667.3 6724.8 6741.5 6752.0 6076.1 6093.9 6100.2 6109.8 6112.7 3506.0 3509.1 3518.3 3531.9 3565.6 6973.7 7001.8 7090.9 7127.6 7147.0 6860.6 6869.8 6875.9 6956.8 6993.7 6878.6 6950.9 7006.5 7024.3 7038.7 6623.8 6715.0 6722.5 6773.1 6789.4 6672.8 6676.9 6746.5 6786.7 6811.2 6918.8 6957.2 6969.4 6982.9 6993.5 5119.4 5158.7 5175.5 5194.4 5209.9 6579.6 6731.3 6740.8 6774.4 6779.5 6371.3 6550.0 6620.8 6623.4 6627.3 6576.0 6576.5 6582.1 6613.1 6615.9 6394.5 6429.4 6440.0 6451.3 6463.5 6524.0 6552.3 6562.5 6604.8 6669.5 6256.4 6371.7 6464.4 6467.7 6492.5 12:24:53 INFO - PID 3845 | 12:24:53 INFO - PID 3845 | [#5] yui.html Cycles:180 Average:27834.26 Median:18948.22 stddev:40743.97 (215.0%) stddev-sans-first:40808.30 12:24:53 INFO - PID 3845 | Values: 959.9 962.4 977.4 987.9 1002.7 18977.7 19216.0 19338.3 19879.0 20281.0 18636.8 18819.3 18876.4 18889.9 18995.7 1043.8 1063.5 1066.4 1069.7 1072.0 1081.8 1082.6 1084.7 1085.2 1085.2 1107.5 1133.1 1133.4 1136.4 1144.3 1144.4 1145.1 1145.2 1145.4 1146.7 793.9 814.4 822.6 828.3 831.6 15846.8 15994.4 15998.2 16102.4 17295.8 22617.8 22883.8 23155.8 23217.8 23376.9 18278.6 18730.3 18768.2 18897.6 19034.6 3873.4 3888.0 3917.9 3933.1 3936.9 124864.0 158045.0 158615.0 161251.0 162079.2 18720.4 18730.5 19039.3 19065.0 19196.3 33874.3 34685.5 34730.8 34830.8 35190.1 9955.3 10029.0 10098.2 10129.3 10144.7 4676.6 4695.4 4711.0 4723.9 4757.4 6069.4 6163.3 6172.0 6184.0 6200.6 1154.3 1198.0 1198.5 1208.9 1218.1 734.3 752.3 766.6 771.8 774.9 12042.0 12231.6 12381.9 13145.6 13363.7 741.3 743.1 760.0 761.5 768.6 6140.4 6152.6 6247.6 6266.2 6290.6 142147.0 142280.0 144224.0 147698.0 148849.0 125749.0 127334.2 130107.3 131212.0 135020.0 104369.0 108674.5 110092.4 110715.0 110973.4 13106.3 13467.4 13530.7 13618.3 13629.7 52590.7 53427.7 53530.7 54379.0 54482.5 67747.7 68230.7 68483.0 68613.0 68740.3 18944.6 19228.8 19318.4 19324.2 19385.6 22091.2 22147.0 22151.0 22210.0 22245.3 18951.8 19119.2 19132.1 19255.8 19438.1 17770.8 17940.9 18042.0 18052.5 18066.6 19276.1 19332.6 19420.9 19527.5 19643.9 13713.7 13951.8 14093.9 14402.4 14495.5 14641.6 14797.5 14907.3 14960.7 14964.9 12:24:53 INFO - PID 3845 | -------- Summary: end -------- 12:24:53 INFO - PID 3845 | 12:24:54 INFO - PID 3845 | [Parent 3845, Gecko_IOThread] WARNING: waitpid failed pid:3894 errno:10: file /builds/worker/workspace/build/src/ipc/chromium/src/base/process_util_posix.cc, line 276 12:24:54 INFO - TEST-INFO | 3845: exit 0 12:24:54 INFO - TEST-OK | dromaeo_css | took 1108475ms 12:24:54 INFO - TEST-START | kraken 12:24:54 INFO - Initialising browser for kraken test... 12:24:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54825/getInfo.html -profile /tmp/tmpECPQld/profile 12:24:54 INFO - TEST-INFO | started process 4212 (/builds/slave/test/build/application/firefox/firefox http://localhost:54825/getInfo.html) 12:24:59 INFO - TEST-INFO | 4212: exit 0 12:24:59 INFO - Browser initialized. 12:24:59 INFO - Running cycle 1/1 for kraken test... 12:24:59 INFO - Using env: {'DISPLAY': ':0', 12:24:59 INFO - 'HOME': '/home/cltbld', 12:24:59 INFO - 'JSGC_DISABLE_POISONING': '1', 12:24:59 INFO - 'LANG': 'en_US.UTF-8', 12:24:59 INFO - 'LANGUAGE': 'en_US:en', 12:24:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:24:59 INFO - 'LOGNAME': 'cltbld', 12:24:59 INFO - 'MAIL': '/var/mail/cltbld', 12:24:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:24:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:24:59 INFO - 'MOZ_CRASHREPORTER': '1', 12:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:24:59 INFO - 'MOZ_NO_REMOTE': '1', 12:24:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:24:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:59 INFO - 'NO_EM_RESTART': '1', 12:24:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:59 INFO - 'PWD': '/builds/slave/test', 12:24:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:24:59 INFO - 'RUST_BACKTRACE': 'full', 12:24:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:24:59 INFO - 'SHELL': '/bin/bash', 12:24:59 INFO - 'SHLVL': '1', 12:24:59 INFO - 'TERM': 'linux', 12:24:59 INFO - 'TMOUT': '86400', 12:24:59 INFO - 'USER': 'cltbld', 12:24:59 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538', 12:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:59 INFO - '_': '/tools/buildbot/bin/python'} 12:24:59 INFO - TEST-INFO | started process 4454 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpECPQld/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:25:00 INFO - PID 4454 | 12:25:00 INFO - PID 4454 | (/builds/slave/test/build/application/firefox/firefox:4503): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:25:00 INFO - PID 4454 | 12:25:11 INFO - PID 4454 | RSS: Main: 144642048 12:25:11 INFO - PID 4454 | 12:25:13 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54825/tests/kraken/driver.html?testName=audio-beat-detection) 12:25:14 INFO - PID 4454 | RSS: Main: 151289856 12:25:14 INFO - PID 4454 | 12:25:16 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54825/tests/kraken/driver.html?testName=audio-dft) 12:25:17 INFO - PID 4454 | RSS: Main: 151166976 12:25:17 INFO - PID 4454 | 12:25:20 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54825/tests/kraken/driver.html?testName=audio-fft) 12:25:21 INFO - PID 4454 | RSS: Main: 148090880 12:25:21 INFO - PID 4454 | 12:25:23 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54825/tests/kraken/driver.html?testName=audio-oscillator) 12:25:23 INFO - PID 4454 | RSS: Main: 150089728 12:25:23 INFO - PID 4454 | 12:25:25 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54825/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:25:26 INFO - PID 4454 | RSS: Main: 149385216 12:25:26 INFO - PID 4454 | 12:25:29 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54825/tests/kraken/driver.html?testName=imaging-darkroom) 12:25:30 INFO - PID 4454 | RSS: Main: 149291008 12:25:30 INFO - PID 4454 | 12:25:33 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54825/tests/kraken/driver.html?testName=imaging-desaturate) 12:25:34 INFO - PID 4454 | RSS: Main: 150560768 12:25:34 INFO - PID 4454 | 12:25:37 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54825/tests/kraken/driver.html?testName=json-parse-financial) 12:25:37 INFO - PID 4454 | RSS: Main: 152137728 12:25:37 INFO - PID 4454 | 12:25:39 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54825/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:25:39 INFO - PID 4454 | RSS: Main: 151777280 12:25:39 INFO - PID 4454 | 12:25:41 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54825/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:25:42 INFO - PID 4454 | RSS: Main: 151511040 12:25:42 INFO - PID 4454 | 12:25:44 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54825/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:25:44 INFO - PID 4454 | RSS: Main: 148819968 12:25:44 INFO - PID 4454 | 12:25:47 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54825/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:25:47 INFO - PID 4454 | RSS: Main: 148807680 12:25:47 INFO - PID 4454 | 12:25:50 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54825/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:25:51 INFO - PID 4454 | RSS: Main: 149340160 12:25:51 INFO - PID 4454 | 12:25:52 INFO - PID 4454 | Cycle 1(1): loaded http://localhost:54825/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54825/tests/kraken/driver.html?testName=ai-astar) 12:25:53 INFO - PID 4454 | RSS: Main: 151023616 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | __start_tp_report 12:25:53 INFO - PID 4454 | _x_x_mozilla_page_load 12:25:53 INFO - PID 4454 | _x_x_mozilla_page_load_details 12:25:53 INFO - PID 4454 | |i|pagename|runs| 12:25:53 INFO - PID 4454 | |0;ai-astar;105;105;105;105;106;109;100;107;105;105 12:25:53 INFO - PID 4454 | |1;audio-beat-detection;112;112;115;113;117;112;113;114;112;118 12:25:53 INFO - PID 4454 | |2;audio-dft;162;162;164;161;163;164;164;160;164;164 12:25:53 INFO - PID 4454 | |3;audio-fft;73;73;74;73;74;73;76;73;73;73 12:25:53 INFO - PID 4454 | |4;audio-oscillator;79;78;78;78;79;78;79;77;79;79 12:25:53 INFO - PID 4454 | |5;imaging-gaussian-blur;113;113;113;114;110;113;112;115;114;114 12:25:53 INFO - PID 4454 | |6;imaging-darkroom;149;149;151;149;149;149;149;151;150;152 12:25:53 INFO - PID 4454 | |7;imaging-desaturate;93;94;99;95;99;95;94;95;94;95 12:25:53 INFO - PID 4454 | |8;json-parse-financial;45;42;44;44;43;44;45;44;43;43 12:25:53 INFO - PID 4454 | |9;json-stringify-tinderbox;42;42;40;49;40;40;40;40;40;41 12:25:53 INFO - PID 4454 | |10;stanford-crypto-aes;69;76;83;74;70;73;71;72;73;70 12:25:53 INFO - PID 4454 | |11;stanford-crypto-ccm;109;111;105;103;110;108;113;106;118;109 12:25:53 INFO - PID 4454 | |12;stanford-crypto-pbkdf2;160;152;152;159;151;155;149;151;151;160 12:25:53 INFO - PID 4454 | |13;stanford-crypto-sha256-iterative;62;62;60;59;59;60;62;60;58;60 12:25:53 INFO - PID 4454 | __end_tp_report 12:25:53 INFO - PID 4454 | __start_cc_report 12:25:53 INFO - PID 4454 | _x_x_mozilla_cycle_collect,742 12:25:53 INFO - PID 4454 | __end_cc_report 12:25:53 INFO - PID 4454 | __startTimestamp1506281153207__endTimestamp 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | ------- Summary: start ------- 12:25:53 INFO - PID 4454 | Number of tests: 14 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#0] ai-astar Cycles:10 Average:105.20 Median:105.00 stddev:2.25 (2.1%) stddev-sans-first:2.39 12:25:53 INFO - PID 4454 | Values: 105.0 105.0 105.0 105.0 106.0 109.0 100.0 107.0 105.0 105.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#1] audio-beat-detection Cycles:10 Average:113.80 Median:113.50 stddev:2.20 (1.9%) stddev-sans-first:2.24 12:25:53 INFO - PID 4454 | Values: 112.0 112.0 115.0 113.0 117.0 112.0 113.0 114.0 112.0 118.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#2] audio-dft Cycles:10 Average:162.80 Median:164.00 stddev:1.48 (0.9%) stddev-sans-first:1.54 12:25:53 INFO - PID 4454 | Values: 162.0 162.0 164.0 161.0 163.0 164.0 164.0 160.0 164.0 164.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#3] audio-fft Cycles:10 Average:73.50 Median:73.00 stddev:0.97 (1.3%) stddev-sans-first:1.01 12:25:53 INFO - PID 4454 | Values: 73.0 73.0 74.0 73.0 74.0 73.0 76.0 73.0 73.0 73.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#4] audio-oscillator Cycles:10 Average:78.40 Median:79.00 stddev:0.70 (0.9%) stddev-sans-first:0.71 12:25:53 INFO - PID 4454 | Values: 79.0 78.0 78.0 78.0 79.0 78.0 79.0 77.0 79.0 79.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.50 stddev:1.37 (1.2%) stddev-sans-first:1.45 12:25:53 INFO - PID 4454 | Values: 113.0 113.0 113.0 114.0 110.0 113.0 112.0 115.0 114.0 114.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#6] imaging-darkroom Cycles:10 Average:149.80 Median:149.50 stddev:1.14 (0.8%) stddev-sans-first:1.17 12:25:53 INFO - PID 4454 | Values: 149.0 149.0 151.0 149.0 149.0 149.0 149.0 151.0 150.0 152.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:2.06 (2.2%) stddev-sans-first:2.01 12:25:53 INFO - PID 4454 | Values: 93.0 94.0 99.0 95.0 99.0 95.0 94.0 95.0 94.0 95.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#8] json-parse-financial Cycles:10 Average:43.70 Median:44.00 stddev:0.95 (2.2%) stddev-sans-first:0.88 12:25:53 INFO - PID 4454 | Values: 45.0 42.0 44.0 44.0 43.0 44.0 45.0 44.0 43.0 43.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#9] json-stringify-tinderbox Cycles:10 Average:41.40 Median:40.50 stddev:2.80 (6.9%) stddev-sans-first:2.96 12:25:53 INFO - PID 4454 | Values: 42.0 42.0 40.0 49.0 40.0 40.0 40.0 40.0 40.0 41.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#10] stanford-crypto-aes Cycles:10 Average:73.10 Median:73.00 stddev:4.07 (5.6%) stddev-sans-first:4.03 12:25:53 INFO - PID 4454 | Values: 69.0 76.0 83.0 74.0 70.0 73.0 71.0 72.0 73.0 70.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#11] stanford-crypto-ccm Cycles:10 Average:109.20 Median:109.50 stddev:4.26 (3.9%) stddev-sans-first:4.52 12:25:53 INFO - PID 4454 | Values: 109.0 111.0 105.0 103.0 110.0 108.0 113.0 106.0 118.0 109.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.00 Median:153.50 stddev:4.19 (2.7%) stddev-sans-first:3.84 12:25:53 INFO - PID 4454 | Values: 160.0 152.0 152.0 159.0 151.0 155.0 149.0 151.0 151.0 160.0 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.20 Median:60.00 stddev:1.40 (2.3%) stddev-sans-first:1.32 12:25:53 INFO - PID 4454 | Values: 62.0 62.0 60.0 59.0 59.0 60.0 62.0 60.0 58.0 60.0 12:25:53 INFO - PID 4454 | -------- Summary: end -------- 12:25:53 INFO - PID 4454 | 12:25:53 INFO - PID 4454 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:25:53 INFO - TEST-INFO | 4454: exit 0 12:25:53 INFO - TEST-OK | kraken | took 59242ms 12:25:53 INFO - SUITE-END | took 1167s 12:25:53 INFO - Completed test suite (00:19:27) 12:25:53 INFO - javascript benchmark 12:25:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9148.79106903836, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3901.687864970803, 3915.549711783145, 3959.7426167299104, 3971.6624086952606, 3974.642282194602, 6339.175907132067, 6400.775972840952, 6453.22561292645, 6460.257070436901, 6511.511636627249, 6459.031145328191, 6462.967685161568, 6477.190351206099, 6489.545731798776, 6504.024396340551, 5723.685197314146, 5737.483626473617, 5773.740181691824, 5780.2196703444915, 5824.825255242349, 5785.450382213704, 5818.7963421280265, 5827.446392592697, 5828.591998560102, 5880.794172203974, 5735.311762588495, 5764.394738552433, 5850.6782126983, 5854.385289544601, 5857.092150716646, 5598.748056337475, 5727.770889164429, 5768.451997060272, 5795.985702502087, 5797.304323481167, 5697.202391665171, 5707.971460142673, 5764.337101233328, 5783.942160578383, 5801.941980580183, 6303.747850085991, 6367.140436041122, 6407.231132264158, 6431.0, 6464.353564643545, 6898.551594146365, 6908.1364829396325, 6922.861542769167, 6930.61881596512, 7000.824979375506, 7050.894236586455, 7090.432765378809, 7109.6089715065655, 7118.359347658736, 7122.109736282965, 6488.156539649863, 6521.902171467432, 6544.509161812883, 6593.011048342739, 6595.967020164869, 7039.0, 7147.53541019832, 7150.499465037501, 7175.928240717631, 7270.200277969419, 6793.898091528532, 6858.3141685832015, 6877.518573699893, 6881.174259088942, 6953.860922781516, 7046.823829404306, 7067.293270672994, 7075.0, 7085.43316534676, 7092.503524753321, 6903.792886213421, 6927.653617319113, 7022.719091236293, 7025.016497690327, 7026.543274687129, 6684.632345220963, 6773.830654233585, 6910.44716422685, 6914.101166848278, 7021.192562855234, 5073.010762901273, 5073.822416215415, 5081.212412076134, 5091.541761241506, 5129.12804823181, 4598.26427771555, 4615.376924115269, 4657.278121891112, 4740.028294199681, 4763.618910487153, 6393.264774550986, 6410.647414392253, 6482.254540727782, 6496.577722448026, 6511.348865113546, 6756.527043107034, 6947.756828510978, 6968.651567421609, 7034.788956331318, 7083.822904427328, 6337.7781777637565, 6354.936450635527, 6403.519736019817, 6406.4554512866, 6425.775097871646, 5636.85907852307, 5660.29246344207, 5664.631798933056, 5704.572157088152, 5707.771689132472, 7209.963950180216, 7214.567125972353, 7229.060222171084, 7311.89032164518, 7322.011528443699, 6882.8623427530165, 6940.028396024561, 6968.79093627192, 6979.755708550177, 6982.7206911725, 7275.0, 7319.0, 7320.304571065741, 7331.926680733124, 7333.046703928455, 6843.692033858588, 6928.722851086101, 6946.687399066953, 7015.649217539, 7018.649067546704, 6895.103636527219, 6949.513534052568, 7026.543274687129, 7030.367266945999, 7066.258042905421, 6721.966390168018, 6780.423663988616, 6791.04925310447, 6792.320767923168, 6795.524313298021, 6713.731450741916, 6717.328267173243, 6810.6594670265295, 6812.455003599799, 6813.693383797642, 6531.085648009377, 6603.471722262304, 6637.303083176197, 6641.501887358564, 6645.036469711961, 6745.730170793114, 6788.728450862108, 6798.184217893884, 6798.286179951034, 6813.2846051163915, 6522.836929076811, 6610.4050635443045, 6619.569727967667, 6623.072769812326, 6639.136912201383], "value": 6370.115671616024, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [948.8292107420664, 974.2108891797644, 977.4721650308836, 977.9608815647379, 978.0170928217141, 39645.2070958581, 43934.780326098364, 43988.78005609976, 44046.11907761843, 44120.3381949271, 41267.0, 41406.0, 41475.17049659005, 41516.0, 41598.58401415985, 7503.249675032494, 7593.582352970584, 7635.923640763604, 8323.209295116954, 8690.652373905035, 8494.617742201584, 8634.784130396727, 8634.956825215864, 8638.784030399258, 8675.826483470359, 8629.0, 8632.309415246766, 8643.870341944876, 8672.392932494728, 8698.260647844942, 8637.265832404253, 8662.999999999969, 8668.78328041804, 8673.089325620782, 8678.652853885837, 8353.916460835375, 8367.539785311805, 8417.831643367159, 8423.915760842374, 8457.238848503664, 41433.79283103596, 41557.79221103875, 41591.1681766366, 41821.581784182075, 41841.581584184074, 3684.778913265213, 3692.335379631665, 3701.0747313171705, 3710.9814450927574, 3723.2181241939224, 33210.50184247238, 33222.16944576381, 33329.333413331595, 33414.16464588381, 33432.33135337304, 5528.198411230349, 5537.446255374471, 5563.36021357545, 5576.386597474275, 5593.32880054392, 220965.0, 221564.0, 222143.0, 222615.0, 222864.0, 45975.0, 51461.48538514567, 51789.223161652604, 52189.0, 52222.47777522176, 49549.0, 56461.71769141128, 57787.71106144526, 57849.710751446815, 58115.0, 16595.585110372336, 16656.75014874778, 16716.41492547755, 16778.161091945352, 16856.91571542159, 13531.323433828466, 13610.59168224955, 13614.659633509242, 13621.318934053257, 13630.18218906849, 15444.305006274744, 15482.84517154814, 15511.5346539604, 15530.534083977498, 15600.765988509955, 5890.204822349014, 6088.203979825962, 6127.724252408652, 6185.134081228632, 6191.087511426576, 1099.7140743406612, 1106.983395249072, 1116.3525155409843, 1118.222835129577, 1123.1407972900738, 25225.0, 25401.61897571538, 25468.363290917874, 25577.232683019538, 25681.871590642302, 1187.1037366788191, 1206.8129439936824, 1234.0682784497646, 1234.370471059748, 1237.07219585311, 13204.735905281841, 13347.866521334856, 13439.059265851492, 13500.379954405338, 13565.0, 289292.0, 289805.0, 289816.0, 290010.0, 290155.0, 56407.71796140993, 56536.71731641315, 56571.0, 56702.71648641813, 56807.14789278164, 1516.9772453413207, 1529.6481809184172, 1565.71034358644, 1565.8590726834639, 1581.8734501239874, 25045.49909001846, 25226.621600675637, 25604.61593076068, 25634.615480768178, 25828.612570811827, 40045.1990960185, 40102.39846402248, 40169.79915100406, 40221.0, 40253.597464024984, 245030.0, 245739.0, 247063.0, 247432.0, 248580.0, 35496.29007419785, 37884.0, 38516.0, 38719.80640096781, 38762.224755505296, 49852.0, 50446.24330635117, 50532.24201637052, 50938.490615093375, 51174.488255118464, 37182.628173719, 37250.25499490049, 37292.627073728916, 37513.0, 37642.43536346902, 36665.26669466649, 37008.814955925045, 37032.62967370292, 37241.0, 37313.44029839609, 43569.0, 43819.56180438155, 43910.78044609757, 44287.778561106985, 44292.11415771602, 35016.47475287822, 35488.0, 35595.46606800952, 35809.0, 35907.102322441104, 26354.73645263523, 27028.45943081167, 27161.0, 27338.179854604394, 27511.86244068767], "value": 18944.45340760047, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.3180517185594, 617.7405489694329, 621.4220774679549, 623.1929651101823, 634.3814780588926, 22764.65853012202, 23289.417764555896, 24134.275971720854, 24139.879300603516, 24152.999999999956, 21493.785062149374, 22996.425089372733, 23027.42431439215, 23033.654495182545, 23035.539289214248, 6715.764948226813, 6775.1531058617675, 6797.11637487126, 6813.148356455443, 6844.076049733272, 6606.207255129391, 6849.41779948705, 6877.312268773133, 6884.655767211644, 6891.448684105285, 6823.454123670119, 6829.214640316352, 6839.418649414805, 6839.692213850364, 6842.384185423311, 6784.253732089466, 6811.386975172209, 6842.384185423336, 6845.965770171118, 6847.417969472601, 6321.0834429008, 6338.429541341256, 6377.8405539861415, 6378.138951241615, 6385.648789316586, 28932.0, 29949.101526954226, 30298.69701303003, 30440.54339184914, 30525.542116868266, 2339.2865176121277, 2348.272035668931, 2362.7282862470865, 2371.253055287596, 2372.727136379304, 20587.4853128673, 21616.0, 21661.0, 21675.34973950784, 21682.457938551506, 2446.645236440706, 2500.062476571286, 2538.695356557225, 2539.2382285314334, 2566.230130960704, 132146.0, 136336.31831840877, 153214.0, 160252.0, 167132.0, 15875.364985400454, 16427.83572164287, 16525.0, 16971.490855274362, 16986.490405287863, 25708.100216492334, 25869.35326616812, 25881.09416170425, 25933.22200333993, 25974.480510390065, 10849.891501084889, 10944.17918656104, 10991.945040274748, 11177.66467005991, 11182.608608698656, 9854.11312981835, 9996.550155243032, 10030.097291243823, 10062.899371006197, 10149.188064954933, 9555.0, 10089.545970431349, 10199.603093273076, 10226.0, 10313.484325783831, 2898.5507246376847, 2929.770359907555, 2972.0043785331723, 3068.7238148566735, 3083.7070478304527, 1047.418682631147, 1049.228816819637, 1049.3022140276782, 1050.7635781949152, 1061.8991195836384, 20739.0, 20922.267720629705, 21064.473388165112, 21083.367498975054, 21463.570728585342, 1110.622388387952, 1114.053328944936, 1115.8214685650257, 1117.664700589836, 1124.8537690100234, 11505.252158609663, 11566.248193867372, 11632.302061876146, 11648.767024659459, 11744.765104697859, 583518.0, 590081.0, 592826.0, 597361.0, 597627.0, 128236.0, 132949.0, 134104.0, 136238.0, 136510.0, 1251.69959209789, 1256.9497220111093, 1264.3551788587886, 1280.564608033273, 1297.6496345986532, 18528.258869645062, 18646.44060678182, 18712.438626841216, 18729.0, 18924.905375473034, 59869.0, 60820.78358432895, 62257.0, 63372.0, 63429.68285158544, 79326.6033669851, 79832.20167798248, 79921.60039199766, 80078.59960700393, 80649.59675201586, 15437.768433473282, 15682.800087602158, 15818.525444236691, 15828.0, 15830.208489575705, 23233.419164521023, 23298.65052024187, 23407.0, 23754.643680344463, 24008.759912400652, 19358.128884200527, 19412.417627471197, 19530.0, 19638.214471421554, 19731.704024439932, 16011.519654410386, 17470.30118795234, 17651.205695743465, 17954.820451795316, 18043.368482103062, 19073.332433364765, 19569.902150489157, 19746.506337341107, 19827.801721982596, 19918.90040549788, 15371.0, 15373.077615343114, 15405.537833865003, 15410.69178616399, 15497.690046199239, 18918.621627567645, 19161.233550657817, 19213.23147074157, 19362.90318548398, 19401.2239510418], "value": 12806.454443078508, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.7707566789313, 1497.8352381238062, 1510.1014896136794, 1526.8854835887312, 1527.6486408126132, 17126.314947402105, 17532.737008944878, 17653.20560574774, 17743.822561774377, 17832.0, 17508.649827003454, 17585.208665610047, 17592.120393980313, 17601.55996100095, 17623.471295861145, 2653.9336516587045, 2685.1541764344265, 2720.91837244883, 2727.972720272792, 2728.2224565998695, 2756.503829310723, 2786.888524459029, 2799.566067259578, 2809.1853362524844, 2815.5635876439183, 2714.9185524434297, 2731.617573539706, 2798.8320700757918, 2801.0476438011074, 2805.0322638689618, 2741.300968253103, 2749.8350098994124, 2757.241758516404, 2767.4188420431633, 2769.2938300733185, 2508.385445565848, 2606.504764094816, 2612.203278000209, 2715.0633031604066, 2715.5383584790434, 17341.219645116, 17354.045527495982, 17361.218745156373, 17362.39231626887, 17397.304107835673, 8193.918060819435, 8426.78933026673, 8457.281131103802, 8465.111163327885, 8472.0, 5347.518723314882, 5451.618386712932, 5509.807156749534, 5555.694436805974, 5560.7219639017885, 2111.5249068959515, 2121.9151233950624, 2129.606022885771, 2158.7733288004847, 2177.499175189715, 31444.685553144634, 31453.84273078643, 31495.685043149504, 31505.21236969071, 31535.053948381585, 7191.316824901626, 7828.138904720477, 8034.15641357649, 8041.396895232764, 8067.798305042421, 19879.60240795176, 19943.202271909253, 19952.800471995386, 20120.094595743227, 20149.39551813448, 8317.96025496813, 8517.0, 8535.189157030072, 8558.61486233121, 8577.742667719978, 7781.144074151839, 7850.960745196237, 7874.685012599546, 7885.960570197227, 7906.683732650745, 5482.17767334903, 5611.663300202001, 5613.1587326859235, 5650.378458369576, 5658.773649054074, 1552.0, 1566.7963164788505, 1567.388718399825, 1573.858352748258, 1581.3595493825019, 933.1042199488567, 941.9199368053655, 954.9638642073428, 963.2438535749543, 967.2213867836459, 8483.872741908875, 8487.448315859448, 8528.872066919, 8631.136886311318, 8631.827363452696, 777.5917643237345, 805.1706742055692, 819.6885183630183, 819.8852160697508, 824.5959479854948, 5394.0, 5462.672239665632, 5533.142362933751, 5592.888142237214, 5916.230889984275, 24184.0, 24995.0, 25105.87447062753, 25642.74357256441, 25651.10221142251, 24827.62758558587, 25017.249482515555, 25209.495810084063, 25614.487710245692, 25651.230463086136, 12763.425645845959, 12885.355732213351, 12908.483660653657, 12948.935255323662, 12961.935190324177, 9621.27840411958, 9708.85436718464, 9745.12293893553, 9866.013398660076, 9953.850692239477, 21358.466038349165, 21568.568628627025, 21666.35000949974, 22053.779462205173, 22379.216727414463, 21888.78111218931, 22357.441063973532, 22386.440338991655, 22414.103435863035, 22574.774252257266, 12584.748305033665, 12622.810657840324, 12673.30296833683, 12677.429515671633, 12682.429290682127, 15469.613259668598, 15594.298256578708, 15732.528024158835, 15877.285522151762, 15903.443379481663, 12177.086718496326, 12414.317212553397, 12477.438515266653, 12509.061820363691, 12775.61673149807, 11572.537098515964, 11691.649250522498, 11773.470193841127, 11898.643040708792, 12062.457189426672, 10673.092787113183, 10870.347779133277, 10901.727456813644, 10904.182186336215, 10908.290961087503, 8258.710945116893, 8361.790955225924, 8383.28742056932, 8426.705065322683, 8486.40595158333, 8286.46138001028, 8345.374096942633, 8354.248117669438, 8492.61783219769, 8531.786705332417], "value": 7285.993025730644, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [542.427738735634, 568.5962966293931, 570.549266079797, 578.3118089473526, 586.2173997713055, 6325.4307112359875, 6349.904751428732, 6354.714037868295, 6369.681515924209, 6381.393767592077, 6178.845528861781, 6179.0, 6212.62724236545, 6237.220347456568, 6266.780662676807, 5121.897562048747, 5132.589392848563, 5142.640015198937, 5168.974155129219, 5196.740162991854, 4826.1795494765975, 4966.553010229079, 5140.280360749498, 5167.147420675584, 5174.948250517485, 4923.261510773395, 4949.257611358289, 4953.108440480712, 4996.725180115111, 5005.399352077738, 4956.306117143603, 4969.180085285923, 4998.325226094504, 4999.200127979506, 5001.549860512571, 4507.008458139204, 4513.201814874798, 4522.977385113053, 4525.094981003813, 4585.816567337302, 6177.289611694623, 6212.689365531705, 6218.409251121137, 6226.0, 6233.657148856811, 5855.268091488564, 5890.823275301748, 5896.911546326808, 5920.437558431942, 5938.346781853993, 6578.111954886077, 6605.834854128637, 6618.768343107968, 6678.09845670838, 6687.297833727486, 5000.449950505442, 5033.245013248042, 5044.798208071673, 5072.873178170538, 5103.18349064148, 6618.635975021371, 6724.562903411263, 6786.864262714718, 6853.143357080365, 6895.931130674753, 5188.766505507261, 5200.557952574073, 5206.71363075027, 5211.270422140903, 5252.002119597264, 5273.446288139767, 5276.630635855453, 5277.1292736698015, 5291.920621190685, 5302.628815982845, 5842.883142337129, 5868.911966320508, 5901.793437229677, 5909.586328957018, 5917.585768996215, 6594.769183078665, 6604.207495100619, 6700.363465470772, 6706.853806781881, 6782.117600019972, 1784.1168621532424, 1792.1755992243714, 1804.621029583802, 1809.6380723855282, 1829.2500074969198, 1251.9874801252054, 1282.7947528395412, 1290.9031822613342, 1302.7524770293614, 1305.771489989248, 6122.724477398528, 6155.661438620829, 6189.442950134419, 6198.535109866778, 6204.906926396107, 6482.870342593216, 6667.299933507009, 6724.764633237911, 6741.460683145337, 6752.0, 6076.149339092531, 6093.908591371133, 6100.206973093558, 6109.84725381869, 6112.694365281718, 3505.964940350615, 3509.1402606361094, 3518.313928783914, 3531.94702079469, 3565.625609311037, 6973.651317434108, 7001.824954376181, 7090.858182836314, 7127.643617819088, 7146.964265178744, 6860.588364698134, 6869.7595584155315, 6875.862482750317, 6956.756513522002, 6993.685284162123, 6878.621675807779, 6950.8609827804175, 7006.544574602634, 7024.26245244242, 7038.718451261892, 6623.801285961236, 6714.966425167843, 6722.462203023844, 6773.05177275172, 6789.355011273921, 6672.766453174309, 6676.899846502403, 6746.527743057939, 6786.660666966731, 6811.182658080864, 6918.792436226921, 6957.1651401832105, 6969.372756451944, 6982.860342793218, 6993.510454268152, 5119.385673719177, 5158.664686795346, 5175.534201921845, 5194.35070616173, 5209.905919756742, 6579.6052236864025, 6731.32686731323, 6740.831479212863, 6774.356436138559, 6779.457643388616, 6371.331010243857, 6549.967250163719, 6620.801375958729, 6623.370779775913, 6627.3372662733345, 6575.994321731918, 6576.506761992774, 6582.124094633006, 6613.107230523818, 6615.933840661532, 6394.4564712, 6429.38920802523, 6440.0, 6451.258105317948, 6463.547551671338, 6524.042787163737, 6552.313551443885, 6562.52343222771, 6604.801855944329, 6669.46644268467, 6256.405641464054, 6371.681415929278, 6464.385883341075, 6467.741290348334, 6492.513061520499], "value": 5184.3609086119495, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [959.9424034557925, 962.3936919740562, 977.4428575711843, 987.9160271376933, 1002.6841544913352, 18977.71533426998, 19216.0, 19338.323158689447, 19879.0, 20281.0, 18636.81363186368, 18819.341323053697, 18876.433706988777, 18889.905550472264, 18995.715064274013, 1043.7547176413518, 1063.5161001744227, 1066.3974854207386, 1069.6951368859877, 1071.9571217151345, 1081.783643271345, 1082.5723839083557, 1084.701707030565, 1085.207798307236, 1085.2132204151967, 1107.457345900511, 1133.0878642692628, 1133.4162906103363, 1136.4374634555866, 1144.31341195283, 1144.3705961721103, 1145.0553293532828, 1145.2098052343856, 1145.4330106597204, 1146.6731981385308, 793.8610743119931, 814.4461765999177, 822.5599304372132, 828.337330135895, 831.5925196653596, 15846.841531584652, 15994.360225590963, 15998.200089995453, 16102.43641472543, 17295.827041729674, 22617.77382226173, 22883.771162288496, 23155.76844231553, 23217.767822321897, 23376.883115584315, 18278.634427311514, 18730.34443794461, 18768.155433005548, 18897.62204755911, 19034.61930761377, 3873.399623058402, 3888.047428380065, 3917.902052448683, 3933.0757272041046, 3936.940945885814, 124864.0, 158045.0, 158615.0, 161251.0, 162079.1896040524, 18720.438386848415, 18730.531736706693, 19039.333623323117, 19065.0, 19196.328128515714, 33874.32251354959, 34685.47971780425, 34730.82634586811, 34830.82584587061, 35190.120246993516, 9955.30312878106, 10029.0, 10098.192144628412, 10129.341592796445, 10144.695659130239, 4676.55572720591, 4695.43654761424, 4710.9764451177525, 4723.881902952454, 4757.405324334458, 6069.3627169147485, 6163.322034576193, 6171.9691401543605, 6184.0, 6200.627962322275, 1154.272808130889, 1197.9520819167135, 1198.532572296805, 1208.9335086570147, 1218.1229514749366, 734.328089797829, 752.3304259209308, 766.5745511241315, 771.8224808294124, 774.8527779721948, 12042.036637069014, 12231.571894983634, 12381.876181238253, 13145.60563183106, 13363.665908352175, 741.2661465149463, 743.0785825576354, 759.9620018999028, 761.46316846623, 768.5657603454142, 6140.416660417253, 6152.569320147636, 6247.562670613104, 6266.154069200691, 6290.559660823698, 142147.0, 142280.0, 144224.0, 147698.0, 148849.0, 125749.0, 127334.1799746009, 130107.34946325207, 131212.0, 135020.0, 104369.0, 108674.45662771635, 110092.4495377518, 110715.0, 110973.44513277382, 13106.344682766014, 13467.3939672713, 13530.72938541204, 13618.319084045559, 13629.659258518617, 52590.73704631453, 53427.73286133545, 53530.732346338016, 54379.0, 54482.455175447736, 67747.66126169535, 68230.65884670743, 68483.0, 68613.0, 68740.31259687539, 18944.62110757805, 19228.807711922702, 19318.4204473866, 19324.227030918606, 19385.612287753884, 22091.226807061677, 22147.0, 22151.003204856144, 22210.0, 22245.332640020824, 18951.810481895554, 19119.23523059062, 19132.139053742336, 19255.8074419254, 19438.125284361955, 17770.822291776916, 17940.91029544844, 18042.0, 18052.54868628295, 18066.638667226318, 19276.132574033923, 19332.613347733248, 19420.902895485433, 19527.511812204808, 19643.901780491007, 13713.657158571115, 13951.790723138958, 14093.929530352283, 14402.351894164578, 14495.492657756928, 14641.633959151106, 14797.482088126864, 14907.254637267877, 14960.700785984001, 14964.850351496345], "value": 10044.14956219151, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1373.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 105.0, 105.0, 106.0, 109.0, 100.0, 107.0, 105.0, 105.0], "value": 105.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 115.0, 113.0, 117.0, 112.0, 113.0, 114.0, 112.0, 118.0], "value": 113.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 164.0, 161.0, 163.0, 164.0, 164.0, 160.0, 164.0, 164.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 73.0, 74.0, 73.0, 76.0, 73.0, 73.0, 73.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 78.0, 79.0, 78.0, 79.0, 77.0, 79.0, 79.0], "value": 78.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 114.0, 110.0, 113.0, 112.0, 115.0, 114.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 151.0, 149.0, 149.0, 149.0, 149.0, 151.0, 150.0, 152.0], "value": 149.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 99.0, 95.0, 99.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 42.0, 44.0, 44.0, 43.0, 44.0, 45.0, 44.0, 43.0, 43.0], "value": 43.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 42.0, 40.0, 49.0, 40.0, 40.0, 40.0, 40.0, 40.0, 41.0], "value": 41.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 83.0, 74.0, 70.0, 73.0, 71.0, 72.0, 73.0, 70.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 105.0, 103.0, 110.0, 108.0, 113.0, 106.0, 118.0, 109.0], "value": 109.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 152.0, 152.0, 159.0, 151.0, 155.0, 149.0, 151.0, 151.0, 160.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 60.0, 59.0, 59.0, 60.0, 62.0, 60.0, 58.0, 60.0], "value": 60.2, "unit": "score"}]}]} 12:25:53 INFO - Return code: 0 12:25:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:25:54 INFO - # TBPL SUCCESS # 12:25:54 INFO - Running post-action listener: _package_coverage_data 12:25:54 INFO - Running post-action listener: _resource_record_post_action 12:25:54 INFO - [mozharness: 2017-09-24 19:25:54.035234Z] Finished run-tests step (success) 12:25:54 INFO - Running post-run listener: _resource_record_post_run 12:25:54 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 343785472; Read time: 0; Write time: 216744 12:25:54 INFO - TinderboxPrint: CPU usage
13.1% 12:25:54 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 12:25:54 INFO - TinderboxPrint: I/O write bytes / time
343,785,472 / 216,744 12:25:54 INFO - TinderboxPrint: CPU idle
8,223.8 (86.9%) 12:25:54 INFO - TinderboxPrint: CPU user
1,189.1 (12.6%) 12:25:54 INFO - TinderboxPrint: Swap in / out
0 / 0 12:25:54 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12603392; Read time: 0; Write time: 1284 12:25:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:25:54 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 0; Write bytes: 330567680; Read time: 0; Write time: 215456 12:25:54 INFO - Running post-run listener: _upload_blobber_files 12:25:54 INFO - Blob upload gear active. 12:25:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:25:54 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 12:25:54 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'] 12:25:54 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 12:25:55 INFO - (blobuploader) - INFO - Open directory for files ... 12:25:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:25:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:25:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:56 INFO - (blobuploader) - INFO - Done attempting. 12:25:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:25:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:25:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:58 INFO - (blobuploader) - INFO - Done attempting. 12:25:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:25:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:26:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:06 INFO - (blobuploader) - INFO - Done attempting. 12:26:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:26:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:26:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:07 INFO - (blobuploader) - INFO - Done attempting. 12:26:07 INFO - (blobuploader) - INFO - Iteration through files over. 12:26:07 INFO - Return code: 0 12:26:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:26:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:26:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d359d1a4afb367704232936530c88ae6ecc5c9d5a2ca9b9c6fa9b93563e09076f4015605bd9fa37afe86d1b20fe637b1f8dbdb5e5b3414637aecdf378860883b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06ac001c0cfc19d182ec0a8cbbfb6910d3edfd97a4d43553a6aeb89b9c27a12b0f5c9593d2f828bc403df3a10a34c4ba8a1650fe3348dc1e6ea35fec39463d0d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af1cf1a00b3eab300139a707e159876e194ecfa9c26782c8853a97e8dd9e83926ae036542b45b72de12600ecd30f195f5dc1efbb55b7121b2202975df140dde", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5161b2f63bb97f17ede761b3111f255cf24ca92e0bf3747640882f4fa3699b1963504a05983ca2e045fa960c37be1c8c807085a7684f2d65d8287628a8c8d23"} 12:26:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:26:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:26:07 INFO - Contents: 12:26:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d359d1a4afb367704232936530c88ae6ecc5c9d5a2ca9b9c6fa9b93563e09076f4015605bd9fa37afe86d1b20fe637b1f8dbdb5e5b3414637aecdf378860883b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06ac001c0cfc19d182ec0a8cbbfb6910d3edfd97a4d43553a6aeb89b9c27a12b0f5c9593d2f828bc403df3a10a34c4ba8a1650fe3348dc1e6ea35fec39463d0d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af1cf1a00b3eab300139a707e159876e194ecfa9c26782c8853a97e8dd9e83926ae036542b45b72de12600ecd30f195f5dc1efbb55b7121b2202975df140dde", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5161b2f63bb97f17ede761b3111f255cf24ca92e0bf3747640882f4fa3699b1963504a05983ca2e045fa960c37be1c8c807085a7684f2d65d8287628a8c8d23"} 12:26:07 INFO - Running post-run listener: copy_logs_to_upload_dir 12:26:07 INFO - Copying logs to upload dir... 12:26:07 INFO - mkdir: /builds/slave/test/build/upload/logs 12:26:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.549956 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-24 12:26:07.612597) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-24 12:26:07.617492) ========= 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=41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d359d1a4afb367704232936530c88ae6ecc5c9d5a2ca9b9c6fa9b93563e09076f4015605bd9fa37afe86d1b20fe637b1f8dbdb5e5b3414637aecdf378860883b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06ac001c0cfc19d182ec0a8cbbfb6910d3edfd97a4d43553a6aeb89b9c27a12b0f5c9593d2f828bc403df3a10a34c4ba8a1650fe3348dc1e6ea35fec39463d0d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af1cf1a00b3eab300139a707e159876e194ecfa9c26782c8853a97e8dd9e83926ae036542b45b72de12600ecd30f195f5dc1efbb55b7121b2202975df140dde", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5161b2f63bb97f17ede761b3111f255cf24ca92e0bf3747640882f4fa3699b1963504a05983ca2e045fa960c37be1c8c807085a7684f2d65d8287628a8c8d23"} build_url:https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011363 build_url: 'https://queue.taskcluster.net/v1/task/GuT1PCFFRDO-QtxXURDi3Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d359d1a4afb367704232936530c88ae6ecc5c9d5a2ca9b9c6fa9b93563e09076f4015605bd9fa37afe86d1b20fe637b1f8dbdb5e5b3414637aecdf378860883b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06ac001c0cfc19d182ec0a8cbbfb6910d3edfd97a4d43553a6aeb89b9c27a12b0f5c9593d2f828bc403df3a10a34c4ba8a1650fe3348dc1e6ea35fec39463d0d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8af1cf1a00b3eab300139a707e159876e194ecfa9c26782c8853a97e8dd9e83926ae036542b45b72de12600ecd30f195f5dc1efbb55b7121b2202975df140dde", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5161b2f63bb97f17ede761b3111f255cf24ca92e0bf3747640882f4fa3699b1963504a05983ca2e045fa960c37be1c8c807085a7684f2d65d8287628a8c8d23"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-24 12:26:07.649118) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 12:26:07.649520) ========= 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=41bf3e9aed84707956354da8000001c5-1506279487.718251-1528083538 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004555 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 12:26:07.731599) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-24 12:26:07.731918) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-24 12:26:07.732258) ========= ========= Total master_lag: 0.27 =========