builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-070 starttime: 1505502325.98 results: success (0) revision: 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:25.976532) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:25.976945) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:25.977212) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:26.124246) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:26.124520) ========= 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=2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-15 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.6M=0s 2017-09-15 12:05:26 (54.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112038 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:26.267321) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:26.267605) ========= 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=2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020505 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:26.320613) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:26.323954) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 --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 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 --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=2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-15 12:05:26,407 truncating revision to first 12 chars 2017-09-15 12:05:26,407 Setting DEBUG logging. 2017-09-15 12:05:26,407 attempt 1/10 2017-09-15 12:05:26,407 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/31992a5c3d64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-15 12:05:26,855 unpacking tar archive at: mozilla-central-31992a5c3d64/testing/mozharness/ program finished with exit code 0 elapsedTime=0.720681 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:27.073787) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:27.074065) ========= script_repo_revision: 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:27.074426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:27.074685) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:27.091584) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-15 12:05:27.094955) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:05:27 INFO - MultiFileLogger online at 20170915 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-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', 12:05:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:05:27 INFO - 'branch': 'Firefox-Non-PGO', 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-070', 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-15 19:05:27.314941Z] 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-15 19:05:28.304746Z] Finished clobber step (success) 12:05:28 INFO - [mozharness: 2017-09-15 19:05:28.304843Z] 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": "ryanvm@gmail.com", 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 talos dromaeojs-e10s", 12:05:28 INFO - "stage_platform": "linux64", 12:05:28 INFO - "basedir": "/builds/slave/test", 12:05:28 INFO - "buildnumber": 829, 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": "KzfU9SXvTHitmwtqk5zUPw", 12:05:28 INFO - "branch": "mozilla-central", 12:05:28 INFO - "script_repo_revision": "production", 12:05:28 INFO - "revision": "31992a5c3d64ade078accdaab8ac4ffb5e43a9d7", 12:05:28 INFO - "slavename": "talos-linux64-ix-070", 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/KzfU9SXvTHitmwtqk5zUPw'}, attempt #1 12:05:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KzfU9SXvTHitmwtqk5zUPw'}, attempt #1 12:05:29 INFO - Task dependencies: fA4-U6SkTAuYHvLWxXHwTQ 12:05:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ'}, attempt #1 12:05:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 12:05:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.test_packages.json 12:05:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.crashreporter-symbols.zip 12:05:30 INFO - Running post-action listener: _resource_record_post_action 12:05:30 INFO - [mozharness: 2017-09-15 19:05:30.101543Z] Finished read-buildbot-config step (success) 12:05:30 INFO - [mozharness: 2017-09-15 19:05:30.101759Z] Running download-and-extract step. 12:05:30 INFO - Running pre-action listener: _resource_record_pre_action 12:05:30 INFO - Running main action method: download_and_extract 12:05:30 INFO - mkdir: /builds/slave/test/build/tests 12:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:30 INFO - trying https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.test_packages.json 12:05:30 INFO - Downloading https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:05:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/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/fA4-U6SkTAuYHvLWxXHwTQ/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/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:05:30 INFO - Fetch https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.common.tests.zip into memory 12:05:31 INFO - Content-Length response header: 54734997 12:05:31 INFO - Bytes received: 54734997 12:05:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/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/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:05:42 INFO - Fetch https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.talos.tests.zip into memory 12:05:43 INFO - Content-Length response header: 14508749 12:05:43 INFO - Bytes received: 14508749 12:05:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:43 INFO - trying https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 12:05:43 INFO - Downloading https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:05:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:05:44 INFO - Downloaded 61472732 bytes. 12:05:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 12:05:44 INFO - mkdir: /builds/slave/test/properties 12:05:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:05:44 INFO - Writing to file /builds/slave/test/properties/build_url 12:05:44 INFO - Contents: 12:05:44 INFO - build_url:https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 12:05:44 INFO - Running post-action listener: _resource_record_post_action 12:05:44 INFO - Running post-action listener: find_tests_for_verification 12:05:44 INFO - Running post-action listener: set_extra_try_arguments 12:05:44 INFO - [mozharness: 2017-09-15 19:05:44.710490Z] Finished download-and-extract step (success) 12:05:44 INFO - [mozharness: 2017-09-15 19:05:44.710589Z] Running populate-webroot step. 12:05:44 INFO - Running pre-action listener: _resource_record_pre_action 12:05:44 INFO - Running main action method: populate_webroot 12:05:44 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:05:44 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['tresize', 12:05:44 INFO - 'tcanvasmark']}, 12:05:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:05:44 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['dromaeo_css', 12:05:44 INFO - 'kraken']}, 12:05:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:05:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:44 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:44 INFO - 'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:05:44 INFO - 'tests': ['damp', 'tps']}, 12:05:44 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:44 INFO - 'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['damp', 'tps']}, 12:05:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:05:44 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['dromaeo_dom']}, 12:05:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:05:44 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['basic_compositor_video', 12:05:44 INFO - 'glvideo']}, 12:05:44 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:05:44 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:05:44 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:44 INFO - 'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['ts_paint_webext', 12:05:44 INFO - 'tp5o_webext']}, 12:05:44 INFO - 'other-e10s': {'tests': ['a11yr', 12:05:44 INFO - 'ts_paint', 12:05:44 INFO - 'tpaint', 12:05:44 INFO - 'sessionrestore', 12:05:44 INFO - 'sessionrestore_many_windows', 12:05:44 INFO - 'sessionrestore_no_auto_restore', 12:05:44 INFO - 'tabpaint']}, 12:05:44 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['a11yr', 12:05:44 INFO - 'ts_paint', 12:05:44 INFO - 'tpaint', 12:05:44 INFO - 'sessionrestore', 12:05:44 INFO - 'sessionrestore_many_windows', 12:05:44 INFO - 'sessionrestore_no_auto_restore', 12:05:44 INFO - 'tabpaint']}, 12:05:44 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:05:44 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:05:44 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['perf_reftest_singletons']}, 12:05:44 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['bloom_basic']}, 12:05:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:05:44 INFO - 'tsvgr_opacity', 12:05:44 INFO - 'tart', 12:05:44 INFO - 'tscrollx', 12:05:44 INFO - 'tsvg_static']}, 12:05:44 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['tsvgx', 12:05:44 INFO - 'tsvgr_opacity', 12:05:44 INFO - 'tart', 12:05:44 INFO - 'tscrollx', 12:05:44 INFO - 'tsvg_static']}, 12:05:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:05:44 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:44 INFO - 'talos_options': ['--disable-stylo'], 12:05:44 INFO - 'tests': ['tp5o']}, 12:05:44 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:05:44 INFO - 'talos_options': ['--mitmproxy', 12:05:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:05:44 INFO - '--firstNonBlankPaint'], 12:05:44 INFO - 'tests': ['tp6_google', 12:05:44 INFO - 'tp6_youtube', 12:05:44 INFO - 'tp6_amazon', 12:05:44 INFO - 'tp6_facebook']}, 12:05:44 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:05:44 INFO - 'talos_options': ['--disable-stylo', 12:05:44 INFO - '--mitmproxy', 12:05:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:05:44 INFO - '--firstNonBlankPaint'], 12:05:44 INFO - 'tests': ['tp6_google', 12:05:44 INFO - 'tp6_youtube', 12:05:44 INFO - 'tp6_amazon', 12:05:44 INFO - 'tp6_facebook']}, 12:05:44 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:05:44 INFO - 'talos_options': ['--stylo-threads=1', 12:05:44 INFO - '--mitmproxy', 12:05:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:05:44 INFO - '--firstNonBlankPaint'], 12:05:44 INFO - 'tests': ['tp6_google', 12:05:44 INFO - 'tp6_youtube', 12:05:44 INFO - 'tp6_amazon', 12:05:44 INFO - 'tp6_facebook']}, 12:05:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:05:44 INFO - 'talos_options': ['--xperf_path', 12:05:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:44 INFO - 'tests': ['tp5n']}, 12:05:44 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:44 INFO - 'talos_options': ['--disable-stylo', 12:05:44 INFO - '--xperf_path', 12:05:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:44 INFO - 'tests': ['tp5n']}}} 12:05:44 INFO - Running post-action listener: _resource_record_post_action 12:05:44 INFO - [mozharness: 2017-09-15 19:05:44.725593Z] Finished populate-webroot step (success) 12:05:44 INFO - [mozharness: 2017-09-15 19:05:44.725683Z] Running create-virtualenv step. 12:05:44 INFO - Running pre-action listener: _resource_record_pre_action 12:05:44 INFO - Running main action method: create_virtualenv 12:05:44 INFO - Creating virtualenv /builds/slave/test/build/venv 12:05:44 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:05:44 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:05:44 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:05:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:05:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:05:45 INFO - Installing distribute.............................................................................................................................................................................................done. 12:05:45 INFO - Installing pip...............done. 12:05:45 INFO - Return code: 0 12:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:05:45 INFO - Reading from file tmpfile_stdout 12:05:45 INFO - Output received: 12:05:45 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:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:05:45 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:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:45 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:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:45 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 0x29841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cf0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf4090>, '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 0x2cf4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceef90>, '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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', '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:45 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:45 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:45 INFO - Using env: {'DISPLAY': ':0', 12:05:45 INFO - 'HOME': '/home/cltbld', 12:05:45 INFO - 'LANG': 'en_US.UTF-8', 12:05:45 INFO - 'LANGUAGE': 'en_US:en', 12:05:45 INFO - 'LOGNAME': 'cltbld', 12:05:45 INFO - 'MAIL': '/var/mail/cltbld', 12:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:45 INFO - 'MOZ_NO_REMOTE': '1', 12:05:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:45 INFO - 'NO_EM_RESTART': '1', 12:05:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:45 INFO - 'PWD': '/builds/slave/test', 12:05:45 INFO - 'SHELL': '/bin/bash', 12:05:45 INFO - 'SHLVL': '1', 12:05:45 INFO - 'TERM': 'linux', 12:05:45 INFO - 'TMOUT': '86400', 12:05:45 INFO - 'USER': 'cltbld', 12:05:45 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 12:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:45 INFO - '_': '/tools/buildbot/bin/python'} 12:05:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:05:48 INFO - Downloading/unpacking pip>=1.5 12:05:48 INFO - Running setup.py egg_info for package pip 12:05:48 INFO - warning: no files found matching 'pip/cacert.pem' 12:05:48 INFO - warning: no files found matching '*.html' under directory 'docs' 12:05:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:05:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:05:48 INFO - Installing collected packages: pip 12:05:48 INFO - Found existing installation: pip 1.1 12:05:48 INFO - Uninstalling pip: 12:05:48 INFO - Successfully uninstalled pip 12:05:48 INFO - Running setup.py install for pip 12:05:48 INFO - warning: no files found matching 'pip/cacert.pem' 12:05:48 INFO - warning: no files found matching '*.html' under directory 'docs' 12:05:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:05:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:05:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:05:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:05:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:05:48 INFO - Successfully installed pip 12:05:48 INFO - Cleaning up... 12:05:48 INFO - Return code: 0 12:05:48 INFO - Installing psutil>=3.1.1 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', '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 0x29841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cf0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf4090>, '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 0x2cf4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceef90>, '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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', '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', 'psutil>=3.1.1'] 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 psutil>=3.1.1 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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 12:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:48 INFO - '_': '/tools/buildbot/bin/python'} 12:05:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:48 INFO - Downloading/unpacking psutil>=3.1.1 12:05:48 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:48 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:48 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:48 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 - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:05:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:49 INFO - Installing collected packages: psutil 12:05:49 INFO - Running setup.py install for psutil 12:05:49 INFO - building 'psutil._psutil_linux' extension 12:05:49 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:49 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:49 INFO - building 'psutil._psutil_posix' extension 12:05:49 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:49 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:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:50 INFO - Successfully installed psutil 12:05:50 INFO - Cleaning up... 12:05:50 INFO - Return code: 0 12:05:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cf0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf4090>, '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 0x2cf4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceef90>, '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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', '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:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:05:50 INFO - Using env: {'DISPLAY': ':0', 12:05:50 INFO - 'HOME': '/home/cltbld', 12:05:50 INFO - 'LANG': 'en_US.UTF-8', 12:05:50 INFO - 'LANGUAGE': 'en_US:en', 12:05:50 INFO - 'LOGNAME': 'cltbld', 12:05:50 INFO - 'MAIL': '/var/mail/cltbld', 12:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:50 INFO - 'MOZ_NO_REMOTE': '1', 12:05:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:50 INFO - 'NO_EM_RESTART': '1', 12:05:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:50 INFO - 'PWD': '/builds/slave/test', 12:05:50 INFO - 'SHELL': '/bin/bash', 12:05:50 INFO - 'SHLVL': '1', 12:05:50 INFO - 'TERM': 'linux', 12:05:50 INFO - 'TMOUT': '86400', 12:05:50 INFO - 'USER': 'cltbld', 12:05:50 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 12:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:50 INFO - '_': '/tools/buildbot/bin/python'} 12:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:05:50 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:50 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 - 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:50 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 - Downloading mozsystemmonitor-0.3.tar.gz 12:05:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:05:51 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:51 INFO - Installing collected packages: mozsystemmonitor 12:05:51 INFO - Running setup.py install for mozsystemmonitor 12:05:51 INFO - Successfully installed mozsystemmonitor 12:05:51 INFO - Cleaning up... 12:05:51 INFO - Return code: 0 12:05:51 INFO - Installing jsonschema==2.5.1 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', '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 0x29841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cf0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf4090>, '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 0x2cf4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceef90>, '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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', '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', 'jsonschema==2.5.1'] 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 jsonschema==2.5.1 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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 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 jsonschema==2.5.1 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 jsonschema-2.5.1-py2.py3-none-any.whl 12:05:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:05:52 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:52 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 - 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:52 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 functools32-3.2.3-2.tar.gz 12:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:05:53 INFO - warning: no files found matching '*.txt' 12:05:53 INFO - no previously-included directories found matching 'build' 12:05:53 INFO - no previously-included directories found matching 'dist' 12:05:53 INFO - no previously-included directories found matching '.git*' 12:05:53 INFO - Installing collected packages: jsonschema, functools32 12:05:53 INFO - Running setup.py install for functools32 12:05:53 INFO - warning: no files found matching '*.txt' 12:05:53 INFO - no previously-included directories found matching 'build' 12:05:53 INFO - no previously-included directories found matching 'dist' 12:05:53 INFO - no previously-included directories found matching '.git*' 12:05:53 INFO - Successfully installed jsonschema functools32 12:05:53 INFO - Cleaning up... 12:05:53 INFO - Return code: 0 12:05:53 INFO - Installing functools32==3.2.3-2 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', '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 0x29841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cf0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf4090>, '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 0x2cf4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceef90>, '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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', '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', 'functools32==3.2.3-2'] 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 functools32==3.2.3-2 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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 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 - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:05:53 INFO - Cleaning up... 12:05:53 INFO - Return code: 0 12:05:53 INFO - Installing blobuploader==1.2.4 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', '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 0x29841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cf0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf4090>, '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 0x2cf4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceef90>, '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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', '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', 'blobuploader==1.2.4'] 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 blobuploader==1.2.4 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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 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 blobuploader==1.2.4 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:54 INFO - Downloading blobuploader-1.2.4.tar.gz 12:05:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:05:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:05:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 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 docopt-0.6.1.tar.gz 12:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:05:55 INFO - Installing collected packages: blobuploader, requests, docopt 12:05:55 INFO - Running setup.py install for blobuploader 12:05:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:05:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:05:55 INFO - Running setup.py install for requests 12:05:55 INFO - Running setup.py install for docopt 12:05:55 INFO - Successfully installed blobuploader requests docopt 12:05:55 INFO - Cleaning up... 12:05:55 INFO - Return code: 0 12:05:55 INFO - Installing None 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', '--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 0x29841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cf0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf4090>, '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 0x2cf4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceef90>, '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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', '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', '--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:55 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: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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 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 - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:05:56 INFO - Running setup.py (path:/tmp/pip-JTwBFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:05:56 INFO - Running setup.py (path:/tmp/pip-LrXXJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:05:56 INFO - Running setup.py (path:/tmp/pip-KJl056-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:05:56 INFO - Running setup.py (path:/tmp/pip-va2BMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:05:56 INFO - Running setup.py (path:/tmp/pip-0L9Lno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:05:56 INFO - Running setup.py (path:/tmp/pip-gX_3xF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:05:56 INFO - Running setup.py (path:/tmp/pip-6uMQyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:05:56 INFO - Running setup.py (path:/tmp/pip-3uB9ZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:05:56 INFO - Running setup.py (path:/tmp/pip-h26YSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:05:56 INFO - Running setup.py (path:/tmp/pip-3UHH7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:05:56 INFO - Running setup.py (path:/tmp/pip-bffJ60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:05:56 INFO - Running setup.py (path:/tmp/pip-l1Yn6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:05:56 INFO - Running setup.py (path:/tmp/pip-4tq6eS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:05:57 INFO - Running setup.py (path:/tmp/pip-CdXErC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:57 INFO - Running setup.py (path:/tmp/pip-0Uu2xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:05:57 INFO - Running setup.py (path:/tmp/pip-rx3XF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:05:57 INFO - Running setup.py (path:/tmp/pip-NqfJfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:05:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:05:57 INFO - Running setup.py install for manifestparser 12:05:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:05:57 INFO - Running setup.py install for mozcrash 12:05:57 INFO - Running setup.py install for mozdebug 12:05:57 INFO - Running setup.py install for mozdevice 12:05:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:05:57 INFO - Running setup.py install for mozfile 12:05:57 INFO - Running setup.py install for mozhttpd 12:05:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:05:57 INFO - Running setup.py install for mozinfo 12:05:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:05:58 INFO - Running setup.py install for mozInstall 12:05:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:05:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:05:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:05:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:05:58 INFO - Running setup.py install for mozleak 12:05:58 INFO - Running setup.py install for mozlog 12:05:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:05:58 INFO - Running setup.py install for moznetwork 12:05:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:05:58 INFO - Running setup.py install for mozprocess 12:05:58 INFO - Running setup.py install for mozprofile 12:05:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:05:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:05:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:05:58 INFO - Running setup.py install for mozrunner 12:05:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:05:58 INFO - Running setup.py install for mozscreenshot 12:05:58 INFO - Running setup.py install for moztest 12:05:59 INFO - Running setup.py install for mozversion 12:05:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:05:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:05:59 INFO - Cleaning up... 12:05:59 INFO - Return code: 0 12:05:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05:59 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:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:59 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:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:59 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 0x29841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cf0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf4090>, '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 0x2cf4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceef90>, '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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', '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:59 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:05:59 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:05:59 INFO - Using env: {'DISPLAY': ':0', 12:05:59 INFO - 'HOME': '/home/cltbld', 12:05:59 INFO - 'LANG': 'en_US.UTF-8', 12:05:59 INFO - 'LANGUAGE': 'en_US:en', 12:05:59 INFO - 'LOGNAME': 'cltbld', 12:05:59 INFO - 'MAIL': '/var/mail/cltbld', 12:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:59 INFO - 'MOZ_NO_REMOTE': '1', 12:05:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:59 INFO - 'NO_EM_RESTART': '1', 12:05:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:59 INFO - 'PWD': '/builds/slave/test', 12:05:59 INFO - 'SHELL': '/bin/bash', 12:05:59 INFO - 'SHLVL': '1', 12:05:59 INFO - 'TERM': 'linux', 12:05:59 INFO - 'TMOUT': '86400', 12:05:59 INFO - 'USER': 'cltbld', 12:05:59 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 12:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:59 INFO - '_': '/tools/buildbot/bin/python'} 12:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:05:59 INFO - Running setup.py (path:/tmp/pip-C6zZ3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:05:59 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:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:05:59 INFO - Running setup.py (path:/tmp/pip-3EvO7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:05:59 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:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:05:59 INFO - Running setup.py (path:/tmp/pip-8W4D3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:05:59 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:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:05:59 INFO - Running setup.py (path:/tmp/pip-tj_1Si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:05:59 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:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:05:59 INFO - Running setup.py (path:/tmp/pip-5OQcyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:05:59 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:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:05:59 INFO - Running setup.py (path:/tmp/pip-cg5PxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:05:59 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:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:05:59 INFO - Running setup.py (path:/tmp/pip-ZIjrXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:05:59 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:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:05:59 INFO - Running setup.py (path:/tmp/pip-2nk1br-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:05:59 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:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:05:59 INFO - Running setup.py (path:/tmp/pip-o9ony9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:05:59 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:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:05:59 INFO - Running setup.py (path:/tmp/pip-a2iLi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:06:00 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:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:06:00 INFO - Running setup.py (path:/tmp/pip-pdkOee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:06:00 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:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:06:00 INFO - Running setup.py (path:/tmp/pip-xUng1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:06:00 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:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:06:00 INFO - Running setup.py (path:/tmp/pip-cy4pJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:06:00 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:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:06:00 INFO - Running setup.py (path:/tmp/pip-28FlHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:06:00 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:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:00 INFO - Running setup.py (path:/tmp/pip-cOvVEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:00 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:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:06:00 INFO - Running setup.py (path:/tmp/pip-PbovEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:06:00 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:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:06:00 INFO - Running setup.py (path:/tmp/pip-pCo_x0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:06:00 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:00 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:00 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:00 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:00 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:00 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:00 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:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:06:00 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:00 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:00 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:00 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:01 INFO - Downloading blessings-1.6.tar.gz 12:06:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:06:01 INFO - Installing collected packages: blessings 12:06:01 INFO - Running setup.py install for blessings 12:06:01 INFO - Successfully installed blessings 12:06:01 INFO - Cleaning up... 12:06:01 INFO - Return code: 0 12:06:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:06:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:06:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:06:02 INFO - Reading from file tmpfile_stdout 12:06:02 INFO - Current package versions: 12:06:02 INFO - argparse == 1.2.1 12:06:02 INFO - blessings == 1.6 12:06:02 INFO - blobuploader == 1.2.4 12:06:02 INFO - docopt == 0.6.1 12:06:02 INFO - functools32 == 3.2.3-2 12:06:02 INFO - jsonschema == 2.5.1 12:06:02 INFO - manifestparser == 1.1 12:06:02 INFO - mozInstall == 1.13 12:06:02 INFO - mozcrash == 1.0 12:06:02 INFO - mozdebug == 0.1 12:06:02 INFO - mozdevice == 0.51 12:06:02 INFO - mozfile == 1.2 12:06:02 INFO - mozhttpd == 0.7 12:06:02 INFO - mozinfo == 0.10 12:06:02 INFO - mozleak == 0.1 12:06:02 INFO - mozlog == 3.5 12:06:02 INFO - moznetwork == 0.27 12:06:02 INFO - mozprocess == 0.25 12:06:02 INFO - mozprofile == 0.28 12:06:02 INFO - mozrunner == 6.13 12:06:02 INFO - mozscreenshot == 0.1 12:06:02 INFO - mozsystemmonitor == 0.3 12:06:02 INFO - moztest == 0.8 12:06:02 INFO - mozversion == 1.4 12:06:02 INFO - psutil == 3.1.1 12:06:02 INFO - requests == 1.2.3 12:06:02 INFO - wsgiref == 0.1.2 12:06:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:02 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 0x29841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cf0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf4090>, '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 0x2cf4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceef90>, '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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', '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:02 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:02 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:02 INFO - Using env: {'DISPLAY': ':0', 12:06:02 INFO - 'HOME': '/home/cltbld', 12:06:02 INFO - 'LANG': 'en_US.UTF-8', 12:06:02 INFO - 'LANGUAGE': 'en_US:en', 12:06:02 INFO - 'LOGNAME': 'cltbld', 12:06:02 INFO - 'MAIL': '/var/mail/cltbld', 12:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:02 INFO - 'MOZ_NO_REMOTE': '1', 12:06:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:02 INFO - 'NO_EM_RESTART': '1', 12:06:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:02 INFO - 'PWD': '/builds/slave/test', 12:06:02 INFO - 'SHELL': '/bin/bash', 12:06:02 INFO - 'SHLVL': '1', 12:06:02 INFO - 'TERM': 'linux', 12:06:02 INFO - 'TMOUT': '86400', 12:06:02 INFO - 'USER': 'cltbld', 12:06:02 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 12:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:02 INFO - '_': '/tools/buildbot/bin/python'} 12:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:02 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:02 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:02 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:02 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:02 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:02 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:02 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:02 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:02 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:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:06:02 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:02 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:02 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:02 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 - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:06:03 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:03 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:03 INFO - Installing collected packages: simplejson 12:06:03 INFO - Running setup.py install for simplejson 12:06:03 INFO - building 'simplejson._speedups' extension 12:06:03 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:04 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:04 INFO - Successfully installed simplejson 12:06:04 INFO - Cleaning up... 12:06:04 INFO - Return code: 0 12:06:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:06:04 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:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:04 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:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:04 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 0x29841f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cf0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cf4090>, '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 0x2cf4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceef90>, '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': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', '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:04 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:04 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:04 INFO - Using env: {'DISPLAY': ':0', 12:06:04 INFO - 'HOME': '/home/cltbld', 12:06:04 INFO - 'LANG': 'en_US.UTF-8', 12:06:04 INFO - 'LANGUAGE': 'en_US:en', 12:06:04 INFO - 'LOGNAME': 'cltbld', 12:06:04 INFO - 'MAIL': '/var/mail/cltbld', 12:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:04 INFO - 'MOZ_NO_REMOTE': '1', 12:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:04 INFO - 'NO_EM_RESTART': '1', 12:06:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:04 INFO - 'PWD': '/builds/slave/test', 12:06:04 INFO - 'SHELL': '/bin/bash', 12:06:04 INFO - 'SHLVL': '1', 12:06:04 INFO - 'TERM': 'linux', 12:06:04 INFO - 'TMOUT': '86400', 12:06:04 INFO - 'USER': 'cltbld', 12:06:04 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 12:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:04 INFO - '_': '/tools/buildbot/bin/python'} 12:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:06:04 INFO - Cleaning up... 12:06:04 INFO - Return code: 0 12:06:04 INFO - Running post-action listener: _resource_record_post_action 12:06:04 INFO - Running post-action listener: _start_resource_monitoring 12:06:04 INFO - Starting resource monitoring. 12:06:04 INFO - [mozharness: 2017-09-15 19:06:04.823800Z] Finished create-virtualenv step (success) 12:06:04 INFO - [mozharness: 2017-09-15 19:06:04.824262Z] Running install step. 12:06:04 INFO - Running pre-action listener: _resource_record_pre_action 12:06:04 INFO - Running main action method: install 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 - Detecting whether we're running mozinstall >=1.0... 12:06:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:06:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:06:05 INFO - Reading from file tmpfile_stdout 12:06:05 INFO - Output received: 12:06:05 INFO - Usage: mozinstall [options] installer 12:06:05 INFO - Options: 12:06:05 INFO - -h, --help show this help message and exit 12:06:05 INFO - -d DEST, --destination=DEST 12:06:05 INFO - Directory to install application into. [default: 12:06:05 INFO - "/builds/slave/test"] 12:06:05 INFO - --app=APP Application being installed. [default: firefox] 12:06:05 INFO - mkdir: /builds/slave/test/build/application 12:06:05 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:05 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:22 INFO - Reading from file tmpfile_stdout 12:06:22 INFO - Output received: 12:06:22 INFO - /builds/slave/test/build/application/firefox/firefox 12:06:22 INFO - Running post-action listener: _resource_record_post_action 12:06:22 INFO - [mozharness: 2017-09-15 19:06:22.998463Z] Finished install step (success) 12:06:22 INFO - [mozharness: 2017-09-15 19:06:22.998716Z] Running setup-mitmproxy step. 12:06:22 INFO - Running pre-action listener: _resource_record_pre_action 12:06:22 INFO - Running main action method: setup_mitmproxy 12:06:22 INFO - Skipping: mitmproxy is not required 12:06:22 INFO - Running post-action listener: _resource_record_post_action 12:06:22 INFO - [mozharness: 2017-09-15 19:06:22.999832Z] Finished setup-mitmproxy step (success) 12:06:23 INFO - [mozharness: 2017-09-15 19:06:23.000118Z] Running run-tests step. 12:06:23 INFO - Running pre-action listener: _resource_record_pre_action 12:06:23 INFO - Running pre-action listener: _set_gcov_prefix 12:06:23 INFO - Running main action method: run_tests 12:06:23 WARNING - Try message not found. 12:06:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:06:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:06:23 INFO - Python 2.7.3 12:06:23 INFO - Return code: 0 12:06:23 INFO - grabbing minidump binary from tooltool 12:06:23 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:23 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 0x2cf4090>, '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 0x2cf4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ceef90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:06:23 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:23 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:23 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:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:06:23 INFO - Return code: 0 12:06:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:06:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:06:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:06:23 INFO - ENV: RUST_BACKTRACE is now full 12:06:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:06:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:06:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-070', '--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:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-070 --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:23 INFO - Using env: {'DISPLAY': ':0', 12:06:23 INFO - 'HOME': '/home/cltbld', 12:06:23 INFO - 'LANG': 'en_US.UTF-8', 12:06:23 INFO - 'LANGUAGE': 'en_US:en', 12:06:23 INFO - 'LOGNAME': 'cltbld', 12:06:23 INFO - 'MAIL': '/var/mail/cltbld', 12:06:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:06:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:23 INFO - 'MOZ_NO_REMOTE': '1', 12:06:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:06:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:23 INFO - 'NO_EM_RESTART': '1', 12:06:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:23 INFO - 'PWD': '/builds/slave/test', 12:06:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:06:23 INFO - 'RUST_BACKTRACE': 'full', 12:06:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:06:23 INFO - 'SHELL': '/bin/bash', 12:06:23 INFO - 'SHLVL': '1', 12:06:23 INFO - 'TERM': 'linux', 12:06:23 INFO - 'TMOUT': '86400', 12:06:23 INFO - 'USER': 'cltbld', 12:06:23 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 12:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:23 INFO - '_': '/tools/buildbot/bin/python'} 12:06:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-070', '--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:23 INFO - mozversion application_buildid: 20170915181541 12:06:23 INFO - mozversion application_changeset: 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 12:06:23 INFO - mozversion application_display_name: Nightly 12:06:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:06:23 INFO - mozversion application_name: Firefox 12:06:23 INFO - mozversion application_remotingname: firefox 12:06:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:06:23 INFO - mozversion application_vendor: Mozilla 12:06:23 INFO - mozversion application_version: 57.0a1 12:06:23 INFO - mozversion platform_buildid: 20170915181541 12:06:23 INFO - mozversion platform_changeset: 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 12:06:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:06:23 INFO - mozversion platform_version: 57.0a1 12:06:23 INFO - using testdate: 1505502383 12:06:23 INFO - actual date: 1505502383 12:06:23 INFO - starting webserver on 'localhost:60588' 12:06:23 INFO - SUITE-START | Running 2 tests 12:06:23 INFO - TEST-START | dromaeo_css 12:06:23 INFO - Initialising browser for dromaeo_css test... 12:06:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60588/getInfo.html -profile /tmp/tmpxoBifO/profile 12:06:23 INFO - TEST-INFO | started process 9387 (/builds/slave/test/build/application/firefox/firefox http://localhost:60588/getInfo.html) 12:06:28 INFO - TEST-INFO | 9387: exit 0 12:06:28 INFO - Browser initialized. 12:06:28 INFO - Running cycle 1/1 for dromaeo_css test... 12:06:28 INFO - Using env: {'DISPLAY': ':0', 12:06:28 INFO - 'HOME': '/home/cltbld', 12:06:28 INFO - 'JSGC_DISABLE_POISONING': '1', 12:06:28 INFO - 'LANG': 'en_US.UTF-8', 12:06:28 INFO - 'LANGUAGE': 'en_US:en', 12:06:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:06:28 INFO - 'LOGNAME': 'cltbld', 12:06:28 INFO - 'MAIL': '/var/mail/cltbld', 12:06:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:06:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:06:28 INFO - 'MOZ_CRASHREPORTER': '1', 12:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:06:28 INFO - 'MOZ_NO_REMOTE': '1', 12:06:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:06:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:28 INFO - 'NO_EM_RESTART': '1', 12:06:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:28 INFO - 'PWD': '/builds/slave/test', 12:06:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:06:28 INFO - 'RUST_BACKTRACE': 'full', 12:06:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:06:28 INFO - 'SHELL': '/bin/bash', 12:06:28 INFO - 'SHLVL': '1', 12:06:28 INFO - 'TERM': 'linux', 12:06:28 INFO - 'TMOUT': '86400', 12:06:28 INFO - 'USER': 'cltbld', 12:06:28 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 12:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:28 INFO - '_': '/tools/buildbot/bin/python'} 12:06:28 INFO - TEST-INFO | started process 9618 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxoBifO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:06:29 INFO - PID 9618 | 12:06:29 INFO - PID 9618 | (/builds/slave/test/build/application/firefox/firefox:9668): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:06:29 INFO - PID 9618 | 12:06:40 INFO - PID 9618 | RSS: Main: 173772800 12:06:40 INFO - PID 9618 | 12:09:37 INFO - PID 9618 | Cycle 1(1): loaded http://localhost:60588/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60588/tests/dromaeo/cssquery-ext.html) 12:09:38 INFO - PID 9618 | RSS: Main: 175722496 12:09:38 INFO - PID 9618 | 12:12:40 INFO - PID 9618 | Cycle 1(1): loaded http://localhost:60588/tests/dromaeo/cssquery-ext.html (next: http://localhost:60588/tests/dromaeo/cssquery-jquery.html) 12:12:41 INFO - PID 9618 | RSS: Main: 172683264 12:12:41 INFO - PID 9618 | 12:15:43 INFO - PID 9618 | Cycle 1(1): loaded http://localhost:60588/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60588/tests/dromaeo/cssquery-mootools.html) 12:15:43 INFO - PID 9618 | RSS: Main: 174620672 12:15:43 INFO - PID 9618 | 12:18:45 INFO - PID 9618 | Cycle 1(1): loaded http://localhost:60588/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60588/tests/dromaeo/cssquery-prototype.html) 12:18:46 INFO - PID 9618 | RSS: Main: 172916736 12:18:46 INFO - PID 9618 | 12:21:49 INFO - PID 9618 | Cycle 1(1): loaded http://localhost:60588/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60588/tests/dromaeo/cssquery-yui.html) 12:21:49 INFO - PID 9618 | RSS: Main: 174661632 12:21:49 INFO - PID 9618 | 12:24:51 INFO - PID 9618 | Cycle 1(1): loaded http://localhost:60588/tests/dromaeo/cssquery-yui.html (next: http://localhost:60588/tests/dromaeo/cssquery-dojo.html) 12:24:51 INFO - PID 9618 | RSS: Main: 178139136 12:24:51 INFO - PID 9618 | 12:24:51 INFO - PID 9618 | __start_tp_report 12:24:51 INFO - PID 9618 | _x_x_mozilla_page_load 12:24:51 INFO - PID 9618 | _x_x_mozilla_page_load_details 12:24:51 INFO - PID 9618 | |i|pagename|runs| 12:24:51 INFO - PID 9618 | |0;dojo.html;3140.152158917092;3143.4656108461572;3166.2242750526116;3172.8096314221148;3176.031310450315;6106.603070800395;6247.312795592481;6310.400511951369;6337.429631333179;6370.840728981778;6205.317415084337;6224.346443623411;6242.18851549298;6257.780977665782;6372.458341041017;5817.069268916975;5847.181394604748;5866.914620795166;5888.234529511179;5901.616394934337;5709.885802283972;5766;5812.273465816773;5880.3825598312205;5885.529157667398;5755.884882302331;5834.912476312858;5894.469497745224;5896.292444906617;5899.5870289079785;5624.55003599711;5685.630434021817;5693.686847223401;5743.8564035899435;5828.358880523139;5733.082706766939;5744.655320680772;5745.253117094751;5783.479486846162;5789.768409263625;6205;6209.627422354673;6279.277883043463;6295.433410993032;6350.714217860207;6507.609543427409;6626.006099085128;6663.900041499382;6735.191776986744;6759.087523184406;6685.76599819004;6691.531592788507;6697;6702.865942681168;6714.496412769062;6298.118263443122;6357.268914074894;6361.332060133712;6363.045543168516;6363.459105975951;6891.827704307332;6896.551724138016;6906.274841141708;6916.861662766716;6971.093757811452;6397.072424498421;6560.179977502812;6596.80209593713;6603.20761508622;6676.465882729467;6727.562708424035;6746.730130794714;6769.72921083161;6805.285445028201;6820.420264277592;6495.0582165586675;6590.077389165521;6656.068150458939;6741.426978706767;6759.662016899136;6570.080188773575;6606.768763093365;6614.040964060185;6623.039659249382;6623.337666233435;5211.009908117446;5225.6603320784025;5232.633715639945;5233.6074794389815;5236.842894713165;4570.428696412949;4572.588467037983;4580.541945805459;4596.080783843245;4688.882777930579;5787.045137552341;5831.620944638585;5863.5309175265875;5868.354481007086;5894.498967587718;6732.158480189976;6745.831354216184;6827.965860170667;6841.452683785285;6846.8972965405555;5768.884622307614;5785.971070144707;5850.385709500526;5860.531157507389;5861.3552509223955;5632.324121105494;5700.9429905701245;5701.828945131652;5702.914456283159;5713.971430142823;6384;6396.0405939109505;6412.070249813751;6416.903746443807;6417.390347917034;6675.332466753286;6676.89984650221;6683.5989840608745;6695.464362850863;6723.697433615401;6505.186851643544;6509.349065093453;6550.803475895731;6559.278479367361;6584.868302634016;6633.469322454289;6637.269900311058;6644.667766611554;6728.461723062242;6731.899021514779;6594.571352862049;6693.497987650849;6791.185057793096;6852;6870.931290687229;6474.935250647622;6492.35076492347;6515.381038801306;6520.217573891164;6560.147180866456;6545.639989800607;6590.769323073861;6615.073889655549;6628.734850605922;6634.137562116894;6463.321351258238;6470.870582588416;6540.117084193763;6549.345065493603;6551.115599394021;6384.106225128578;6517.9348206517325;6580.045893345534;6595.340465953366;6698.095757072928;6301.928672125658;6420.3258657842125;6420.903686444801;6424.164858568356;6435.646039467874 12:24:51 INFO - PID 9618 | |1;ext.html;856.2507805670039;886.4371124336046;888.1961824548786;890.1454603580562;890.5992303463443;38931.22137557251;42347;42643;42977.78511107444;43103.35344969828;39876.80061599688;39963;40055.19889602206;40110.7994460028;40217;7091.361777440029;7219.494635375526;7243.203247642781;7260.419166466696;7263.636818159097;7047.260037696045;7073.9292607073785;7134.215236324;7149.177844547866;7149.428045756353;7127.572345659251;7162.140543134805;7164.820879478003;7168.677409516558;7721.189275126087;7783.066032076159;7883.605819709021;7884.842303153905;7886.960565197137;7887.447878648497;7684.538927664302;7721.22787721229;7743.574103424309;7749.108852481924;7765.41759368044;38374.23251534982;39095.80452097721;39442.802785985885;39521.20957580832;39545.80227098875;3529.29414117175;3543.273628906068;3557.5908770491465;3583.7491375603718;3586.7309951753464;32237.838810805795;32294;32319.6768032319;32455.188620284684;32597.837010814794;5170.5346518813485;5175.896482070375;5189.636725429222;5190.71451070191;5227.8170264040955;175920;176019;176690.11654941773;176740;177589;42216.78891605584;43342.349864752054;48575;48791.51208487941;48868.7556562222;52185.7390713044;52296.738516307174;52297.215541766906;52359.47640523622;52396;14783;14841.109533428029;14848.62878428048;14866.554003379915;14898.106113633217;11955.16313858039;11984.760304793856;12015.279083255033;12032;12089.879101209051;14234.14595124296;14340.21128837924;14373.856261437251;14412.855871441361;14473;5727.914081288784;5779.942200578024;5871.6770577618645;5955.255593050869;6049.78825741097;1001.409168590535;1002.333448256901;1011.489197955028;1016.7356487313203;1021.581151727788;22506.66240006401;22646.773532264797;22754.65868011981;22771.203007894645;22887.771122288897;1107.396468924438;1110.1081682217869;1116.0736588631416;1120.921535492524;1130.559081958037;12042.698932526757;12123.696907577381;12251.75496490083;12294.200876943149;12336.568220112433;217296;217512;217597;217739;218834;53167.734161328946;53598.73200633972;54005.45994540004;54033.45966540363;54143.18785218225;1426;1451.8765904897991;1452.4988878836784;1487.851214878525;1489.3074720255133;21859.56280874342;22027.559448811255;22301.442463938532;22595.5480890378;22664.433389165402;33539.329213415105;34313;37718.62281377261;37772.62227377801;37819.05452363603;184440;185211;185387;185550;186368;31095;31909;32034.19914502156;34860.47709284312;35195.472067919516;42627.57372426236;42754.78622606867;42861.78569107259;43083.353749694405;43757.78121109501;31500.527492088095;31557.684423155475;31803.20491987719;32193.678063219068;32229.838850805594;31425.52861707122;31451.213719657193;31590;31702.365952681277;32137;35573;35628.64371356253;35689.64310356967;35774.4633830498;35901.281974359845;30791;31077.22306942254;31135.84432077825;31293.217669558442;31711.52432713465;23016.194433194756;23038.769612303662;23053.847307634886;23071.6539251908;23152 12:24:51 INFO - PID 9618 | |2;jquery.html;565.2708006671394;567.9346875109361;576;581.2763109928139;595.4938302442923;21819.345419637437;21888.45278868029;22579;22717.999999999978;22762.65856012161;25062.874685626546;25290.36774080649;25620;25624.615630765507;25638.487230255385;6470.38531339522;6625.73497060117;6641.236257830363;6646.0363247329105;6646.335366463363;6500.6099634021875;6543.901841472381;6837.726490940357;7199.388052015585;7237.167725711532;7166.355028047475;7170.7490237841685;7171.390431813302;7178.389836863873;7194.4963852530345;7116.964415177891;7133.750318738845;7134.821629459279;7154.391876690487;7175.21072682002;6981.197162326296;6990;7002.369786719169;7033.859322813566;7060.364567188926;30628.234294142603;31070;32115.197120072185;32374.190645233823;32386.19034524132;2092.1422216891;2130.3076500137518;2135.338045205991;2137.1878686099185;2137.732783402075;20877.37367878966;21425.464363390885;21427.357179284492;21446.356609301747;21488;2491.9626205606933;2500.062476571286;2598.155599430174;2616;2616.2151354593548;129902;136297;136304;136391.31804341014;136406;20411.59176816485;21534.461638459165;21551.2457064002;21568.4607884801;21575.56848863014;41740;41821.37267940983;41833;41895;41914.58085419107;10827.783444331228;11004.284721493077;11049.558017679363;11205.103591712643;11225.887741122648;8238.970128733908;9049.683261085831;9141.268698504104;9145.628155776554;9249.630014799468;9721.902780972241;11543.134264930164;11741.765164696659;11873.346965916784;11968.88031119695;4198.244316023084;4298.591633794785;4349.238883195429;4366.737995720267;4403.581659742319;956.6508224498008;973.6223244109506;981.3670182732241;984.867042949207;995.1590905684685;19220;19757.209711611664;19954.401367958693;20075;20235.696464553042;1021.9080282774586;1022.4999875304957;1031.169908223872;1031.5873650539845;1035.471909326234;10751;10867.83698244527;11033.558657653764;11127.165462590337;11325.093992480583;470706;484316;484365;491486;493898;105101.47449262705;105476;106648.9335106639;106931.46534267279;108597;1131.055568600228;1153.3310679805693;1158.3918442817758;1163.1625229834506;1173.1318824070127;17411.129443528025;17632;17698.469045928643;17766.733498997783;17770.644587107927;51026;51063.489365105874;51355;53328.73335633297;53475;65403.67298163479;65988;66075;66145;66613.33386666265;19617.117229724412;20103.497412564804;20238.08928598187;20244.59510809805;20259.392218233475;22484.775152248923;22662.093516259163;22740.658890116993;22816.429589260402;23221.76778232196;18001.279948802425;18153.909230453763;18323.450296491126;18456.630867382846;18536.536586585444;16286.755698664292;16320.755188672418;16331.755023674892;16627.251773670458;17180;18691.25234990585;18884;18938.810611893703;19021.144048517574;19031.714524282423;18575.44273671792;19734.506637334183;19757.209711611373;20026.499337516096;20073.397798066082;17758.91120544441;17850.196741146938;18026.729599056285;18028.819711802713;18111.63776724432 12:24:51 INFO - PID 9618 | |3;mootools.html;1331.8468376136743;1379.0898007315175;1391.3182540555124;1400.1948879394345;1412.3573773932862;14777.113373197615;15326;15374.461893833704;15374.846251537481;15377.615559611015;14980.176090315028;15025.248737563106;15077.92461037696;15133.54599362021;15136.167510786903;2719.0891051497774;2838.2194896403553;2839.7728181745515;2841.374897522552;2846.5018621741215;2640.8019398545184;2760.7239276072332;2762.8342299462097;2763;2776.1671498550454;2757.5036493431176;2828.321202911307;2831.5469524876025;2840.1621521651164;2841.4175094105763;2809.985950070247;2821.3228825082037;2829.688734239232;2836.7021462746425;2838.957415638767;2726.495598314318;2730.699623041464;2733.644626198601;2735.9179224623294;2739.0413355325477;14657.193854338007;15087.773683394758;15102.622434439118;15118.697626047417;15119.470818521298;7945.165757595427;8208.343332533443;8275.172482751737;8293.95853020737;8296.460730052588;5267.55225805807;5326.201069839516;5385.192221166817;5527.419620939824;5649.350324712669;1868.9345872894382;1880.8025157358418;1919.673655478572;1958.8824670519814;1960.127743154297;26569.070082547212;26785.33036674099;26798.464030719275;26816.731832681813;26857.46285074307;6654.434373078246;6716.83207919806;6799.558028728117;6902.93097069033;6989.790306290819;16983.23575439108;17002;17019.48941531756;17045.914770426316;17072.317107315568;7499.8500029999095;7640.159582445926;7640.350570201484;7644.120926093459;7903.130655627876;6935.4104901082965;7215.747448839265;7336.33972942427;7382.446316526282;7405.1854296027395;5106.004329155853;5265.262863199156;5313.867153321198;5339.119045357473;5372.677639341652;1400.7128538649552;1433.8781203597812;1556.3852278350214;1576.5506830553238;1582.8654564362157;914.5975770661021;934.0192797562532;934.6027938126269;938;948.6158105966956;7822.139564647884;7876.684932602631;7904.881426778603;7952.3638108951145;7960.2039796019935;947.8720372749729;966.7389804752818;977.9070988256104;979.6571200079915;1021.2238698589126;4765.213739732905;4790.185668436374;4842.297866809293;4858.6356023298395;4936.90126197474;21403.67894481584;22163.556728865333;22238.888805555867;22415.439614009454;22510.212142574932;20545.897270513553;21503.78496215049;21616.459588510414;21676.45808854791;21804.781952180594;11109.333439993627;11172.832407513732;11182.720431989103;11263.324200547993;11294.152938529644;8235.464694794819;8492.74521764348;8570.357223208159;8584.180039882192;8701.129887011248;18115.81884181141;18686.159122839777;18777.248910043993;19043.42869713911;19106.426807195807;18759.249630014645;19031.714524282423;19161.520961975504;19223.615527689646;19228;11175.608853690082;11379.772404551697;11391.145664075393;11394.943025285153;11425.485853136795;13749.175049496662;14016.509422170582;14175.787363189767;14177.858221417653;14231.146131232159;11395.259308144945;11495.655130346104;11679.474423650787;11864.11019173548;12042.21725587834;11202.047825934887;11494.885051149382;11616.709582260512;11628.94185529067;11808.291502509534;10075.09324160829;10092.293539452461;10208.897911020795;10239.129673977497;10242;7458.216887226763;7519.511231769918;7549.1695913448375;7554.546727196386;7613.124490683421;7500.549967001997;7590.506617069873;7601.0878694557005;7681.116671582839;7737.6518056686455 12:24:51 INFO - PID 9618 | |4;prototype.html;497.2194330732987;505.969641821491;511.3786749099845;530.1808705549925;532.0497839480083;6289.081830041841;6302.968485157574;6361.300256971729;6364.681765911703;6404.583702059363;6250.593711408756;6282.905756413657;6307.3692630736905;6323.620582765037;6326.936730632692;5228.790848366079;5236.7119808410525;5237.502437268454;5268.683878967255;5271.9456108778395;4901.387326584177;4957;5197.948020519803;5205.141151709963;5212.192110222903;4981.526754958291;4990.675606085593;5006.949930500685;5033.471485494026;5050.090983622947;4878.02439512099;5011.874703132415;5042.949570504285;5071.568916642108;5076.543111119999;4494.1910456117885;4495.100979804052;4500.414946057024;4503.301988191835;4514.909701805946;6130.478909292716;6156.891759483291;6164.6917654117115;6175.135481032659;6175.722092505848;5731.770729170828;5743.5118014968775;5756.194132821408;5763.798267060674;5797.4782269595935;6580.400741706999;6581.30896255891;6585.5511787406695;6607.57050791697;6664.900026499607;4873.902521949542;4904.975475122602;4911.312416261726;4937.629677774181;4941.308216849644;6584.37448442402;6603.669816509156;6781.779279729647;6864.519483636147;6900.171979362408;5012.7994880205115;5023.271625614266;5026.396832380065;5028.396592408862;5055.255936701832;5008.474110218447;5065.214891691794;5076.923846142311;5114.105031619451;5141.151709967889;5661.830145095653;5665.915011274834;5680.687562184037;5704.144378343199;5723.427657234327;6571;6662.467002639777;6681.1314529110905;6689.565178263398;6696.5647232929705;1745.7206846904435;1748.9681088158047;1770.6016146367222;1773.4502304285713;1775;1165.7668466306773;1215.7568486302775;1225.6568160914958;1234.6789834643057;1239.69007748063;5745.942540574624;5746.281714785652;5769.624974376652;5770.336411312753;5796.739146738407;6573.309802470767;6662.400383965466;6707.899381509281;6726.697298621574;6727.66361681914;5713.914291285634;5739.708565572796;5757.366689664133;5771.336296325892;5772.163036359704;3558.7508874379064;3585.2829434113282;3595.471924432106;3599.7480176387926;3616.6202548732003;6297.992321228581;6418.743250269937;6435.131257280301;6444.871102578016;6539.11721917535;6423.871522569616;6848.00703897932;6955.65221738911;6967.93032069683;7025.367716905503;6397.424231819158;6414.294427612959;6452.419282264525;6486.44865186455;6489.66372106032;6497.686180048115;6623.006549017686;6639.966800165968;6685.565438246498;6701.966490167713;6656.800295991128;6674.330267892882;6694.966525167343;6737.260820244811;6753.864922701617;6569.737210511526;6603.20761508622;6609.504287178384;6616.195687479622;6654.4676425884845;4976.353074100344;4999.575036121971;5028.446870844131;5053.519915608012;5056.140456122394;6468.902966455413;6537.934620653923;6548.3002103811195;6826.419754320938;6853.760118395832;6465.903011454926;6469.126667899774;6501.577397469149;6551.475881929529;6606.966965165144;6421.325760795049;6484.70818813164;6490.480761538971;6497.18785151856;6548.934510655023;6360.49116070704;6466.482681385572;6540.640264785482;6557.606543607399;6567.146270984741;6667.733130705151;6710.597364158166;6717.361850624183;6726.260111387841;6743.966280168568;6379.5215358847345;6379.649119298483;6402.423781859468;6441.355864413521;6521.315261897433 12:24:51 INFO - PID 9618 | |5;yui.html;910.0944560162638;910.2990697163184;923.2660035271958;927.8097989912067;930.557984957145;16497.340106395746;17049.82950170498;17230.224639891203;17262.913685431555;17336.30654773809;15311.540653780377;15642.765358519602;15713.84286157138;15774.132422716746;15777;912.7444315591629;959.0840747086551;961.4808003678005;965.4738167698586;966.970990870275;966.5891995901749;978.1783302026297;981.6417007792176;982.219135787051;984.6701355046068;950.4629884115452;1006.3509036671343;1028.0233777910978;1037.4086770540796;1041.5625437316307;1023.5291765787747;1024.5850430575629;1032.5508403844315;1034.0125180452724;1034.0693375961619;708.2634060576994;721.476929226312;729.8284903047779;746.0823187479376;748.8577170337618;16228.75656865136;16315.428959986344;16365.427210047592;16367.508974730777;16394.754078688828;19988.300409485597;20146.597068058556;20416.489587760276;20805.89597052005;21003.684944725843;16586.419475318307;16597.751033734505;16627.168641567994;16784.83215167845;16908;3506.6142724300307;3531.841067151984;3539.2567560812004;3540.4689296605716;3541.3094446582927;100567;105615;136581;141997;146353.2682336592;17322.30710771555;17529.0359030252;17556.034418107127;17751.11244437773;17832.821671783375;32083.839580802414;32188.678113218568;32511.674883250864;32538.349233015208;32547.837260814016;8883.156100170474;9123.498207598646;9211.124943130391;9322.30082743797;9383.296252781069;4230.450041494586;4300.225959327288;4302.65578753699;4308.892277693083;4310.913781724411;5419.160030195326;5443.673379597237;5497.890042199214;5540.307461567304;5543.085390910535;1108.0913650806262;1122.3378206857992;1125.8705248896317;1132.997297391814;1133.665568657248;663.3035312921413;681.9352161544646;691.2499937567695;697.6093387702903;705.9188193357829;11377.601783937522;11749.177557570887;11878.227915185485;13005.479780808662;13309.73380532384;675.3988949834651;690.2234985641154;690.5925503952692;707.5118168463744;712.2236761929522;5596.4403559644525;5629.324481062299;5663.433656634304;5734.942650573525;5794;113979.43010285062;119684.40157799155;121889.39055304667;123445;126373;104405;108219;109958;112190.43904780423;114504.42747786375;98149.50925245328;99814.50092749635;100258.49870750599;102515;102771;12465.314407707301;12923.546912125827;12959.611211663665;13018.934905325414;13480.258585777876;43290.56709432865;43606;43629.56370436255;43720.125597488506;45887.311690324;58692.11961820661;58962;59411.405885940585;60719;60911.69544152251;16749.330026799278;17133.74299385535;17170.914145429193;17177.656446870744;17399.04305263172;20119.899400502905;20225;20227.59544809125;20416.79583204149;20736;18202.45392638223;18270.908645456686;18273;18452.81547184511;18463.723044154616;15747.44883929057;15877.761833572275;15895.205239737736;15904.045757254602;15912.204389780232;16388.344466221217;16490.83509164941;16621.335146594483;16621.916890415472;16725.665486690443;13945.442182313;14082.366293517021;14318.785218221527;14336.856631433553;14711;12986.935065324613;13018.934905325414;13149;13492.055556110978;13535.458581657018 12:24:51 INFO - PID 9618 | __end_tp_report 12:24:51 INFO - PID 9618 | __start_cc_report 12:24:51 INFO - PID 9618 | _x_x_mozilla_cycle_collect,386 12:24:51 INFO - PID 9618 | __end_cc_report 12:24:51 INFO - PID 9618 | __startTimestamp1505503491898__endTimestamp 12:24:51 INFO - PID 9618 | 12:24:51 INFO - PID 9618 | ------- Summary: start ------- 12:24:51 INFO - PID 9618 | Number of tests: 6 12:24:51 INFO - PID 9618 | 12:24:51 INFO - PID 9618 | [#0] dojo.html Cycles:165 Average:6176.97 Median:6417.39 stddev:737.09 (11.5%) stddev-sans-first:699.80 12:24:51 INFO - PID 9618 | Values: 3140.2 3143.5 3166.2 3172.8 3176.0 6106.6 6247.3 6310.4 6337.4 6370.8 6205.3 6224.3 6242.2 6257.8 6372.5 5817.1 5847.2 5866.9 5888.2 5901.6 5709.9 5766.0 5812.3 5880.4 5885.5 5755.9 5834.9 5894.5 5896.3 5899.6 5624.6 5685.6 5693.7 5743.9 5828.4 5733.1 5744.7 5745.3 5783.5 5789.8 6205.0 6209.6 6279.3 6295.4 6350.7 6507.6 6626.0 6663.9 6735.2 6759.1 6685.8 6691.5 6697.0 6702.9 6714.5 6298.1 6357.3 6361.3 6363.0 6363.5 6891.8 6896.6 6906.3 6916.9 6971.1 6397.1 6560.2 6596.8 6603.2 6676.5 6727.6 6746.7 6769.7 6805.3 6820.4 6495.1 6590.1 6656.1 6741.4 6759.7 6570.1 6606.8 6614.0 6623.0 6623.3 5211.0 5225.7 5232.6 5233.6 5236.8 4570.4 4572.6 4580.5 4596.1 4688.9 5787.0 5831.6 5863.5 5868.4 5894.5 6732.2 6745.8 6828.0 6841.5 6846.9 5768.9 5786.0 5850.4 5860.5 5861.4 5632.3 5700.9 5701.8 5702.9 5714.0 6384.0 6396.0 6412.1 6416.9 6417.4 6675.3 6676.9 6683.6 6695.5 6723.7 6505.2 6509.3 6550.8 6559.3 6584.9 6633.5 6637.3 6644.7 6728.5 6731.9 6594.6 6693.5 6791.2 6852.0 6870.9 6474.9 6492.4 6515.4 6520.2 6560.1 6545.6 6590.8 6615.1 6628.7 6634.1 6463.3 6470.9 6540.1 6549.3 6551.1 6384.1 6517.9 6580.0 6595.3 6698.1 6301.9 6420.3 6420.9 6424.2 6435.6 12:24:51 INFO - PID 9618 | 12:24:51 INFO - PID 9618 | [#1] ext.html Cycles:180 Average:36287.77 Median:35027.97 stddev:50272.00 (143.5%) stddev-sans-first:50342.63 12:24:51 INFO - PID 9618 | Values: 856.3 886.4 888.2 890.1 890.6 38931.2 42347.0 42643.0 42977.8 43103.4 39876.8 39963.0 40055.2 40110.8 40217.0 7091.4 7219.5 7243.2 7260.4 7263.6 7047.3 7073.9 7134.2 7149.2 7149.4 7127.6 7162.1 7164.8 7168.7 7721.2 7783.1 7883.6 7884.8 7887.0 7887.4 7684.5 7721.2 7743.6 7749.1 7765.4 38374.2 39095.8 39442.8 39521.2 39545.8 3529.3 3543.3 3557.6 3583.7 3586.7 32237.8 32294.0 32319.7 32455.2 32597.8 5170.5 5175.9 5189.6 5190.7 5227.8 175920.0 176019.0 176690.1 176740.0 177589.0 42216.8 43342.3 48575.0 48791.5 48868.8 52185.7 52296.7 52297.2 52359.5 52396.0 14783.0 14841.1 14848.6 14866.6 14898.1 11955.2 11984.8 12015.3 12032.0 12089.9 14234.1 14340.2 14373.9 14412.9 14473.0 5727.9 5779.9 5871.7 5955.3 6049.8 1001.4 1002.3 1011.5 1016.7 1021.6 22506.7 22646.8 22754.7 22771.2 22887.8 1107.4 1110.1 1116.1 1120.9 1130.6 12042.7 12123.7 12251.8 12294.2 12336.6 217296.0 217512.0 217597.0 217739.0 218834.0 53167.7 53598.7 54005.5 54033.5 54143.2 1426.0 1451.9 1452.5 1487.9 1489.3 21859.6 22027.6 22301.4 22595.5 22664.4 33539.3 34313.0 37718.6 37772.6 37819.1 184440.0 185211.0 185387.0 185550.0 186368.0 31095.0 31909.0 32034.2 34860.5 35195.5 42627.6 42754.8 42861.8 43083.4 43757.8 31500.5 31557.7 31803.2 32193.7 32229.8 31425.5 31451.2 31590.0 31702.4 32137.0 35573.0 35628.6 35689.6 35774.5 35901.3 30791.0 31077.2 31135.8 31293.2 31711.5 23016.2 23038.8 23053.8 23071.7 23152.0 12:24:51 INFO - PID 9618 | 12:24:51 INFO - PID 9618 | [#2] jquery.html Cycles:180 Average:35288.34 Median:22186.61 stddev:81181.89 (365.9%) stddev-sans-first:81367.76 12:24:51 INFO - PID 9618 | Values: 565.3 567.9 576.0 581.3 595.5 21819.3 21888.5 22579.0 22718.0 22762.7 25062.9 25290.4 25620.0 25624.6 25638.5 6470.4 6625.7 6641.2 6646.0 6646.3 6500.6 6543.9 6837.7 7199.4 7237.2 7166.4 7170.7 7171.4 7178.4 7194.5 7117.0 7133.8 7134.8 7154.4 7175.2 6981.2 6990.0 7002.4 7033.9 7060.4 30628.2 31070.0 32115.2 32374.2 32386.2 2092.1 2130.3 2135.3 2137.2 2137.7 20877.4 21425.5 21427.4 21446.4 21488.0 2492.0 2500.1 2598.2 2616.0 2616.2 129902.0 136297.0 136304.0 136391.3 136406.0 20411.6 21534.5 21551.2 21568.5 21575.6 41740.0 41821.4 41833.0 41895.0 41914.6 10827.8 11004.3 11049.6 11205.1 11225.9 8239.0 9049.7 9141.3 9145.6 9249.6 9721.9 11543.1 11741.8 11873.3 11968.9 4198.2 4298.6 4349.2 4366.7 4403.6 956.7 973.6 981.4 984.9 995.2 19220.0 19757.2 19954.4 20075.0 20235.7 1021.9 1022.5 1031.2 1031.6 1035.5 10751.0 10867.8 11033.6 11127.2 11325.1 470706.0 484316.0 484365.0 491486.0 493898.0 105101.5 105476.0 106648.9 106931.5 108597.0 1131.1 1153.3 1158.4 1163.2 1173.1 17411.1 17632.0 17698.5 17766.7 17770.6 51026.0 51063.5 51355.0 53328.7 53475.0 65403.7 65988.0 66075.0 66145.0 66613.3 19617.1 20103.5 20238.1 20244.6 20259.4 22484.8 22662.1 22740.7 22816.4 23221.8 18001.3 18153.9 18323.5 18456.6 18536.5 16286.8 16320.8 16331.8 16627.3 17180.0 18691.3 18884.0 18938.8 19021.1 19031.7 18575.4 19734.5 19757.2 20026.5 20073.4 17758.9 17850.2 18026.7 18028.8 18111.6 12:24:51 INFO - PID 9618 | 12:24:51 INFO - PID 9618 | [#3] mootools.html Cycles:180 Average:9397.22 Median:26837.10 stddev:6703.52 (25.0%) stddev-sans-first:6694.94 12:24:51 INFO - PID 9618 | Values: 1331.8 1379.1 1391.3 1400.2 1412.4 14777.1 15326.0 15374.5 15374.8 15377.6 14980.2 15025.2 15077.9 15133.5 15136.2 2719.1 2838.2 2839.8 2841.4 2846.5 2640.8 2760.7 2762.8 2763.0 2776.2 2757.5 2828.3 2831.5 2840.2 2841.4 2810.0 2821.3 2829.7 2836.7 2839.0 2726.5 2730.7 2733.6 2735.9 2739.0 14657.2 15087.8 15102.6 15118.7 15119.5 7945.2 8208.3 8275.2 8294.0 8296.5 5267.6 5326.2 5385.2 5527.4 5649.4 1868.9 1880.8 1919.7 1958.9 1960.1 26569.1 26785.3 26798.5 26816.7 26857.5 6654.4 6716.8 6799.6 6902.9 6989.8 16983.2 17002.0 17019.5 17045.9 17072.3 7499.9 7640.2 7640.4 7644.1 7903.1 6935.4 7215.7 7336.3 7382.4 7405.2 5106.0 5265.3 5313.9 5339.1 5372.7 1400.7 1433.9 1556.4 1576.6 1582.9 914.6 934.0 934.6 938.0 948.6 7822.1 7876.7 7904.9 7952.4 7960.2 947.9 966.7 977.9 979.7 1021.2 4765.2 4790.2 4842.3 4858.6 4936.9 21403.7 22163.6 22238.9 22415.4 22510.2 20545.9 21503.8 21616.5 21676.5 21804.8 11109.3 11172.8 11182.7 11263.3 11294.2 8235.5 8492.7 8570.4 8584.2 8701.1 18115.8 18686.2 18777.2 19043.4 19106.4 18759.2 19031.7 19161.5 19223.6 19228.0 11175.6 11379.8 11391.1 11394.9 11425.5 13749.2 14016.5 14175.8 14177.9 14231.1 11395.3 11495.7 11679.5 11864.1 12042.2 11202.0 11494.9 11616.7 11628.9 11808.3 10075.1 10092.3 10208.9 10239.1 10242.0 7458.2 7519.5 7549.2 7554.5 7613.1 7500.5 7590.5 7601.1 7681.1 7737.7 12:24:51 INFO - PID 9618 | 12:24:51 INFO - PID 9618 | [#4] prototype.html Cycles:180 Average:5539.36 Median:6266.75 stddev:1543.48 (24.6%) stddev-sans-first:1500.70 12:24:51 INFO - PID 9618 | Values: 497.2 506.0 511.4 530.2 532.0 6289.1 6303.0 6361.3 6364.7 6404.6 6250.6 6282.9 6307.4 6323.6 6326.9 5228.8 5236.7 5237.5 5268.7 5271.9 4901.4 4957.0 5197.9 5205.1 5212.2 4981.5 4990.7 5006.9 5033.5 5050.1 4878.0 5011.9 5042.9 5071.6 5076.5 4494.2 4495.1 4500.4 4503.3 4514.9 6130.5 6156.9 6164.7 6175.1 6175.7 5731.8 5743.5 5756.2 5763.8 5797.5 6580.4 6581.3 6585.6 6607.6 6664.9 4873.9 4905.0 4911.3 4937.6 4941.3 6584.4 6603.7 6781.8 6864.5 6900.2 5012.8 5023.3 5026.4 5028.4 5055.3 5008.5 5065.2 5076.9 5114.1 5141.2 5661.8 5665.9 5680.7 5704.1 5723.4 6571.0 6662.5 6681.1 6689.6 6696.6 1745.7 1749.0 1770.6 1773.5 1775.0 1165.8 1215.8 1225.7 1234.7 1239.7 5745.9 5746.3 5769.6 5770.3 5796.7 6573.3 6662.4 6707.9 6726.7 6727.7 5713.9 5739.7 5757.4 5771.3 5772.2 3558.8 3585.3 3595.5 3599.7 3616.6 6298.0 6418.7 6435.1 6444.9 6539.1 6423.9 6848.0 6955.7 6967.9 7025.4 6397.4 6414.3 6452.4 6486.4 6489.7 6497.7 6623.0 6640.0 6685.6 6702.0 6656.8 6674.3 6695.0 6737.3 6753.9 6569.7 6603.2 6609.5 6616.2 6654.5 4976.4 4999.6 5028.4 5053.5 5056.1 6468.9 6537.9 6548.3 6826.4 6853.8 6465.9 6469.1 6501.6 6551.5 6607.0 6421.3 6484.7 6490.5 6497.2 6548.9 6360.5 6466.5 6540.6 6557.6 6567.1 6667.7 6710.6 6717.4 6726.3 6744.0 6379.5 6379.6 6402.4 6441.4 6521.3 12:24:51 INFO - PID 9618 | 12:24:51 INFO - PID 9618 | [#5] yui.html Cycles:180 Average:24308.93 Median:17791.97 stddev:34556.09 (194.2%) stddev-sans-first:34608.37 12:24:51 INFO - PID 9618 | Values: 910.1 910.3 923.3 927.8 930.6 16497.3 17049.8 17230.2 17262.9 17336.3 15311.5 15642.8 15713.8 15774.1 15777.0 912.7 959.1 961.5 965.5 967.0 966.6 978.2 981.6 982.2 984.7 950.5 1006.4 1028.0 1037.4 1041.6 1023.5 1024.6 1032.6 1034.0 1034.1 708.3 721.5 729.8 746.1 748.9 16228.8 16315.4 16365.4 16367.5 16394.8 19988.3 20146.6 20416.5 20805.9 21003.7 16586.4 16597.8 16627.2 16784.8 16908.0 3506.6 3531.8 3539.3 3540.5 3541.3 100567.0 105615.0 136581.0 141997.0 146353.3 17322.3 17529.0 17556.0 17751.1 17832.8 32083.8 32188.7 32511.7 32538.3 32547.8 8883.2 9123.5 9211.1 9322.3 9383.3 4230.5 4300.2 4302.7 4308.9 4310.9 5419.2 5443.7 5497.9 5540.3 5543.1 1108.1 1122.3 1125.9 1133.0 1133.7 663.3 681.9 691.2 697.6 705.9 11377.6 11749.2 11878.2 13005.5 13309.7 675.4 690.2 690.6 707.5 712.2 5596.4 5629.3 5663.4 5734.9 5794.0 113979.4 119684.4 121889.4 123445.0 126373.0 104405.0 108219.0 109958.0 112190.4 114504.4 98149.5 99814.5 100258.5 102515.0 102771.0 12465.3 12923.5 12959.6 13018.9 13480.3 43290.6 43606.0 43629.6 43720.1 45887.3 58692.1 58962.0 59411.4 60719.0 60911.7 16749.3 17133.7 17170.9 17177.7 17399.0 20119.9 20225.0 20227.6 20416.8 20736.0 18202.5 18270.9 18273.0 18452.8 18463.7 15747.4 15877.8 15895.2 15904.0 15912.2 16388.3 16490.8 16621.3 16621.9 16725.7 13945.4 14082.4 14318.8 14336.9 14711.0 12986.9 13018.9 13149.0 13492.1 13535.5 12:24:51 INFO - PID 9618 | -------- Summary: end -------- 12:24:51 INFO - PID 9618 | 12:24:52 INFO - TEST-INFO | 9618: exit 0 12:24:52 INFO - TEST-OK | dromaeo_css | took 1109292ms 12:24:52 INFO - TEST-START | kraken 12:24:52 INFO - Initialising browser for kraken test... 12:24:52 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60588/getInfo.html -profile /tmp/tmpiryz9O/profile 12:24:52 INFO - TEST-INFO | started process 9990 (/builds/slave/test/build/application/firefox/firefox http://localhost:60588/getInfo.html) 12:24:58 INFO - TEST-INFO | 9990: exit 0 12:24:58 INFO - Browser initialized. 12:24:58 INFO - Running cycle 1/1 for kraken test... 12:24:58 INFO - Using env: {'DISPLAY': ':0', 12:24:58 INFO - 'HOME': '/home/cltbld', 12:24:58 INFO - 'JSGC_DISABLE_POISONING': '1', 12:24:58 INFO - 'LANG': 'en_US.UTF-8', 12:24:58 INFO - 'LANGUAGE': 'en_US:en', 12:24:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:24:58 INFO - 'LOGNAME': 'cltbld', 12:24:58 INFO - 'MAIL': '/var/mail/cltbld', 12:24:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:24:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:24:58 INFO - 'MOZ_CRASHREPORTER': '1', 12:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:24:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:24:58 INFO - 'MOZ_NO_REMOTE': '1', 12:24:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:24:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:24:58 INFO - 'NO_EM_RESTART': '1', 12:24:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:24:58 INFO - 'PWD': '/builds/slave/test', 12:24:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:24:58 INFO - 'RUST_BACKTRACE': 'full', 12:24:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:24:58 INFO - 'SHELL': '/bin/bash', 12:24:58 INFO - 'SHLVL': '1', 12:24:58 INFO - 'TERM': 'linux', 12:24:58 INFO - 'TMOUT': '86400', 12:24:58 INFO - 'USER': 'cltbld', 12:24:58 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214', 12:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:24:58 INFO - '_': '/tools/buildbot/bin/python'} 12:24:58 INFO - TEST-INFO | started process 10224 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiryz9O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:24:59 INFO - PID 10224 | 12:24:59 INFO - PID 10224 | (/builds/slave/test/build/application/firefox/firefox:10274): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:24:59 INFO - PID 10224 | 12:25:09 INFO - PID 10224 | RSS: Main: 174043136 12:25:09 INFO - PID 10224 | 12:25:12 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60588/tests/kraken/driver.html?testName=audio-beat-detection) 12:25:13 INFO - PID 10224 | RSS: Main: 179654656 12:25:13 INFO - PID 10224 | 12:25:15 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60588/tests/kraken/driver.html?testName=audio-dft) 12:25:16 INFO - PID 10224 | RSS: Main: 179904512 12:25:16 INFO - PID 10224 | 12:25:19 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60588/tests/kraken/driver.html?testName=audio-fft) 12:25:19 INFO - PID 10224 | RSS: Main: 174333952 12:25:19 INFO - PID 10224 | 12:25:22 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60588/tests/kraken/driver.html?testName=audio-oscillator) 12:25:22 INFO - PID 10224 | RSS: Main: 174911488 12:25:22 INFO - PID 10224 | 12:25:24 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60588/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:25:25 INFO - PID 10224 | RSS: Main: 175329280 12:25:25 INFO - PID 10224 | 12:25:28 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60588/tests/kraken/driver.html?testName=imaging-darkroom) 12:25:29 INFO - PID 10224 | RSS: Main: 177930240 12:25:29 INFO - PID 10224 | 12:25:32 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60588/tests/kraken/driver.html?testName=imaging-desaturate) 12:25:33 INFO - PID 10224 | RSS: Main: 177717248 12:25:33 INFO - PID 10224 | 12:25:36 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60588/tests/kraken/driver.html?testName=json-parse-financial) 12:25:37 INFO - PID 10224 | RSS: Main: 177917952 12:25:37 INFO - PID 10224 | 12:25:38 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60588/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:25:39 INFO - PID 10224 | RSS: Main: 177852416 12:25:39 INFO - PID 10224 | 12:25:41 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60588/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:25:41 INFO - PID 10224 | RSS: Main: 178319360 12:25:41 INFO - PID 10224 | 12:25:43 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60588/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:25:44 INFO - PID 10224 | RSS: Main: 175144960 12:25:44 INFO - PID 10224 | 12:25:47 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60588/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:25:47 INFO - PID 10224 | RSS: Main: 175579136 12:25:47 INFO - PID 10224 | 12:25:50 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60588/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:25:51 INFO - PID 10224 | RSS: Main: 175661056 12:25:51 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | Cycle 1(1): loaded http://localhost:60588/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60588/tests/kraken/driver.html?testName=ai-astar) 12:25:53 INFO - PID 10224 | RSS: Main: 177238016 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | __start_tp_report 12:25:53 INFO - PID 10224 | _x_x_mozilla_page_load 12:25:53 INFO - PID 10224 | _x_x_mozilla_page_load_details 12:25:53 INFO - PID 10224 | |i|pagename|runs| 12:25:53 INFO - PID 10224 | |0;ai-astar;110;107;108;109;110;108;109;108;109;108 12:25:53 INFO - PID 10224 | |1;audio-beat-detection;119;116;118;117;137;118;117;119;118;124 12:25:53 INFO - PID 10224 | |2;audio-dft;163;161;160;161;162;160;165;165;163;164 12:25:53 INFO - PID 10224 | |3;audio-fft;74;75;74;73;74;74;74;74;75;74 12:25:53 INFO - PID 10224 | |4;audio-oscillator;81;80;80;79;80;82;79;79;78;79 12:25:53 INFO - PID 10224 | |5;imaging-gaussian-blur;109;115;114;114;114;110;113;113;111;115 12:25:53 INFO - PID 10224 | |6;imaging-darkroom;148;150;147;148;156;147;147;148;148;149 12:25:53 INFO - PID 10224 | |7;imaging-desaturate;94;93;94;93;94;94;95;95;95;93 12:25:53 INFO - PID 10224 | |8;json-parse-financial;55;54;54;53;53;53;58;55;54;56 12:25:53 INFO - PID 10224 | |9;json-stringify-tinderbox;38;37;38;37;38;37;37;38;38;38 12:25:53 INFO - PID 10224 | |10;stanford-crypto-aes;76;85;74;74;75;75;75;76;77;76 12:25:53 INFO - PID 10224 | |11;stanford-crypto-ccm;114;115;118;111;114;110;119;114;128;115 12:25:53 INFO - PID 10224 | |12;stanford-crypto-pbkdf2;174;170;171;167;174;170;171;185;172;169 12:25:53 INFO - PID 10224 | |13;stanford-crypto-sha256-iterative;71;70;66;65;64;65;69;73;68;66 12:25:53 INFO - PID 10224 | __end_tp_report 12:25:53 INFO - PID 10224 | __start_cc_report 12:25:53 INFO - PID 10224 | _x_x_mozilla_cycle_collect,957 12:25:53 INFO - PID 10224 | __end_cc_report 12:25:53 INFO - PID 10224 | __startTimestamp1505503553474__endTimestamp 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | ------- Summary: start ------- 12:25:53 INFO - PID 10224 | Number of tests: 14 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#0] ai-astar Cycles:10 Average:108.60 Median:109.00 stddev:0.97 (0.9%) stddev-sans-first:0.88 12:25:53 INFO - PID 10224 | Values: 110.0 107.0 108.0 109.0 110.0 108.0 109.0 108.0 109.0 108.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#1] audio-beat-detection Cycles:10 Average:120.30 Median:118.50 stddev:6.25 (5.3%) stddev-sans-first:6.62 12:25:53 INFO - PID 10224 | Values: 119.0 116.0 118.0 117.0 137.0 118.0 117.0 119.0 118.0 124.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#2] audio-dft Cycles:10 Average:162.40 Median:163.00 stddev:1.90 (1.2%) stddev-sans-first:2.00 12:25:53 INFO - PID 10224 | Values: 163.0 161.0 160.0 161.0 162.0 160.0 165.0 165.0 163.0 164.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#3] audio-fft Cycles:10 Average:74.10 Median:74.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 12:25:53 INFO - PID 10224 | Values: 74.0 75.0 74.0 73.0 74.0 74.0 74.0 74.0 75.0 74.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:80.00 stddev:1.16 (1.4%) stddev-sans-first:1.13 12:25:53 INFO - PID 10224 | Values: 81.0 80.0 80.0 79.0 80.0 82.0 79.0 79.0 78.0 79.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:114.00 stddev:2.10 (1.8%) stddev-sans-first:1.72 12:25:53 INFO - PID 10224 | Values: 109.0 115.0 114.0 114.0 114.0 110.0 113.0 113.0 111.0 115.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:148.00 stddev:2.70 (1.8%) stddev-sans-first:2.85 12:25:53 INFO - PID 10224 | Values: 148.0 150.0 147.0 148.0 156.0 147.0 147.0 148.0 148.0 149.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 12:25:53 INFO - PID 10224 | Values: 94.0 93.0 94.0 93.0 94.0 94.0 95.0 95.0 95.0 93.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.50 stddev:1.58 (2.9%) stddev-sans-first:1.67 12:25:53 INFO - PID 10224 | Values: 55.0 54.0 54.0 53.0 53.0 53.0 58.0 55.0 54.0 56.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#9] json-stringify-tinderbox Cycles:10 Average:37.60 Median:38.00 stddev:0.52 (1.4%) stddev-sans-first:0.53 12:25:53 INFO - PID 10224 | Values: 38.0 37.0 38.0 37.0 38.0 37.0 37.0 38.0 38.0 38.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:76.00 stddev:3.20 (4.2%) stddev-sans-first:3.39 12:25:53 INFO - PID 10224 | Values: 76.0 85.0 74.0 74.0 75.0 75.0 75.0 76.0 77.0 76.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#11] stanford-crypto-ccm Cycles:10 Average:115.80 Median:115.00 stddev:5.07 (4.4%) stddev-sans-first:5.34 12:25:53 INFO - PID 10224 | Values: 114.0 115.0 118.0 111.0 114.0 110.0 119.0 114.0 128.0 115.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:171.50 stddev:4.95 (2.9%) stddev-sans-first:5.21 12:25:53 INFO - PID 10224 | Values: 174.0 170.0 171.0 167.0 174.0 170.0 171.0 185.0 172.0 169.0 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.50 stddev:2.98 (4.4%) stddev-sans-first:2.92 12:25:53 INFO - PID 10224 | Values: 71.0 70.0 66.0 65.0 64.0 65.0 69.0 73.0 68.0 66.0 12:25:53 INFO - PID 10224 | -------- Summary: end -------- 12:25:53 INFO - PID 10224 | 12:25:53 INFO - PID 10224 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:25:54 INFO - TEST-INFO | 10224: exit 0 12:25:54 INFO - TEST-OK | kraken | took 61586ms 12:25:54 INFO - SUITE-END | took 1170s 12:25:54 INFO - Completed test suite (00:19:31) 12:25:54 INFO - javascript benchmark 12:25:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8628.328356116603, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3140.152158917092, 3143.4656108461572, 3166.2242750526116, 3172.8096314221148, 3176.031310450315, 6106.603070800395, 6247.312795592481, 6310.400511951369, 6337.429631333179, 6370.840728981778, 6205.317415084337, 6224.346443623411, 6242.18851549298, 6257.780977665782, 6372.458341041017, 5817.069268916975, 5847.181394604748, 5866.914620795166, 5888.234529511179, 5901.616394934337, 5709.885802283972, 5766.0, 5812.273465816773, 5880.3825598312205, 5885.529157667398, 5755.884882302331, 5834.912476312858, 5894.469497745224, 5896.292444906617, 5899.5870289079785, 5624.55003599711, 5685.630434021817, 5693.686847223401, 5743.8564035899435, 5828.358880523139, 5733.082706766939, 5744.655320680772, 5745.253117094751, 5783.479486846162, 5789.768409263625, 6205.0, 6209.627422354673, 6279.277883043463, 6295.433410993032, 6350.714217860207, 6507.609543427409, 6626.006099085128, 6663.900041499382, 6735.191776986744, 6759.087523184406, 6685.76599819004, 6691.531592788507, 6697.0, 6702.865942681168, 6714.496412769062, 6298.118263443122, 6357.268914074894, 6361.332060133712, 6363.045543168516, 6363.459105975951, 6891.827704307332, 6896.551724138016, 6906.274841141708, 6916.861662766716, 6971.093757811452, 6397.072424498421, 6560.179977502812, 6596.80209593713, 6603.20761508622, 6676.465882729467, 6727.562708424035, 6746.730130794714, 6769.72921083161, 6805.285445028201, 6820.420264277592, 6495.0582165586675, 6590.077389165521, 6656.068150458939, 6741.426978706767, 6759.662016899136, 6570.080188773575, 6606.768763093365, 6614.040964060185, 6623.039659249382, 6623.337666233435, 5211.009908117446, 5225.6603320784025, 5232.633715639945, 5233.6074794389815, 5236.842894713165, 4570.428696412949, 4572.588467037983, 4580.541945805459, 4596.080783843245, 4688.882777930579, 5787.045137552341, 5831.620944638585, 5863.5309175265875, 5868.354481007086, 5894.498967587718, 6732.158480189976, 6745.831354216184, 6827.965860170667, 6841.452683785285, 6846.8972965405555, 5768.884622307614, 5785.971070144707, 5850.385709500526, 5860.531157507389, 5861.3552509223955, 5632.324121105494, 5700.9429905701245, 5701.828945131652, 5702.914456283159, 5713.971430142823, 6384.0, 6396.0405939109505, 6412.070249813751, 6416.903746443807, 6417.390347917034, 6675.332466753286, 6676.89984650221, 6683.5989840608745, 6695.464362850863, 6723.697433615401, 6505.186851643544, 6509.349065093453, 6550.803475895731, 6559.278479367361, 6584.868302634016, 6633.469322454289, 6637.269900311058, 6644.667766611554, 6728.461723062242, 6731.899021514779, 6594.571352862049, 6693.497987650849, 6791.185057793096, 6852.0, 6870.931290687229, 6474.935250647622, 6492.35076492347, 6515.381038801306, 6520.217573891164, 6560.147180866456, 6545.639989800607, 6590.769323073861, 6615.073889655549, 6628.734850605922, 6634.137562116894, 6463.321351258238, 6470.870582588416, 6540.117084193763, 6549.345065493603, 6551.115599394021, 6384.106225128578, 6517.9348206517325, 6580.045893345534, 6595.340465953366, 6698.095757072928, 6301.928672125658, 6420.3258657842125, 6420.903686444801, 6424.164858568356, 6435.646039467874], "value": 6120.903263482823, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [856.2507805670039, 886.4371124336046, 888.1961824548786, 890.1454603580562, 890.5992303463443, 38931.22137557251, 42347.0, 42643.0, 42977.78511107444, 43103.35344969828, 39876.80061599688, 39963.0, 40055.19889602206, 40110.7994460028, 40217.0, 7091.361777440029, 7219.494635375526, 7243.203247642781, 7260.419166466696, 7263.636818159097, 7047.260037696045, 7073.9292607073785, 7134.215236324, 7149.177844547866, 7149.428045756353, 7127.572345659251, 7162.140543134805, 7164.820879478003, 7168.677409516558, 7721.189275126087, 7783.066032076159, 7883.605819709021, 7884.842303153905, 7886.960565197137, 7887.447878648497, 7684.538927664302, 7721.22787721229, 7743.574103424309, 7749.108852481924, 7765.41759368044, 38374.23251534982, 39095.80452097721, 39442.802785985885, 39521.20957580832, 39545.80227098875, 3529.29414117175, 3543.273628906068, 3557.5908770491465, 3583.7491375603718, 3586.7309951753464, 32237.838810805795, 32294.0, 32319.6768032319, 32455.188620284684, 32597.837010814794, 5170.5346518813485, 5175.896482070375, 5189.636725429222, 5190.71451070191, 5227.8170264040955, 175920.0, 176019.0, 176690.11654941773, 176740.0, 177589.0, 42216.78891605584, 43342.349864752054, 48575.0, 48791.51208487941, 48868.7556562222, 52185.7390713044, 52296.738516307174, 52297.215541766906, 52359.47640523622, 52396.0, 14783.0, 14841.109533428029, 14848.62878428048, 14866.554003379915, 14898.106113633217, 11955.16313858039, 11984.760304793856, 12015.279083255033, 12032.0, 12089.879101209051, 14234.14595124296, 14340.21128837924, 14373.856261437251, 14412.855871441361, 14473.0, 5727.914081288784, 5779.942200578024, 5871.6770577618645, 5955.255593050869, 6049.78825741097, 1001.409168590535, 1002.333448256901, 1011.489197955028, 1016.7356487313203, 1021.581151727788, 22506.66240006401, 22646.773532264797, 22754.65868011981, 22771.203007894645, 22887.771122288897, 1107.396468924438, 1110.1081682217869, 1116.0736588631416, 1120.921535492524, 1130.559081958037, 12042.698932526757, 12123.696907577381, 12251.75496490083, 12294.200876943149, 12336.568220112433, 217296.0, 217512.0, 217597.0, 217739.0, 218834.0, 53167.734161328946, 53598.73200633972, 54005.45994540004, 54033.45966540363, 54143.18785218225, 1426.0, 1451.8765904897991, 1452.4988878836784, 1487.851214878525, 1489.3074720255133, 21859.56280874342, 22027.559448811255, 22301.442463938532, 22595.5480890378, 22664.433389165402, 33539.329213415105, 34313.0, 37718.62281377261, 37772.62227377801, 37819.05452363603, 184440.0, 185211.0, 185387.0, 185550.0, 186368.0, 31095.0, 31909.0, 32034.19914502156, 34860.47709284312, 35195.472067919516, 42627.57372426236, 42754.78622606867, 42861.78569107259, 43083.353749694405, 43757.78121109501, 31500.527492088095, 31557.684423155475, 31803.20491987719, 32193.678063219068, 32229.838850805594, 31425.52861707122, 31451.213719657193, 31590.0, 31702.365952681277, 32137.0, 35573.0, 35628.64371356253, 35689.64310356967, 35774.4633830498, 35901.281974359845, 30791.0, 31077.22306942254, 31135.84432077825, 31293.217669558442, 31711.52432713465, 23016.194433194756, 23038.769612303662, 23053.847307634886, 23071.6539251908, 23152.0], "value": 16873.284105179908, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.2708006671394, 567.9346875109361, 576.0, 581.2763109928139, 595.4938302442923, 21819.345419637437, 21888.45278868029, 22579.0, 22717.999999999978, 22762.65856012161, 25062.874685626546, 25290.36774080649, 25620.0, 25624.615630765507, 25638.487230255385, 6470.38531339522, 6625.73497060117, 6641.236257830363, 6646.0363247329105, 6646.335366463363, 6500.6099634021875, 6543.901841472381, 6837.726490940357, 7199.388052015585, 7237.167725711532, 7166.355028047475, 7170.7490237841685, 7171.390431813302, 7178.389836863873, 7194.4963852530345, 7116.964415177891, 7133.750318738845, 7134.821629459279, 7154.391876690487, 7175.21072682002, 6981.197162326296, 6990.0, 7002.369786719169, 7033.859322813566, 7060.364567188926, 30628.234294142603, 31070.0, 32115.197120072185, 32374.190645233823, 32386.19034524132, 2092.1422216891, 2130.3076500137518, 2135.338045205991, 2137.1878686099185, 2137.732783402075, 20877.37367878966, 21425.464363390885, 21427.357179284492, 21446.356609301747, 21488.0, 2491.9626205606933, 2500.062476571286, 2598.155599430174, 2616.0, 2616.2151354593548, 129902.0, 136297.0, 136304.0, 136391.31804341014, 136406.0, 20411.59176816485, 21534.461638459165, 21551.2457064002, 21568.4607884801, 21575.56848863014, 41740.0, 41821.37267940983, 41833.0, 41895.0, 41914.58085419107, 10827.783444331228, 11004.284721493077, 11049.558017679363, 11205.103591712643, 11225.887741122648, 8238.970128733908, 9049.683261085831, 9141.268698504104, 9145.628155776554, 9249.630014799468, 9721.902780972241, 11543.134264930164, 11741.765164696659, 11873.346965916784, 11968.88031119695, 4198.244316023084, 4298.591633794785, 4349.238883195429, 4366.737995720267, 4403.581659742319, 956.6508224498008, 973.6223244109506, 981.3670182732241, 984.867042949207, 995.1590905684685, 19220.0, 19757.209711611664, 19954.401367958693, 20075.0, 20235.696464553042, 1021.9080282774586, 1022.4999875304957, 1031.169908223872, 1031.5873650539845, 1035.471909326234, 10751.0, 10867.83698244527, 11033.558657653764, 11127.165462590337, 11325.093992480583, 470706.0, 484316.0, 484365.0, 491486.0, 493898.0, 105101.47449262705, 105476.0, 106648.9335106639, 106931.46534267279, 108597.0, 1131.055568600228, 1153.3310679805693, 1158.3918442817758, 1163.1625229834506, 1173.1318824070127, 17411.129443528025, 17632.0, 17698.469045928643, 17766.733498997783, 17770.644587107927, 51026.0, 51063.489365105874, 51355.0, 53328.73335633297, 53475.0, 65403.67298163479, 65988.0, 66075.0, 66145.0, 66613.33386666265, 19617.117229724412, 20103.497412564804, 20238.08928598187, 20244.59510809805, 20259.392218233475, 22484.775152248923, 22662.093516259163, 22740.658890116993, 22816.429589260402, 23221.76778232196, 18001.279948802425, 18153.909230453763, 18323.450296491126, 18456.630867382846, 18536.536586585444, 16286.755698664292, 16320.755188672418, 16331.755023674892, 16627.251773670458, 17180.0, 18691.25234990585, 18884.0, 18938.810611893703, 19021.144048517574, 19031.714524282423, 18575.44273671792, 19734.506637334183, 19757.209711611373, 20026.499337516096, 20073.397798066082, 17758.91120544441, 17850.196741146938, 18026.729599056285, 18028.819711802713, 18111.63776724432], "value": 12861.340796769282, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.8468376136743, 1379.0898007315175, 1391.3182540555124, 1400.1948879394345, 1412.3573773932862, 14777.113373197615, 15326.0, 15374.461893833704, 15374.846251537481, 15377.615559611015, 14980.176090315028, 15025.248737563106, 15077.92461037696, 15133.54599362021, 15136.167510786903, 2719.0891051497774, 2838.2194896403553, 2839.7728181745515, 2841.374897522552, 2846.5018621741215, 2640.8019398545184, 2760.7239276072332, 2762.8342299462097, 2763.0, 2776.1671498550454, 2757.5036493431176, 2828.321202911307, 2831.5469524876025, 2840.1621521651164, 2841.4175094105763, 2809.985950070247, 2821.3228825082037, 2829.688734239232, 2836.7021462746425, 2838.957415638767, 2726.495598314318, 2730.699623041464, 2733.644626198601, 2735.9179224623294, 2739.0413355325477, 14657.193854338007, 15087.773683394758, 15102.622434439118, 15118.697626047417, 15119.470818521298, 7945.165757595427, 8208.343332533443, 8275.172482751737, 8293.95853020737, 8296.460730052588, 5267.55225805807, 5326.201069839516, 5385.192221166817, 5527.419620939824, 5649.350324712669, 1868.9345872894382, 1880.8025157358418, 1919.673655478572, 1958.8824670519814, 1960.127743154297, 26569.070082547212, 26785.33036674099, 26798.464030719275, 26816.731832681813, 26857.46285074307, 6654.434373078246, 6716.83207919806, 6799.558028728117, 6902.93097069033, 6989.790306290819, 16983.23575439108, 17002.0, 17019.48941531756, 17045.914770426316, 17072.317107315568, 7499.8500029999095, 7640.159582445926, 7640.350570201484, 7644.120926093459, 7903.130655627876, 6935.4104901082965, 7215.747448839265, 7336.33972942427, 7382.446316526282, 7405.1854296027395, 5106.004329155853, 5265.262863199156, 5313.867153321198, 5339.119045357473, 5372.677639341652, 1400.7128538649552, 1433.8781203597812, 1556.3852278350214, 1576.5506830553238, 1582.8654564362157, 914.5975770661021, 934.0192797562532, 934.6027938126269, 938.0, 948.6158105966956, 7822.139564647884, 7876.684932602631, 7904.881426778603, 7952.3638108951145, 7960.2039796019935, 947.8720372749729, 966.7389804752818, 977.9070988256104, 979.6571200079915, 1021.2238698589126, 4765.213739732905, 4790.185668436374, 4842.297866809293, 4858.6356023298395, 4936.90126197474, 21403.67894481584, 22163.556728865333, 22238.888805555867, 22415.439614009454, 22510.212142574932, 20545.897270513553, 21503.78496215049, 21616.459588510414, 21676.45808854791, 21804.781952180594, 11109.333439993627, 11172.832407513732, 11182.720431989103, 11263.324200547993, 11294.152938529644, 8235.464694794819, 8492.74521764348, 8570.357223208159, 8584.180039882192, 8701.129887011248, 18115.81884181141, 18686.159122839777, 18777.248910043993, 19043.42869713911, 19106.426807195807, 18759.249630014645, 19031.714524282423, 19161.520961975504, 19223.615527689646, 19228.0, 11175.608853690082, 11379.772404551697, 11391.145664075393, 11394.943025285153, 11425.485853136795, 13749.175049496662, 14016.509422170582, 14175.787363189767, 14177.858221417653, 14231.146131232159, 11395.259308144945, 11495.655130346104, 11679.474423650787, 11864.11019173548, 12042.21725587834, 11202.047825934887, 11494.885051149382, 11616.709582260512, 11628.94185529067, 11808.291502509534, 10075.09324160829, 10092.293539452461, 10208.897911020795, 10239.129673977497, 10242.0, 7458.216887226763, 7519.511231769918, 7549.1695913448375, 7554.546727196386, 7613.124490683421, 7500.549967001997, 7590.506617069873, 7601.0878694557005, 7681.116671582839, 7737.6518056686455], "value": 6739.299122851568, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.2194330732987, 505.969641821491, 511.3786749099845, 530.1808705549925, 532.0497839480083, 6289.081830041841, 6302.968485157574, 6361.300256971729, 6364.681765911703, 6404.583702059363, 6250.593711408756, 6282.905756413657, 6307.3692630736905, 6323.620582765037, 6326.936730632692, 5228.790848366079, 5236.7119808410525, 5237.502437268454, 5268.683878967255, 5271.9456108778395, 4901.387326584177, 4957.0, 5197.948020519803, 5205.141151709963, 5212.192110222903, 4981.526754958291, 4990.675606085593, 5006.949930500685, 5033.471485494026, 5050.090983622947, 4878.02439512099, 5011.874703132415, 5042.949570504285, 5071.568916642108, 5076.543111119999, 4494.1910456117885, 4495.100979804052, 4500.414946057024, 4503.301988191835, 4514.909701805946, 6130.478909292716, 6156.891759483291, 6164.6917654117115, 6175.135481032659, 6175.722092505848, 5731.770729170828, 5743.5118014968775, 5756.194132821408, 5763.798267060674, 5797.4782269595935, 6580.400741706999, 6581.30896255891, 6585.5511787406695, 6607.57050791697, 6664.900026499607, 4873.902521949542, 4904.975475122602, 4911.312416261726, 4937.629677774181, 4941.308216849644, 6584.37448442402, 6603.669816509156, 6781.779279729647, 6864.519483636147, 6900.171979362408, 5012.7994880205115, 5023.271625614266, 5026.396832380065, 5028.396592408862, 5055.255936701832, 5008.474110218447, 5065.214891691794, 5076.923846142311, 5114.105031619451, 5141.151709967889, 5661.830145095653, 5665.915011274834, 5680.687562184037, 5704.144378343199, 5723.427657234327, 6571.0, 6662.467002639777, 6681.1314529110905, 6689.565178263398, 6696.5647232929705, 1745.7206846904435, 1748.9681088158047, 1770.6016146367222, 1773.4502304285713, 1775.0, 1165.7668466306773, 1215.7568486302775, 1225.6568160914958, 1234.6789834643057, 1239.69007748063, 5745.942540574624, 5746.281714785652, 5769.624974376652, 5770.336411312753, 5796.739146738407, 6573.309802470767, 6662.400383965466, 6707.899381509281, 6726.697298621574, 6727.66361681914, 5713.914291285634, 5739.708565572796, 5757.366689664133, 5771.336296325892, 5772.163036359704, 3558.7508874379064, 3585.2829434113282, 3595.471924432106, 3599.7480176387926, 3616.6202548732003, 6297.992321228581, 6418.743250269937, 6435.131257280301, 6444.871102578016, 6539.11721917535, 6423.871522569616, 6848.00703897932, 6955.65221738911, 6967.93032069683, 7025.367716905503, 6397.424231819158, 6414.294427612959, 6452.419282264525, 6486.44865186455, 6489.66372106032, 6497.686180048115, 6623.006549017686, 6639.966800165968, 6685.565438246498, 6701.966490167713, 6656.800295991128, 6674.330267892882, 6694.966525167343, 6737.260820244811, 6753.864922701617, 6569.737210511526, 6603.20761508622, 6609.504287178384, 6616.195687479622, 6654.4676425884845, 4976.353074100344, 4999.575036121971, 5028.446870844131, 5053.519915608012, 5056.140456122394, 6468.902966455413, 6537.934620653923, 6548.3002103811195, 6826.419754320938, 6853.760118395832, 6465.903011454926, 6469.126667899774, 6501.577397469149, 6551.475881929529, 6606.966965165144, 6421.325760795049, 6484.70818813164, 6490.480761538971, 6497.18785151856, 6548.934510655023, 6360.49116070704, 6466.482681385572, 6540.640264785482, 6557.606543607399, 6567.146270984741, 6667.733130705151, 6710.597364158166, 6717.361850624183, 6726.260111387841, 6743.966280168568, 6379.5215358847345, 6379.649119298483, 6402.423781859468, 6441.355864413521, 6521.315261897433], "value": 5083.471631451278, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [910.0944560162638, 910.2990697163184, 923.2660035271958, 927.8097989912067, 930.557984957145, 16497.340106395746, 17049.82950170498, 17230.224639891203, 17262.913685431555, 17336.30654773809, 15311.540653780377, 15642.765358519602, 15713.84286157138, 15774.132422716746, 15777.0, 912.7444315591629, 959.0840747086551, 961.4808003678005, 965.4738167698586, 966.970990870275, 966.5891995901749, 978.1783302026297, 981.6417007792176, 982.219135787051, 984.6701355046068, 950.4629884115452, 1006.3509036671343, 1028.0233777910978, 1037.4086770540796, 1041.5625437316307, 1023.5291765787747, 1024.5850430575629, 1032.5508403844315, 1034.0125180452724, 1034.0693375961619, 708.2634060576994, 721.476929226312, 729.8284903047779, 746.0823187479376, 748.8577170337618, 16228.75656865136, 16315.428959986344, 16365.427210047592, 16367.508974730777, 16394.754078688828, 19988.300409485597, 20146.597068058556, 20416.489587760276, 20805.89597052005, 21003.684944725843, 16586.419475318307, 16597.751033734505, 16627.168641567994, 16784.83215167845, 16908.0, 3506.6142724300307, 3531.841067151984, 3539.2567560812004, 3540.4689296605716, 3541.3094446582927, 100567.0, 105615.0, 136581.0, 141997.0, 146353.2682336592, 17322.30710771555, 17529.0359030252, 17556.034418107127, 17751.11244437773, 17832.821671783375, 32083.839580802414, 32188.678113218568, 32511.674883250864, 32538.349233015208, 32547.837260814016, 8883.156100170474, 9123.498207598646, 9211.124943130391, 9322.30082743797, 9383.296252781069, 4230.450041494586, 4300.225959327288, 4302.65578753699, 4308.892277693083, 4310.913781724411, 5419.160030195326, 5443.673379597237, 5497.890042199214, 5540.307461567304, 5543.085390910535, 1108.0913650806262, 1122.3378206857992, 1125.8705248896317, 1132.997297391814, 1133.665568657248, 663.3035312921413, 681.9352161544646, 691.2499937567695, 697.6093387702903, 705.9188193357829, 11377.601783937522, 11749.177557570887, 11878.227915185485, 13005.479780808662, 13309.73380532384, 675.3988949834651, 690.2234985641154, 690.5925503952692, 707.5118168463744, 712.2236761929522, 5596.4403559644525, 5629.324481062299, 5663.433656634304, 5734.942650573525, 5794.0, 113979.43010285062, 119684.40157799155, 121889.39055304667, 123445.0, 126373.0, 104405.0, 108219.0, 109958.0, 112190.43904780423, 114504.42747786375, 98149.50925245328, 99814.50092749635, 100258.49870750599, 102515.0, 102771.0, 12465.314407707301, 12923.546912125827, 12959.611211663665, 13018.934905325414, 13480.258585777876, 43290.56709432865, 43606.0, 43629.56370436255, 43720.125597488506, 45887.311690324, 58692.11961820661, 58962.0, 59411.405885940585, 60719.0, 60911.69544152251, 16749.330026799278, 17133.74299385535, 17170.914145429193, 17177.656446870744, 17399.04305263172, 20119.899400502905, 20225.0, 20227.59544809125, 20416.79583204149, 20736.0, 18202.45392638223, 18270.908645456686, 18273.0, 18452.81547184511, 18463.723044154616, 15747.44883929057, 15877.761833572275, 15895.205239737736, 15904.045757254602, 15912.204389780232, 16388.344466221217, 16490.83509164941, 16621.335146594483, 16621.916890415472, 16725.665486690443, 13945.442182313, 14082.366293517021, 14318.785218221527, 14336.856631433553, 14711.0, 12986.935065324613, 13018.934905325414, 13149.0, 13492.055556110978, 13535.458581657018], "value": 9066.866916806772, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1424.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 107.0, 108.0, 109.0, 110.0, 108.0, 109.0, 108.0, 109.0, 108.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 118.0, 117.0, 137.0, 118.0, 117.0, 119.0, 118.0, 124.0], "value": 120.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 160.0, 161.0, 162.0, 160.0, 165.0, 165.0, 163.0, 164.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 73.0, 74.0, 74.0, 74.0, 74.0, 75.0, 74.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 80.0, 79.0, 80.0, 82.0, 79.0, 79.0, 78.0, 79.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 115.0, 114.0, 114.0, 114.0, 110.0, 113.0, 113.0, 111.0, 115.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 147.0, 148.0, 156.0, 147.0, 147.0, 148.0, 148.0, 149.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 94.0, 94.0, 95.0, 95.0, 95.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 53.0, 53.0, 53.0, 58.0, 55.0, 54.0, 56.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 38.0, 37.0, 38.0, 37.0, 37.0, 38.0, 38.0, 38.0], "value": 37.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 85.0, 74.0, 74.0, 75.0, 75.0, 75.0, 76.0, 77.0, 76.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 118.0, 111.0, 114.0, 110.0, 119.0, 114.0, 128.0, 115.0], "value": 115.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 170.0, 171.0, 167.0, 174.0, 170.0, 171.0, 185.0, 172.0, 169.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 66.0, 65.0, 64.0, 65.0, 69.0, 73.0, 68.0, 66.0], "value": 67.7, "unit": "score"}]}]} 12:25:54 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-15 19:25:54.745127Z] 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: 1189s; CPU: 13.0%; Read bytes: 49152; Write bytes: 538763264; Read time: 380; Write time: 394704 12:25:54 INFO - TinderboxPrint: CPU usage
13.1% 12:25:54 INFO - TinderboxPrint: I/O read bytes / time
49,152 / 380 12:25:54 INFO - TinderboxPrint: I/O write bytes / time
538,763,264 / 394,704 12:25:54 INFO - TinderboxPrint: CPU idle
8,236.2 (86.9%) 12:25:54 INFO - TinderboxPrint: CPU user
1,193.6 (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: 4096; Write bytes: 210681856; Read time: 152; Write time: 196600 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: 1172s; CPU: 13.0%; Read bytes: 40960; Write bytes: 322154496; Read time: 224; Write time: 196372 12:25:55 INFO - Running post-run listener: _upload_blobber_files 12:25:55 INFO - Blob upload gear active. 12:25:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:25:55 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:25:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:25:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -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_errorsummary.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_errorsummary.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_raw.log ... 12:25:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:25:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:25:57 INFO - (blobuploader) - INFO - Done attempting. 12:25:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:25:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:25:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:25:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: 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:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:26:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:05 INFO - (blobuploader) - INFO - Done attempting. 12:26:05 INFO - (blobuploader) - INFO - Iteration through files over. 12:26:05 INFO - Return code: 0 12:26:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:26:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:26:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eca11f374f203ac8eac18278691d212fcb92a55739e0c2c0335d29366b36a0beaf42df8d4b08af2dd8656ee56fc11ce060485583f22a30f99c5214a09fdaffce", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bb3956e6a3cd08efb9dad453d8c0b87c1dcd99842d269fcdd444c72459f138b5cf9492e5367e056c32b885b5cc1a62fb8ddc3766da637db64ed7fe3275ca1b4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1ae42a4d4ade5263d9ff5e0a8d3a82cfeb054a230a344fb2b44b97cb61cc49d19a84c1cad6ab462a6ea0f49fac6e3068da56e70da1e58ced0e9488ed3493ccf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3d9f60b1f50c03cf06760e82ca4c7a09cf3731147dffd42e28398a2c26b76909e074e0339eed7beaed7757885cebe67c198c2ee6fc685819749289d3999efe44"} 12:26:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:26:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:26:05 INFO - Contents: 12:26:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eca11f374f203ac8eac18278691d212fcb92a55739e0c2c0335d29366b36a0beaf42df8d4b08af2dd8656ee56fc11ce060485583f22a30f99c5214a09fdaffce", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bb3956e6a3cd08efb9dad453d8c0b87c1dcd99842d269fcdd444c72459f138b5cf9492e5367e056c32b885b5cc1a62fb8ddc3766da637db64ed7fe3275ca1b4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1ae42a4d4ade5263d9ff5e0a8d3a82cfeb054a230a344fb2b44b97cb61cc49d19a84c1cad6ab462a6ea0f49fac6e3068da56e70da1e58ced0e9488ed3493ccf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3d9f60b1f50c03cf06760e82ca4c7a09cf3731147dffd42e28398a2c26b76909e074e0339eed7beaed7757885cebe67c198c2ee6fc685819749289d3999efe44"} 12:26:05 INFO - Running post-run listener: copy_logs_to_upload_dir 12:26:05 INFO - Copying logs to upload dir... 12:26:05 INFO - mkdir: /builds/slave/test/build/upload/logs 12:26:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.514113 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-15 12:26:05.655606) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 12:26:05.663128) ========= 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=2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214 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-Non-PGO/sha512/eca11f374f203ac8eac18278691d212fcb92a55739e0c2c0335d29366b36a0beaf42df8d4b08af2dd8656ee56fc11ce060485583f22a30f99c5214a09fdaffce", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bb3956e6a3cd08efb9dad453d8c0b87c1dcd99842d269fcdd444c72459f138b5cf9492e5367e056c32b885b5cc1a62fb8ddc3766da637db64ed7fe3275ca1b4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1ae42a4d4ade5263d9ff5e0a8d3a82cfeb054a230a344fb2b44b97cb61cc49d19a84c1cad6ab462a6ea0f49fac6e3068da56e70da1e58ced0e9488ed3493ccf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3d9f60b1f50c03cf06760e82ca4c7a09cf3731147dffd42e28398a2c26b76909e074e0339eed7beaed7757885cebe67c198c2ee6fc685819749289d3999efe44"} build_url:https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011222 build_url: 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eca11f374f203ac8eac18278691d212fcb92a55739e0c2c0335d29366b36a0beaf42df8d4b08af2dd8656ee56fc11ce060485583f22a30f99c5214a09fdaffce", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3bb3956e6a3cd08efb9dad453d8c0b87c1dcd99842d269fcdd444c72459f138b5cf9492e5367e056c32b885b5cc1a62fb8ddc3766da637db64ed7fe3275ca1b4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1ae42a4d4ade5263d9ff5e0a8d3a82cfeb054a230a344fb2b44b97cb61cc49d19a84c1cad6ab462a6ea0f49fac6e3068da56e70da1e58ced0e9488ed3493ccf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3d9f60b1f50c03cf06760e82ca4c7a09cf3731147dffd42e28398a2c26b76909e074e0339eed7beaed7757885cebe67c198c2ee6fc685819749289d3999efe44"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 12:26:05.693327) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:26:05.693612) ========= 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=2432103b8b3de4003b23cad4000001c2-1505501798.381961-307999214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:26:05.774618) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 12:26:05.777947) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 12:26:05.778268) ========= ========= Total master_lag: 0.23 =========