builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-028 starttime: 1506474564.92 results: success (0) revision: 70158e4e215d784d1391db5e517b18727f4b3683 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:24.919403) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:24.919870) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:24.920145) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:25.004365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:25.008653) ========= 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=836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-26 18:09:25-- 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% 41.6M=0s 2017-09-26 18:09:25 (41.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111177 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:25.186957) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:25.187262) ========= 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=836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020869 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:25.265428) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-26 18:09:25.265764) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 70158e4e215d784d1391db5e517b18727f4b3683 --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 70158e4e215d784d1391db5e517b18727f4b3683 --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=836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-26 18:09:25,358 truncating revision to first 12 chars 2017-09-26 18:09:25,358 Setting DEBUG logging. 2017-09-26 18:09:25,358 attempt 1/10 2017-09-26 18:09:25,358 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/70158e4e215d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-26 18:09:26,084 unpacking tar archive at: mozilla-central-70158e4e215d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.027559 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-26 18:09:26.383388) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:26.383665) ========= script_repo_revision: 70158e4e215d784d1391db5e517b18727f4b3683 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:26.384042) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:26.384300) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 18:09:26.397765) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-26 18:09:26.398083) ========= /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=836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:09:26 INFO - MultiFileLogger online at 20170926 18:09:26 in /builds/slave/test 18:09:26 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 18:09:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:09:26 INFO - {'append_to_log': False, 18:09:26 INFO - 'base_work_dir': '/builds/slave/test', 18:09:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:09:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:09:26 INFO - 'branch': 'Firefox-Non-PGO', 18:09:26 INFO - 'buildbot_json_path': 'buildprops.json', 18:09:26 INFO - 'code_coverage': False, 18:09:26 INFO - 'config_files': ('talos/linux_config.py',), 18:09:26 INFO - 'default_actions': ('clobber', 18:09:26 INFO - 'read-buildbot-config', 18:09:26 INFO - 'download-and-extract', 18:09:26 INFO - 'populate-webroot', 18:09:26 INFO - 'create-virtualenv', 18:09:26 INFO - 'install', 18:09:26 INFO - 'setup-mitmproxy', 18:09:26 INFO - 'run-tests'), 18:09:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:09:26 INFO - 'disable_ccov_upload': False, 18:09:26 INFO - 'disable_stylo': False, 18:09:26 INFO - 'download_minidump_stackwalk': True, 18:09:26 INFO - 'download_symbols': 'ondemand', 18:09:26 INFO - 'enable_stylo': False, 18:09:26 INFO - 'enable_webrender': False, 18:09:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:09:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:09:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:09:26 INFO - 'gecko_profile': False, 18:09:26 INFO - 'gecko_profile_interval': 0, 18:09:26 INFO - 'installer_path': 'installer.exe', 18:09:26 INFO - 'log_level': 'info', 18:09:26 INFO - 'log_name': 'talos', 18:09:26 INFO - 'log_to_console': True, 18:09:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:09:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:09:26 INFO - 'opt_config_files': (), 18:09:26 INFO - 'pip_index': False, 18:09:26 INFO - 'suite': 'dromaeojs-e10s', 18:09:26 INFO - 'system_bits': '32', 18:09:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:09:26 INFO - 'title': 'talos-linux64-ix-028', 18:09:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:09:26 INFO - 'use_talos_json': True, 18:09:26 INFO - 'verify': 'False', 18:09:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:09:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:09:26 INFO - 'work_dir': 'build'} 18:09:26 INFO - [mozharness: 2017-09-27 01:09:26.556708Z] Running clobber step. 18:09:26 INFO - Running pre-action listener: _resource_record_pre_action 18:09:26 INFO - Running main action method: clobber 18:09:26 INFO - rmtree: /builds/slave/test/build 18:09:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:09:28 INFO - Running post-action listener: _resource_record_post_action 18:09:28 INFO - [mozharness: 2017-09-27 01:09:28.050677Z] Finished clobber step (success) 18:09:28 INFO - [mozharness: 2017-09-27 01:09:28.050774Z] Running read-buildbot-config step. 18:09:28 INFO - Running pre-action listener: _resource_record_pre_action 18:09:28 INFO - Running main action method: read_buildbot_config 18:09:28 INFO - Using buildbot properties: 18:09:28 INFO - { 18:09:28 INFO - "project": "", 18:09:28 INFO - "product": "firefox", 18:09:28 INFO - "who": "kwierso@gmail.com", 18:09:28 INFO - "installer_path": "public/build/target.tar.bz2", 18:09:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:09:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:09:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:09:28 INFO - "stage_platform": "linux64", 18:09:28 INFO - "basedir": "/builds/slave/test", 18:09:28 INFO - "buildnumber": 870, 18:09:28 INFO - "platform": "ubuntu64_hw", 18:09:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:09:28 INFO - "slavebuilddir": "test", 18:09:28 INFO - "taskId": "WhwBaD0gSOaXgM0E28xpVQ", 18:09:28 INFO - "branch": "mozilla-central", 18:09:28 INFO - "script_repo_revision": "production", 18:09:28 INFO - "revision": "70158e4e215d784d1391db5e517b18727f4b3683", 18:09:28 INFO - "slavename": "talos-linux64-ix-028", 18:09:28 INFO - "repo_path": "mozilla-central" 18:09:28 INFO - } 18:09:28 INFO - Finding installer, test and symbols from parent task. 18:09:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WhwBaD0gSOaXgM0E28xpVQ'}, attempt #1 18:09:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WhwBaD0gSOaXgM0E28xpVQ'}, attempt #1 18:09:28 INFO - Task dependencies: XCg3p_MvQCykzqwmBsV7eA 18:09:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA'}, attempt #1 18:09:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2 18:09:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.test_packages.json 18:09:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.crashreporter-symbols.zip 18:09:29 INFO - Running post-action listener: _resource_record_post_action 18:09:29 INFO - [mozharness: 2017-09-27 01:09:29.392189Z] Finished read-buildbot-config step (success) 18:09:29 INFO - [mozharness: 2017-09-27 01:09:29.392408Z] Running download-and-extract step. 18:09:29 INFO - Running pre-action listener: _resource_record_pre_action 18:09:29 INFO - Running main action method: download_and_extract 18:09:29 INFO - mkdir: /builds/slave/test/build/tests 18:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:29 INFO - trying https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.test_packages.json 18:09:29 INFO - Downloading https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:09:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:09:29 INFO - Downloaded 989 bytes. 18:09:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:09:29 INFO - Using the following test package requirements: 18:09:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:09:29 INFO - u'common': [u'target.common.tests.zip'], 18:09:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:09:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:09:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:09:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:09:29 INFO - u'mozbase': [u'target.common.tests.zip'], 18:09:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:09:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:09:29 INFO - u'web-platform': [u'target.common.tests.zip', 18:09:29 INFO - u'target.web-platform.tests.tar.gz'], 18:09:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:09:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:09:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.common.tests.zip 18:09:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:09:29 INFO - Fetch https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.common.tests.zip into memory 18:09:32 INFO - Content-Length response header: 54946143 18:09:32 INFO - Bytes received: 54946143 18:09:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.talos.tests.zip 18:09:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:09:43 INFO - Fetch https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.talos.tests.zip into memory 18:09:44 INFO - Content-Length response header: 14513823 18:09:44 INFO - Bytes received: 14513823 18:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:44 INFO - trying https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2 18:09:44 INFO - Downloading https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:09:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:09:46 INFO - Downloaded 61661854 bytes. 18:09:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2 18:09:46 INFO - mkdir: /builds/slave/test/properties 18:09:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:09:46 INFO - Writing to file /builds/slave/test/properties/build_url 18:09:46 INFO - Contents: 18:09:46 INFO - build_url:https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2 18:09:46 INFO - Running post-action listener: _resource_record_post_action 18:09:46 INFO - Running post-action listener: find_tests_for_verification 18:09:46 INFO - Running post-action listener: set_extra_try_arguments 18:09:46 INFO - [mozharness: 2017-09-27 01:09:46.477640Z] Finished download-and-extract step (success) 18:09:46 INFO - [mozharness: 2017-09-27 01:09:46.477739Z] Running populate-webroot step. 18:09:46 INFO - Running pre-action listener: _resource_record_pre_action 18:09:46 INFO - Running main action method: populate_webroot 18:09:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:09:46 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['tresize', 18:09:46 INFO - 'tcanvasmark']}, 18:09:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:09:46 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['dromaeo_css', 18:09:46 INFO - 'kraken']}, 18:09:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:09:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:09:46 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:09:46 INFO - 'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:09:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:09:46 INFO - 'tests': ['damp', 'tps']}, 18:09:46 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:09:46 INFO - 'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['damp', 'tps']}, 18:09:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:09:46 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['dromaeo_dom']}, 18:09:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:09:46 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['basic_compositor_video', 18:09:46 INFO - 'glvideo']}, 18:09:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:09:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:09:46 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:09:46 INFO - 'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['ts_paint_webext', 18:09:46 INFO - 'tp5o_webext']}, 18:09:46 INFO - 'other-e10s': {'tests': ['a11yr', 18:09:46 INFO - 'ts_paint', 18:09:46 INFO - 'tpaint', 18:09:46 INFO - 'sessionrestore', 18:09:46 INFO - 'sessionrestore_many_windows', 18:09:46 INFO - 'sessionrestore_no_auto_restore', 18:09:46 INFO - 'tabpaint']}, 18:09:46 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['a11yr', 18:09:46 INFO - 'ts_paint', 18:09:46 INFO - 'tpaint', 18:09:46 INFO - 'sessionrestore', 18:09:46 INFO - 'sessionrestore_many_windows', 18:09:46 INFO - 'sessionrestore_no_auto_restore', 18:09:46 INFO - 'tabpaint']}, 18:09:46 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 18:09:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 18:09:46 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['perf_reftest_singletons']}, 18:09:46 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['perf_reftest']}, 18:09:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:09:46 INFO - 'tsvgr_opacity', 18:09:46 INFO - 'tart', 18:09:46 INFO - 'tscrollx', 18:09:46 INFO - 'tsvg_static']}, 18:09:46 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['tsvgx', 18:09:46 INFO - 'tsvgr_opacity', 18:09:46 INFO - 'tart', 18:09:46 INFO - 'tscrollx', 18:09:46 INFO - 'tsvg_static']}, 18:09:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:09:46 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:09:46 INFO - 'talos_options': ['--disable-stylo'], 18:09:46 INFO - 'tests': ['tp5o']}, 18:09:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:09:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:09:46 INFO - 'talos_options': ['--mitmproxy', 18:09:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:09:46 INFO - '--firstNonBlankPaint'], 18:09:46 INFO - 'tests': ['tp6_google', 18:09:46 INFO - 'tp6_youtube', 18:09:46 INFO - 'tp6_amazon', 18:09:46 INFO - 'tp6_facebook']}, 18:09:46 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:09:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:09:46 INFO - 'talos_options': ['--disable-stylo', 18:09:46 INFO - '--mitmproxy', 18:09:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:09:46 INFO - '--firstNonBlankPaint'], 18:09:46 INFO - 'tests': ['tp6_google', 18:09:46 INFO - 'tp6_youtube', 18:09:46 INFO - 'tp6_amazon', 18:09:46 INFO - 'tp6_facebook']}, 18:09:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:09:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:09:46 INFO - 'talos_options': ['--stylo-threads=1', 18:09:46 INFO - '--mitmproxy', 18:09:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:09:46 INFO - '--firstNonBlankPaint'], 18:09:46 INFO - 'tests': ['tp6_google', 18:09:46 INFO - 'tp6_youtube', 18:09:46 INFO - 'tp6_amazon', 18:09:46 INFO - 'tp6_facebook']}, 18:09:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:09:46 INFO - 'talos_options': ['--xperf_path', 18:09:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:09:46 INFO - 'tests': ['tp5n']}, 18:09:46 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:09:46 INFO - 'talos_options': ['--disable-stylo', 18:09:46 INFO - '--xperf_path', 18:09:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:09:46 INFO - 'tests': ['tp5n']}}} 18:09:46 INFO - Running post-action listener: _resource_record_post_action 18:09:46 INFO - [mozharness: 2017-09-27 01:09:46.495012Z] Finished populate-webroot step (success) 18:09:46 INFO - [mozharness: 2017-09-27 01:09:46.495118Z] Running create-virtualenv step. 18:09:46 INFO - Running pre-action listener: _resource_record_pre_action 18:09:46 INFO - Running main action method: create_virtualenv 18:09:46 INFO - Creating virtualenv /builds/slave/test/build/venv 18:09:46 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:09:46 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:09:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:09:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:09:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:09:47 INFO - Installing distribute.............................................................................................................................................................................................done. 18:09:47 INFO - Installing pip...............done. 18:09:47 INFO - Return code: 0 18:09:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:09:47 INFO - Reading from file tmpfile_stdout 18:09:47 INFO - Output received: 18:09:47 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:09:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:47 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 0xaf81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe659c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe62d60>, '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 0xe63a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe63ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', '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 18:09:47 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 18:09:47 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 18:09:47 INFO - Using env: {'DISPLAY': ':0', 18:09:47 INFO - 'HOME': '/home/cltbld', 18:09:47 INFO - 'LANG': 'en_US.UTF-8', 18:09:47 INFO - 'LANGUAGE': 'en_US:en', 18:09:47 INFO - 'LOGNAME': 'cltbld', 18:09:47 INFO - 'MAIL': '/var/mail/cltbld', 18:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:47 INFO - 'MOZ_NO_REMOTE': '1', 18:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:47 INFO - 'NO_EM_RESTART': '1', 18:09:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:47 INFO - 'PWD': '/builds/slave/test', 18:09:47 INFO - 'SHELL': '/bin/bash', 18:09:47 INFO - 'SHLVL': '1', 18:09:47 INFO - 'TERM': 'linux', 18:09:47 INFO - 'TMOUT': '86400', 18:09:47 INFO - 'USER': 'cltbld', 18:09:47 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:47 INFO - '_': '/tools/buildbot/bin/python'} 18:09:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:09:48 INFO - Downloading/unpacking pip>=1.5 18:09:48 INFO - Running setup.py egg_info for package pip 18:09:48 INFO - warning: no files found matching 'pip/cacert.pem' 18:09:48 INFO - warning: no files found matching '*.html' under directory 'docs' 18:09:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:09:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:09:48 INFO - Installing collected packages: pip 18:09:48 INFO - Found existing installation: pip 1.1 18:09:48 INFO - Uninstalling pip: 18:09:48 INFO - Successfully uninstalled pip 18:09:48 INFO - Running setup.py install for pip 18:09:48 INFO - warning: no files found matching 'pip/cacert.pem' 18:09:48 INFO - warning: no files found matching '*.html' under directory 'docs' 18:09:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:09:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:09:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:09:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:09:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:09:48 INFO - Successfully installed pip 18:09:48 INFO - Cleaning up... 18:09:48 INFO - Return code: 0 18:09:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:09: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')]} 18:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09: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')]} 18:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09: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 0xaf81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe659c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe62d60>, '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 0xe63a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe63ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', '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 18:09: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 18:09: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 18:09:48 INFO - Using env: {'DISPLAY': ':0', 18:09:48 INFO - 'HOME': '/home/cltbld', 18:09:48 INFO - 'LANG': 'en_US.UTF-8', 18:09:48 INFO - 'LANGUAGE': 'en_US:en', 18:09:48 INFO - 'LOGNAME': 'cltbld', 18:09:48 INFO - 'MAIL': '/var/mail/cltbld', 18:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:48 INFO - 'MOZ_NO_REMOTE': '1', 18:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:48 INFO - 'NO_EM_RESTART': '1', 18:09:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:48 INFO - 'PWD': '/builds/slave/test', 18:09:48 INFO - 'SHELL': '/bin/bash', 18:09:48 INFO - 'SHLVL': '1', 18:09:48 INFO - 'TERM': 'linux', 18:09:48 INFO - 'TMOUT': '86400', 18:09:48 INFO - 'USER': 'cltbld', 18:09:48 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:48 INFO - '_': '/tools/buildbot/bin/python'} 18:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:49 INFO - Downloading/unpacking psutil>=3.1.1 18:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:09:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:09:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:09:50 INFO - Installing collected packages: psutil 18:09:50 INFO - Running setup.py install for psutil 18:09:50 INFO - building 'psutil._psutil_linux' extension 18:09:50 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 18:09:50 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 18:09:50 INFO - building 'psutil._psutil_posix' extension 18:09:50 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 18:09:50 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 18:09:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:09:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:09:50 INFO - Successfully installed psutil 18:09:50 INFO - Cleaning up... 18:09:50 INFO - Return code: 0 18:09:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:09: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')]} 18:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09: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')]} 18:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09: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 0xaf81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe659c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe62d60>, '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 0xe63a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe63ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', '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 18:09: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 18:09: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 18:09:50 INFO - Using env: {'DISPLAY': ':0', 18:09:50 INFO - 'HOME': '/home/cltbld', 18:09:50 INFO - 'LANG': 'en_US.UTF-8', 18:09:50 INFO - 'LANGUAGE': 'en_US:en', 18:09:50 INFO - 'LOGNAME': 'cltbld', 18:09:50 INFO - 'MAIL': '/var/mail/cltbld', 18:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:50 INFO - 'MOZ_NO_REMOTE': '1', 18:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:50 INFO - 'NO_EM_RESTART': '1', 18:09:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:50 INFO - 'PWD': '/builds/slave/test', 18:09:50 INFO - 'SHELL': '/bin/bash', 18:09:50 INFO - 'SHLVL': '1', 18:09:50 INFO - 'TERM': 'linux', 18:09:50 INFO - 'TMOUT': '86400', 18:09:50 INFO - 'USER': 'cltbld', 18:09:50 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:50 INFO - '_': '/tools/buildbot/bin/python'} 18:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:09: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 18:09: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 18:09: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 18:09: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 18:09:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:09:52 INFO - Installing collected packages: mozsystemmonitor 18:09:52 INFO - Running setup.py install for mozsystemmonitor 18:09:52 INFO - Successfully installed mozsystemmonitor 18:09:52 INFO - Cleaning up... 18:09:53 INFO - Return code: 0 18:09:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:09: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')]} 18:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09: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')]} 18:09:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xaf81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe659c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe62d60>, '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 0xe63a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe63ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', '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 18:09:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:09:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:09:53 INFO - Using env: {'DISPLAY': ':0', 18:09:53 INFO - 'HOME': '/home/cltbld', 18:09:53 INFO - 'LANG': 'en_US.UTF-8', 18:09:53 INFO - 'LANGUAGE': 'en_US:en', 18:09:53 INFO - 'LOGNAME': 'cltbld', 18:09:53 INFO - 'MAIL': '/var/mail/cltbld', 18:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:53 INFO - 'MOZ_NO_REMOTE': '1', 18:09:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:53 INFO - 'NO_EM_RESTART': '1', 18:09:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:53 INFO - 'PWD': '/builds/slave/test', 18:09:53 INFO - 'SHELL': '/bin/bash', 18:09:53 INFO - 'SHLVL': '1', 18:09:53 INFO - 'TERM': 'linux', 18:09:53 INFO - 'TMOUT': '86400', 18:09:53 INFO - 'USER': 'cltbld', 18:09:53 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:53 INFO - '_': '/tools/buildbot/bin/python'} 18:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:53 INFO - Downloading/unpacking jsonschema==2.5.1 18:09: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 18:09: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 18:09: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 18:09: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 18:09:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:09:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:09: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 18:09: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 18:09: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 18:09: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 18:09:54 INFO - Downloading functools32-3.2.3-2.tar.gz 18:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:09:54 INFO - warning: no files found matching '*.txt' 18:09:54 INFO - no previously-included directories found matching 'build' 18:09:54 INFO - no previously-included directories found matching 'dist' 18:09:54 INFO - no previously-included directories found matching '.git*' 18:09:54 INFO - Installing collected packages: jsonschema, functools32 18:09:54 INFO - Running setup.py install for functools32 18:09:54 INFO - warning: no files found matching '*.txt' 18:09:54 INFO - no previously-included directories found matching 'build' 18:09:54 INFO - no previously-included directories found matching 'dist' 18:09:54 INFO - no previously-included directories found matching '.git*' 18:09:54 INFO - Successfully installed jsonschema functools32 18:09:54 INFO - Cleaning up... 18:09:54 INFO - Return code: 0 18:09:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:09:54 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')]} 18:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:54 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')]} 18:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:54 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 0xaf81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe659c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe62d60>, '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 0xe63a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe63ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', '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 18:09:54 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 18:09:54 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 18:09:54 INFO - Using env: {'DISPLAY': ':0', 18:09:54 INFO - 'HOME': '/home/cltbld', 18:09:54 INFO - 'LANG': 'en_US.UTF-8', 18:09:54 INFO - 'LANGUAGE': 'en_US:en', 18:09:54 INFO - 'LOGNAME': 'cltbld', 18:09:54 INFO - 'MAIL': '/var/mail/cltbld', 18:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:54 INFO - 'MOZ_NO_REMOTE': '1', 18:09:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:54 INFO - 'NO_EM_RESTART': '1', 18:09:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:54 INFO - 'PWD': '/builds/slave/test', 18:09:54 INFO - 'SHELL': '/bin/bash', 18:09:54 INFO - 'SHLVL': '1', 18:09:54 INFO - 'TERM': 'linux', 18:09:54 INFO - 'TMOUT': '86400', 18:09:54 INFO - 'USER': 'cltbld', 18:09:54 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:54 INFO - '_': '/tools/buildbot/bin/python'} 18:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:09:55 INFO - Cleaning up... 18:09:55 INFO - Return code: 0 18:09:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:09: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')]} 18:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09: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')]} 18:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xaf81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe659c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe62d60>, '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 0xe63a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe63ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', '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 18:09:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:09:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:09:55 INFO - Using env: {'DISPLAY': ':0', 18:09:55 INFO - 'HOME': '/home/cltbld', 18:09:55 INFO - 'LANG': 'en_US.UTF-8', 18:09:55 INFO - 'LANGUAGE': 'en_US:en', 18:09:55 INFO - 'LOGNAME': 'cltbld', 18:09:55 INFO - 'MAIL': '/var/mail/cltbld', 18:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:55 INFO - 'MOZ_NO_REMOTE': '1', 18:09:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:55 INFO - 'NO_EM_RESTART': '1', 18:09:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:55 INFO - 'PWD': '/builds/slave/test', 18:09:55 INFO - 'SHELL': '/bin/bash', 18:09:55 INFO - 'SHLVL': '1', 18:09:55 INFO - 'TERM': 'linux', 18:09:55 INFO - 'TMOUT': '86400', 18:09:55 INFO - 'USER': 'cltbld', 18:09:55 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:55 INFO - '_': '/tools/buildbot/bin/python'} 18:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:55 INFO - Downloading/unpacking blobuploader==1.2.4 18:09: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 18:09: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 18:09: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 18:09: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 18:09:56 INFO - Downloading blobuploader-1.2.4.tar.gz 18:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:09:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:09:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:56 INFO - Downloading docopt-0.6.1.tar.gz 18:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:09:57 INFO - Installing collected packages: blobuploader, requests, docopt 18:09:57 INFO - Running setup.py install for blobuploader 18:09:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:09:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:09:57 INFO - Running setup.py install for requests 18:09:57 INFO - Running setup.py install for docopt 18:09:57 INFO - Successfully installed blobuploader requests docopt 18:09:57 INFO - Cleaning up... 18:09:57 INFO - Return code: 0 18:09:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:57 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 0xaf81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe659c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe62d60>, '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 0xe63a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe63ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', '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 18:09:57 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 18:09:57 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 18:09:57 INFO - Using env: {'DISPLAY': ':0', 18:09:57 INFO - 'HOME': '/home/cltbld', 18:09:57 INFO - 'LANG': 'en_US.UTF-8', 18:09:57 INFO - 'LANGUAGE': 'en_US:en', 18:09:57 INFO - 'LOGNAME': 'cltbld', 18:09:57 INFO - 'MAIL': '/var/mail/cltbld', 18:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:57 INFO - 'MOZ_NO_REMOTE': '1', 18:09:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:57 INFO - 'NO_EM_RESTART': '1', 18:09:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:57 INFO - 'PWD': '/builds/slave/test', 18:09:57 INFO - 'SHELL': '/bin/bash', 18:09:57 INFO - 'SHLVL': '1', 18:09:57 INFO - 'TERM': 'linux', 18:09:57 INFO - 'TMOUT': '86400', 18:09:57 INFO - 'USER': 'cltbld', 18:09:57 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:57 INFO - '_': '/tools/buildbot/bin/python'} 18:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:09:57 INFO - Running setup.py (path:/tmp/pip-YruZYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:09:57 INFO - Running setup.py (path:/tmp/pip-0pfqpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:09:57 INFO - Running setup.py (path:/tmp/pip-vq_7ev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:09:58 INFO - Running setup.py (path:/tmp/pip-sJQwIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:09:58 INFO - Running setup.py (path:/tmp/pip-c5sq8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:09:58 INFO - Running setup.py (path:/tmp/pip-JnDIjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:09:58 INFO - Running setup.py (path:/tmp/pip-OEh32C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:09:58 INFO - Running setup.py (path:/tmp/pip-GSEn9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:09:58 INFO - Running setup.py (path:/tmp/pip-pAb8Ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:09:58 INFO - Running setup.py (path:/tmp/pip-WUR74S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:09:58 INFO - Running setup.py (path:/tmp/pip-qQ2KKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:09:58 INFO - Running setup.py (path:/tmp/pip-Kf0UJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:09:58 INFO - Running setup.py (path:/tmp/pip-OydVUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:09:58 INFO - Running setup.py (path:/tmp/pip-8qx2P7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:09:58 INFO - Running setup.py (path:/tmp/pip-zCRMM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:09:58 INFO - Running setup.py (path:/tmp/pip-z3lBdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:09:58 INFO - Running setup.py (path:/tmp/pip-z2prx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:09:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:09:59 INFO - Running setup.py install for manifestparser 18:09:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:09:59 INFO - Running setup.py install for mozcrash 18:09:59 INFO - Running setup.py install for mozdebug 18:09:59 INFO - Running setup.py install for mozdevice 18:09:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:09:59 INFO - Running setup.py install for mozfile 18:09:59 INFO - Running setup.py install for mozhttpd 18:09:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:09:59 INFO - Running setup.py install for mozinfo 18:09:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:09:59 INFO - Running setup.py install for mozInstall 18:09:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:09:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:09:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:09:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:09:59 INFO - Running setup.py install for mozleak 18:09:59 INFO - Running setup.py install for mozlog 18:10:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:10:00 INFO - Running setup.py install for moznetwork 18:10:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:10:00 INFO - Running setup.py install for mozprocess 18:10:00 INFO - Running setup.py install for mozprofile 18:10:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:10:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:10:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:10:00 INFO - Running setup.py install for mozrunner 18:10:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:10:00 INFO - Running setup.py install for mozscreenshot 18:10:00 INFO - Running setup.py install for moztest 18:10:00 INFO - Running setup.py install for mozversion 18:10:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:10:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:10:00 INFO - Cleaning up... 18:10:00 INFO - Return code: 0 18:10:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:00 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 0xaf81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe659c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe62d60>, '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 0xe63a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe63ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', '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 18:10:00 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 18:10:00 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 18:10:00 INFO - Using env: {'DISPLAY': ':0', 18:10:00 INFO - 'HOME': '/home/cltbld', 18:10:00 INFO - 'LANG': 'en_US.UTF-8', 18:10:00 INFO - 'LANGUAGE': 'en_US:en', 18:10:00 INFO - 'LOGNAME': 'cltbld', 18:10:00 INFO - 'MAIL': '/var/mail/cltbld', 18:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:00 INFO - 'MOZ_NO_REMOTE': '1', 18:10:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:00 INFO - 'NO_EM_RESTART': '1', 18:10:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:00 INFO - 'PWD': '/builds/slave/test', 18:10:00 INFO - 'SHELL': '/bin/bash', 18:10:00 INFO - 'SHLVL': '1', 18:10:00 INFO - 'TERM': 'linux', 18:10:00 INFO - 'TMOUT': '86400', 18:10:00 INFO - 'USER': 'cltbld', 18:10:00 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:00 INFO - '_': '/tools/buildbot/bin/python'} 18:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:10:01 INFO - Running setup.py (path:/tmp/pip-sjJE3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:10:01 INFO - Running setup.py (path:/tmp/pip-ZwH6PH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:10:01 INFO - Running setup.py (path:/tmp/pip-kXUbf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:10:01 INFO - Running setup.py (path:/tmp/pip-zTgKxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:10:01 INFO - Running setup.py (path:/tmp/pip-OcENDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:10:01 INFO - Running setup.py (path:/tmp/pip-YBgD8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:10:01 INFO - Running setup.py (path:/tmp/pip-6Zhe91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:10:01 INFO - Running setup.py (path:/tmp/pip-wfIZPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:10:01 INFO - Running setup.py (path:/tmp/pip-kuw3ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:10:01 INFO - Running setup.py (path:/tmp/pip-BP5qub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:10:01 INFO - Running setup.py (path:/tmp/pip-9Pq9ID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:10:01 INFO - Running setup.py (path:/tmp/pip-XlvSKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:10:01 INFO - Running setup.py (path:/tmp/pip-MB5a5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:10:01 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)) 18:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:10:01 INFO - Running setup.py (path:/tmp/pip-aQUo_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:10:02 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)) 18:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:02 INFO - Running setup.py (path:/tmp/pip-9Xp6sT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:02 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)) 18:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:10:02 INFO - Running setup.py (path:/tmp/pip-Bc6h8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:10:02 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)) 18:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:10:02 INFO - Running setup.py (path:/tmp/pip-AR32FX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:10:02 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)) 18:10:02 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)) 18:10:02 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)) 18:10:02 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)) 18:10:02 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)) 18:10:02 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)) 18:10:02 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)) 18:10:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:10: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 18:10: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 18:10: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 18:10: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 18:10:03 INFO - Downloading blessings-1.6.tar.gz 18:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:10:03 INFO - Installing collected packages: blessings 18:10:03 INFO - Running setup.py install for blessings 18:10:03 INFO - Successfully installed blessings 18:10:03 INFO - Cleaning up... 18:10:03 INFO - Return code: 0 18:10:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:10:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:10:03 INFO - Reading from file tmpfile_stdout 18:10:03 INFO - Current package versions: 18:10:03 INFO - argparse == 1.2.1 18:10:03 INFO - blessings == 1.6 18:10:03 INFO - blobuploader == 1.2.4 18:10:03 INFO - docopt == 0.6.1 18:10:03 INFO - functools32 == 3.2.3-2 18:10:03 INFO - jsonschema == 2.5.1 18:10:03 INFO - manifestparser == 1.1 18:10:03 INFO - mozInstall == 1.13 18:10:03 INFO - mozcrash == 1.0 18:10:03 INFO - mozdebug == 0.1 18:10:03 INFO - mozdevice == 0.51 18:10:03 INFO - mozfile == 1.2 18:10:03 INFO - mozhttpd == 0.7 18:10:03 INFO - mozinfo == 0.10 18:10:03 INFO - mozleak == 0.1 18:10:03 INFO - mozlog == 3.5 18:10:03 INFO - moznetwork == 0.27 18:10:03 INFO - mozprocess == 0.25 18:10:03 INFO - mozprofile == 0.28 18:10:03 INFO - mozrunner == 6.13 18:10:03 INFO - mozscreenshot == 0.1 18:10:03 INFO - mozsystemmonitor == 0.3 18:10:03 INFO - moztest == 0.8 18:10:03 INFO - mozversion == 1.4 18:10:03 INFO - psutil == 3.1.1 18:10:03 INFO - requests == 1.2.3 18:10:03 INFO - wsgiref == 0.1.2 18:10:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:03 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 0xaf81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe659c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe62d60>, '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 0xe63a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe63ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', '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 18:10:03 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 18:10:03 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 18:10:03 INFO - Using env: {'DISPLAY': ':0', 18:10:03 INFO - 'HOME': '/home/cltbld', 18:10:03 INFO - 'LANG': 'en_US.UTF-8', 18:10:03 INFO - 'LANGUAGE': 'en_US:en', 18:10:03 INFO - 'LOGNAME': 'cltbld', 18:10:03 INFO - 'MAIL': '/var/mail/cltbld', 18:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:03 INFO - 'MOZ_NO_REMOTE': '1', 18:10:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:03 INFO - 'NO_EM_RESTART': '1', 18:10:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:03 INFO - 'PWD': '/builds/slave/test', 18:10:03 INFO - 'SHELL': '/bin/bash', 18:10:03 INFO - 'SHLVL': '1', 18:10:03 INFO - 'TERM': 'linux', 18:10:03 INFO - 'TMOUT': '86400', 18:10:03 INFO - 'USER': 'cltbld', 18:10:03 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:03 INFO - '_': '/tools/buildbot/bin/python'} 18:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:04 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)) 18:10:04 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)) 18:10:04 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)) 18:10:04 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)) 18:10:04 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)) 18:10:04 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)) 18:10:04 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)) 18:10:04 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)) 18:10:04 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)) 18:10:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:10:04 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 18:10:04 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 18:10:04 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 18:10:04 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 18:10:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:10:05 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)) 18:10:05 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)) 18:10:05 INFO - Installing collected packages: simplejson 18:10:05 INFO - Running setup.py install for simplejson 18:10:05 INFO - building 'simplejson._speedups' extension 18:10:05 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 18:10:06 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 18:10:06 INFO - Successfully installed simplejson 18:10:06 INFO - Cleaning up... 18:10:06 INFO - Return code: 0 18:10:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:06 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 0xaf81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc0b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe659c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe62d60>, '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 0xe63a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe63ef0>, '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': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', '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 18:10:06 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 18:10:06 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 18:10:06 INFO - Using env: {'DISPLAY': ':0', 18:10:06 INFO - 'HOME': '/home/cltbld', 18:10:06 INFO - 'LANG': 'en_US.UTF-8', 18:10:06 INFO - 'LANGUAGE': 'en_US:en', 18:10:06 INFO - 'LOGNAME': 'cltbld', 18:10:06 INFO - 'MAIL': '/var/mail/cltbld', 18:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:06 INFO - 'MOZ_NO_REMOTE': '1', 18:10:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:06 INFO - 'NO_EM_RESTART': '1', 18:10:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:06 INFO - 'PWD': '/builds/slave/test', 18:10:06 INFO - 'SHELL': '/bin/bash', 18:10:06 INFO - 'SHLVL': '1', 18:10:06 INFO - 'TERM': 'linux', 18:10:06 INFO - 'TMOUT': '86400', 18:10:06 INFO - 'USER': 'cltbld', 18:10:06 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:06 INFO - '_': '/tools/buildbot/bin/python'} 18:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:10:06 INFO - Cleaning up... 18:10:06 INFO - Return code: 0 18:10:06 INFO - Running post-action listener: _resource_record_post_action 18:10:06 INFO - Running post-action listener: _start_resource_monitoring 18:10:06 INFO - Starting resource monitoring. 18:10:06 INFO - [mozharness: 2017-09-27 01:10:06.529002Z] Finished create-virtualenv step (success) 18:10:06 INFO - [mozharness: 2017-09-27 01:10:06.529547Z] Running install step. 18:10:06 INFO - Running pre-action listener: _resource_record_pre_action 18:10:06 INFO - Running main action method: install 18:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:10:06 INFO - Reading from file tmpfile_stdout 18:10:06 INFO - Detecting whether we're running mozinstall >=1.0... 18:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:10:06 INFO - Reading from file tmpfile_stdout 18:10:06 INFO - Output received: 18:10:06 INFO - Usage: mozinstall [options] installer 18:10:06 INFO - Options: 18:10:06 INFO - -h, --help show this help message and exit 18:10:06 INFO - -d DEST, --destination=DEST 18:10:06 INFO - Directory to install application into. [default: 18:10:06 INFO - "/builds/slave/test"] 18:10:06 INFO - --app=APP Application being installed. [default: firefox] 18:10:06 INFO - mkdir: /builds/slave/test/build/application 18:10:06 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'] 18:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:10:24 INFO - Reading from file tmpfile_stdout 18:10:24 INFO - Output received: 18:10:24 INFO - /builds/slave/test/build/application/firefox/firefox 18:10:24 INFO - Running post-action listener: _resource_record_post_action 18:10:24 INFO - [mozharness: 2017-09-27 01:10:24.711474Z] Finished install step (success) 18:10:24 INFO - [mozharness: 2017-09-27 01:10:24.711730Z] Running setup-mitmproxy step. 18:10:24 INFO - Running pre-action listener: _resource_record_pre_action 18:10:24 INFO - Running main action method: setup_mitmproxy 18:10:24 INFO - Skipping: mitmproxy is not required 18:10:24 INFO - Running post-action listener: _resource_record_post_action 18:10:24 INFO - [mozharness: 2017-09-27 01:10:24.712864Z] Finished setup-mitmproxy step (success) 18:10:24 INFO - [mozharness: 2017-09-27 01:10:24.713084Z] Running run-tests step. 18:10:24 INFO - Running pre-action listener: _resource_record_pre_action 18:10:24 INFO - Running pre-action listener: _set_gcov_prefix 18:10:24 INFO - Running main action method: run_tests 18:10:24 WARNING - Try message not found. 18:10:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:10:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:10:24 INFO - Python 2.7.3 18:10:24 INFO - Return code: 0 18:10:24 INFO - grabbing minidump binary from tooltool 18:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:10:24 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 0xe62d60>, '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 0xe63a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe63ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:10:24 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 18:10:24 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 18:10:24 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 18:10:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:10:24 INFO - Return code: 0 18:10:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:10:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:10:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:10:24 INFO - ENV: RUST_BACKTRACE is now full 18:10:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:10:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:10:24 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/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 18:10:24 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/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 18:10:24 INFO - Using env: {'DISPLAY': ':0', 18:10:24 INFO - 'HOME': '/home/cltbld', 18:10:24 INFO - 'LANG': 'en_US.UTF-8', 18:10:24 INFO - 'LANGUAGE': 'en_US:en', 18:10:24 INFO - 'LOGNAME': 'cltbld', 18:10:24 INFO - 'MAIL': '/var/mail/cltbld', 18:10:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:10:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:24 INFO - 'MOZ_NO_REMOTE': '1', 18:10:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:10:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:24 INFO - 'NO_EM_RESTART': '1', 18:10:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:24 INFO - 'PWD': '/builds/slave/test', 18:10:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:10:24 INFO - 'RUST_BACKTRACE': 'full', 18:10:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:10:24 INFO - 'SHELL': '/bin/bash', 18:10:24 INFO - 'SHLVL': '1', 18:10:24 INFO - 'TERM': 'linux', 18:10:24 INFO - 'TMOUT': '86400', 18:10:24 INFO - 'USER': 'cltbld', 18:10:24 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:24 INFO - '_': '/tools/buildbot/bin/python'} 18:10:24 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/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 18:10:25 INFO - mozversion application_buildid: 20170927001127 18:10:25 INFO - mozversion application_changeset: 70158e4e215d784d1391db5e517b18727f4b3683 18:10:25 INFO - mozversion application_display_name: Nightly 18:10:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:10:25 INFO - mozversion application_name: Firefox 18:10:25 INFO - mozversion application_remotingname: firefox 18:10:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:10:25 INFO - mozversion application_vendor: Mozilla 18:10:25 INFO - mozversion application_version: 58.0a1 18:10:25 INFO - mozversion platform_buildid: 20170927001127 18:10:25 INFO - mozversion platform_changeset: 70158e4e215d784d1391db5e517b18727f4b3683 18:10:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:10:25 INFO - mozversion platform_version: 58.0a1 18:10:25 INFO - using testdate: 1506474625 18:10:25 INFO - actual date: 1506474625 18:10:25 INFO - starting webserver on 'localhost:42283' 18:10:25 INFO - SUITE-START | Running 2 tests 18:10:25 INFO - TEST-START | dromaeo_css 18:10:25 INFO - Initialising browser for dromaeo_css test... 18:10:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42283/getInfo.html -profile /tmp/tmp4wiTLr/profile 18:10:25 INFO - TEST-INFO | started process 11525 (/builds/slave/test/build/application/firefox/firefox http://localhost:42283/getInfo.html) 18:10:30 INFO - TEST-INFO | 11525: exit 0 18:10:31 INFO - Browser initialized. 18:10:31 INFO - Running cycle 1/1 for dromaeo_css test... 18:10:31 INFO - Using env: {'DISPLAY': ':0', 18:10:31 INFO - 'HOME': '/home/cltbld', 18:10:31 INFO - 'JSGC_DISABLE_POISONING': '1', 18:10:31 INFO - 'LANG': 'en_US.UTF-8', 18:10:31 INFO - 'LANGUAGE': 'en_US:en', 18:10:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:10:31 INFO - 'LOGNAME': 'cltbld', 18:10:31 INFO - 'MAIL': '/var/mail/cltbld', 18:10:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:10:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:10:31 INFO - 'MOZ_CRASHREPORTER': '1', 18:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:10:31 INFO - 'MOZ_NO_REMOTE': '1', 18:10:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:10:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:10:31 INFO - 'NO_EM_RESTART': '1', 18:10:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:31 INFO - 'PWD': '/builds/slave/test', 18:10:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:10:31 INFO - 'RUST_BACKTRACE': 'full', 18:10:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:10:31 INFO - 'SHELL': '/bin/bash', 18:10:31 INFO - 'SHLVL': '1', 18:10:31 INFO - 'TERM': 'linux', 18:10:31 INFO - 'TMOUT': '86400', 18:10:31 INFO - 'USER': 'cltbld', 18:10:31 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:31 INFO - '_': '/tools/buildbot/bin/python'} 18:10:31 INFO - TEST-INFO | started process 11757 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4wiTLr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:10:31 INFO - PID 11757 | 18:10:31 INFO - PID 11757 | (/builds/slave/test/build/application/firefox/firefox:11806): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:10:31 INFO - PID 11757 | 18:10:42 INFO - PID 11757 | RSS: Main: 153333760 18:10:42 INFO - PID 11757 | 18:13:40 INFO - PID 11757 | Cycle 1(1): loaded http://localhost:42283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42283/tests/dromaeo/cssquery-ext.html) 18:13:41 INFO - PID 11757 | RSS: Main: 153292800 18:13:41 INFO - PID 11757 | 18:16:42 INFO - PID 11757 | Cycle 1(1): loaded http://localhost:42283/tests/dromaeo/cssquery-ext.html (next: http://localhost:42283/tests/dromaeo/cssquery-jquery.html) 18:16:43 INFO - PID 11757 | RSS: Main: 153427968 18:16:43 INFO - PID 11757 | 18:19:45 INFO - PID 11757 | Cycle 1(1): loaded http://localhost:42283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42283/tests/dromaeo/cssquery-mootools.html) 18:19:45 INFO - PID 11757 | RSS: Main: 156200960 18:19:45 INFO - PID 11757 | 18:22:47 INFO - PID 11757 | Cycle 1(1): loaded http://localhost:42283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42283/tests/dromaeo/cssquery-prototype.html) 18:22:48 INFO - PID 11757 | RSS: Main: 154120192 18:22:48 INFO - PID 11757 | 18:25:51 INFO - PID 11757 | Cycle 1(1): loaded http://localhost:42283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42283/tests/dromaeo/cssquery-yui.html) 18:25:52 INFO - PID 11757 | RSS: Main: 155054080 18:25:52 INFO - PID 11757 | 18:28:53 INFO - PID 11757 | Cycle 1(1): loaded http://localhost:42283/tests/dromaeo/cssquery-yui.html (next: http://localhost:42283/tests/dromaeo/cssquery-dojo.html) 18:28:53 INFO - PID 11757 | RSS: Main: 158425088 18:28:53 INFO - PID 11757 | 18:28:53 INFO - PID 11757 | __start_tp_report 18:28:53 INFO - PID 11757 | _x_x_mozilla_page_load 18:28:53 INFO - PID 11757 | _x_x_mozilla_page_load_details 18:28:53 INFO - PID 11757 | |i|pagename|runs| 18:28:53 INFO - PID 11757 | |0;dojo.html;3088.351446196299;3131.577237072995;3137.2470607054283;3144.2453811085347;3197.600299962505;6068.39316068393;6187.597806142598;6196.876062478754;6231.439170474657;6298.937010629898;6158.96920515398;6206.813795586139;6208.286047104584;6231.3145553989025;6242.594231374957;5752.36723960364;5805.5936084474;5808.12878068289;5829.679367634779;5855.268091488564;1292.9165969022297;5609.298837645294;5775.48020678141;5862.179294898718;5875.676837773921;5646.548276137899;5676.347220069703;5706.629069110494;5718.685472299022;5771.192033115367;5710.628809127394;5716.1425786131995;5747.540196784247;5778.0466223073145;5796.304443466769;5614.101743721026;5655.095184770458;5687.601867869251;5735.139729040636;5736.311642602873;6075.969620151871;6156.322804491502;6251.12484252205;6278.277998030239;6285.937140628581;6334.3032266450655;6491.415772580443;6592.406683398517;6632.949917199985;6691.498137639648;6595.6372399518;6610.768623098168;6627.701753421107;6628.304028077031;6631.469482441393;6016.398360163948;6064.090386442025;6129.724162412702;6182.381761823872;6187.628742275433;6845.5550389224545;6853.691583878737;6908.723651054002;6944.618046007518;6949.3745562899585;6310.895593271159;6519.087327774116;6525.1517302750335;6573.638449885302;6605.405513503808;6547.148870646785;6552.9344706553275;6556.213254409501;6567.934320656828;6613.503987200979;6565;6618.30507796684;6664.933350666528;6682.264950855402;6688.598684078874;6622.37087476689;6692.364225398676;6735.326467353323;6735.663216839139;6737.326267373223;5188.195829646411;5204.141316682781;5268.947310526923;5279;5284.920726189111;4433.357163211316;4528.660350473728;4547.931781023287;4556.612687921497;4592.747398893026;5643.209950606918;5654.293213348332;5655.208270842086;5666.943330566724;5667;6540.37866402691;6555.278919318871;6568.573042752206;6657.201135863727;6684.2647308795995;5489.313835770528;5577.721113944286;5649.745761440663;5652.63257888236;5659.688717120515;5621.578381621394;5648.068068768689;5682.488576028178;5690.430956904276;5691.089425691869;6255.874882502325;6353.078803573456;6386.393292637192;6416.486681065504;6428.967855160694;6634.402903738686;6703.22912865017;6728.495362847708;6733.293004234582;6739.494537909578;6363.96818015907;6369.45859601939;6401.64790936503;6449.7420103195345;6464.806055818333;6308.905366419599;6425.58233714807;6513.837154071186;6606.273309935807;6616.867662646817;6639.568428052161;6673.132492775908;6682.231543372574;6688.531802773759;6698.966505167443;6450.741970321134;6459.773907913201;6487.935120648733;6504.674766261763;6522.15212022434;6333.683315834282;6424.453921416545;6506.804795856132;6515.28331883502;6529.477641788741;6224.968875155595;6348.174737284124;6350.142730731292;6444.1622589065;6450.161479007699;6475.708593113415;6514.6416947068365;6520.706568204348;6534.379233972765;6577.901331479936;6190.133381326528;6300.432961033529;6338.841528961812;6363.713632886624;6390.808275751735 18:28:53 INFO - PID 11757 | |1;ext.html;841.5329492131864;887.0730087059022;906.8186362727452;907.2832462354736;908.7410088124884;39675.2064958701;40840.387394189114;40857.59142408575;40895.38656920149;40914.18171636569;38502.22995540088;41498.377524337084;42194.7890260549;42490.57509424905;42682.7865860671;7962.880556791652;8098.838023239532;8101.878471822898;8102.675892964275;8106.189381061906;7934.404919631022;8038.678452861878;8079.798005049892;8132.552709600969;8137.633806478693;8121.147279535652;8152.592370381487;8152.592370381487;8160.877586836202;8171.10117887032;8079.555624440653;8125.959370203141;8127.674893004273;8128.837423251502;8131.227533384319;7887.211278872124;7915.722949696733;7957.045154581488;7962.3630109591095;7963.243491868321;37816.62183378186;37836.43245351322;38088.80955595232;38115;38131.23737525262;3534.6465353464705;3564.8574057037686;3565.340412023758;3568.8750893718857;3575.1777091268896;31187.064388068393;31288.687113128806;31345.529817052764;31389;31417.8429107853;4785.808567657289;4951.306817045616;4972.651914365996;5007.824726134604;5127.128388174019;190119;190930;191040;191100;191130;47101.29348059782;47603.5239647606;47677.76161119172;47778.522214777404;47786.28320575194;53343;53916.46083539193;53974.73012634912;54466.72766636142;54482.455175447736;15437.228138593026;15811.36754529829;15820.446284379992;15856.44502442432;15947.122908239926;12893.161944473579;12941;12941.482340706267;12951.287679177736;12954.481820727065;13563.186208827501;13654.795178072336;13746.793798093036;13800.378982945791;13963.79054314186;5442.537384322377;5623.634463759842;5629.54963602919;5656.547476201894;5684.54523638108;998.7702828349375;1005.9144972677258;1021.7343490692474;1030.4899074957943;1038.2161468091542;22964.0480559151;23024.19415320489;23071.769282306963;23100.191493297654;23156.073757049868;1100.862392200975;1102.079763397556;1106.31961343773;1109.9223054386277;1115.1803424482998;12090.153689241668;12154.392280385946;12232.75534489323;12301.446434910451;12326.691832704255;230707;231209;231974;232009;232331;43771.12457750827;50964.74517627462;51316.743416283425;51459.228111578355;52049;1394.2610416479283;1410.746065708162;1418.4609848257596;1419.4109444580383;1431.3344294902713;26917.461650767265;27166.592501112103;27250.182494525197;27426.862865686344;27795.722042779315;36427.45358819567;36480.45279320866;36549.26901461903;36552.81723591365;36565;194007;194507;195840;196694;197679;32712.50931236081;33476.49785253272;33711.83144084264;33727.66272337245;33733.83133084319;43816.78091609649;44003.77998109989;44137.55862441463;44284.11431771411;44350.55649443464;31728.20679482939;31927;32070.19824504406;32210.03369898813;32265;29244.976425824992;29401.705982939897;30087.69912300849;30135.849320753256;30440.238994025327;34105.48841767326;35122.64877351194;35209.647903520636;35335.46996794999;35511.644883550834;30571.54142687817;30850.38299233958;31114.22214444657;31147.377052459276;31372.529412058382;22879.542409152058;22949.655755164018;22979.425514361606;23407.297781066594;23436.296911092693 18:28:53 INFO - PID 11757 | |2;jquery.html;570.2102587915736;580.3963877567328;585.7334912614759;586.4633860018083;611.4160976267665;22193.22323718668;22980.425489362773;22985.08059677615;22994.965226564804;23024.19415320464;20755.792442075573;21212.787872121313;24014.15950441735;24139.63790543143;24343.75656243437;6441.581297215689;6540.313267106957;6582.17722784652;6601;6630.071789949411;6184.752609895599;6438.645874476902;6454.9677251613675;6479.578827376229;6569.638669873155;6410.294867564564;6414.550981431302;6427.614343139403;6427.646479443652;6429.164208652892;6304.401081897213;6402.583832050902;6431.614103153825;6456.354364563553;6468.191476065492;6116.174316467264;6132.325444201134;6132.448079672851;6153;6162.4453799157845;28059.71940280612;28408;28508.429831403464;28535.57196642052;28557.57163642547;2070.9482262943397;2097.339338108506;2106.536561956367;2107.9578408431744;2110.788921107877;18979;19012.239510419706;19319;19477.707834382494;19642.90178549098;2351.729551101628;2382.725986511556;2401.4236583220077;2413;2442.3894026493376;117042;122537.38731306286;125472;136617.95073073913;141735.29132354376;14388.640283992774;14538.491152809602;14544.345504452325;14637.707245855023;14653.194074326015;22569.435764106027;22575.661365079537;22616.434589135402;22647.886760566093;22653.43366415853;9824.607015719434;9981.80036399268;10019.949900250598;10035.448050357301;10046.949765251273;9156.267498600097;9196.678116266032;9213.539323033821;9303.76740581491;9303.76740581491;8717;8755.255803256794;8915.598798054103;8916.55417229136;9028.458292502472;2783.5268004439295;2797.8461184634634;2808.4523517913813;2810.4519618674162;2877.5395936650034;959.3092973059386;971.32007594684;973.8490533967247;987.6247026130025;987.9901200988041;18310.267589296545;18351.357702480615;18572.978486183118;18722;18915.810841891405;1027.9691609251734;1028.3264461777549;1036.8185567525652;1036.9585216591402;1045.9320144190604;9953.601855925826;10095.848562271573;10388.532516036645;10422.5830966762;10543.630972915911;453796;467852;468929;475191;475393;105759;108333;109559.45220273848;109670;110623.44688276507;1130.5308297056629;1154.8787377325427;1208.8005479096028;1238.6407941696987;1239.6528971887885;19988.300409485597;20008;20022.69965950483;20212.78723276608;20248.291309804084;59610;59996;60067;61078.69460652668;62378.68810655917;75816.62091689506;76378.61810690911;76499.61750191436;76763;77032;13766;14241.715165696834;14246.643833904236;14261.857381426053;14275.857241427453;21659.133634654438;21779;21779.891100544395;22063.11747530145;22105.66841497347;17360.131993400028;17608.91195544014;17728;17821.287148513915;17834.37579684705;15539.611509711895;15645.139517326656;15744.29150688199;15783.368665253261;15895.364185432452;18153.909230453763;18309.54226144357;18328.541786455444;18388.17253223635;18477.260909563465;13482.93258533701;14049.578512644637;14143;14182.00725949215;14301.92849035748;16166.595835104217;16194.919025404797;16206.432774852823;16372.181390930644;16407.261673224493 18:28:53 INFO - PID 11757 | |3;mootools.html;1317.5388613985106;1383.1148065238249;1388.007574584172;1390.026981113221;1392.5265409760686;14470.204138772377;15134.697306053873;15169.92415037926;15173.241337933114;15178.089314641129;14751.18868462234;14912.701745965102;14918.104913705185;14946.327415266313;14982.475613353536;2636.670416197975;2747.3406382468165;2752.545829938058;2753.8347699138117;2754.5454999924987;2715.2261605442454;2769.515334816409;2789.7628701560393;2790.065328115084;2795.692473827877;2617.0840205927866;2752.1330780803983;2765.281026933002;2766.5573508238685;2768.0588599876037;2801.921260314773;2803.957940630892;2810.747032767061;2828.7878409119294;2832.7450529452244;2634.249238966885;2709.024751089606;2732.7130651281727;2733.876975536106;2740.287525243431;14737.926310368379;14861.256937153206;14863.925680371636;14880;14889;7735.535867847891;7993;7994.640241189102;8001.1198768135455;8004;4834.613230941552;4942.9011419771405;5022.347094877679;5201.765920533585;5304.734763261821;1680.7226807576735;1693.669734401792;1703.335699077361;1740.5822602575417;1744.3197153110298;25346.619800703003;25694.74305256942;25700.357491062685;25709.3572660685;25764.871175644003;7262.092238470192;7282.599457029808;7428.368588670023;7487.475876688688;7494.887576686354;16908.32366705318;17128.143592820557;17140.828591714173;17175.141242937803;17188.65622687539;7703.6918523259565;7833.804154896059;7835.255650713173;7845.843083138306;7862.213778622092;7134.892976605355;7155.248698886646;7231.63841807918;7267.982482452461;7456.888146677804;5045.571126454292;5184.37787465499;5195.2986346843445;5208.348956380452;5242.7116508591635;1554.1762865681208;1557.9454719085004;1560.1029408090394;1562.414094714482;1575.3304845440641;874.7026011156238;908.7410088124852;915.4736026784625;922.9446233226028;932.4684929590069;7204.92795072053;7471.663775130132;7548;7608.657610407545;7651.234876512304;712.2307907459931;725.1624373848214;744.7877354953812;751.6579956119953;752.3379426105101;5152.896942061213;5160.638755287094;5185.429602743695;5193.922091168635;5201.921971170435;20753;21343.573128537344;21425.142994280053;21507.354779356643;21572.784272157078;19994.70007949882;21172.364829055154;21697.56604867894;21701.67447488289;21715.674264886038;10989.945050274697;11036.227464077438;11081.445927703582;11130.888691113147;11183.329000259848;8086.474379165335;8127.674893004214;8164.918350816653;8257.917420825956;8272.296854767412;17420.390286339916;17540.298388064333;17961.461156165336;18070.09649517545;18509.72235416443;18926.242950281834;19012.334568290044;19044;19360.322388716326;19552.31566895152;10940.781184376108;11073.446327683745;11163.051140653135;11206.943965280121;11213.327200368005;13723.313834308045;13822.516211932534;13851.30743462843;13889.791653125414;13911.234882081179;11283.379414131974;11297.209195356247;11480.598179063692;11588.420578971187;11593.478293476983;10942.507587158438;10991.395473249047;11023.944880275547;11151.163662725166;11206.047485963785;10020.549075291776;10133.13868321201;10183.796324073624;10190.694279171637;10193.133583645176;7049.400800931979;7205;7216.7113315466795;7227.8554428912175;7241.601711905896;6739.258681544928;6968.407685346802;6970.546914450544;7073.434125270069;7135.82160445993 18:28:53 INFO - PID 11757 | |4;prototype.html;491.8008206676293;508.94401615822267;523.3432042786303;533.8512487238863;535.3147970597634;6080.939190608093;6124.632522048681;6143.283313595278;6201.689915504229;6265.18552588164;6061.757529698818;6074.331823499422;6087.421694938985;6119.0209566469375;6146.508279337653;5120.820771273006;5150.845474635767;5154.561862241714;5164.431912489623;5165.612579056567;4786.329913812069;4941.160002799515;4947.158982972904;5051.863330750589;5067.555746612216;4886.047220791947;4896.485868983759;4900.803967841282;4952.702837829724;4958.652894297401;4905.166121759309;4937.012597480519;4948.554630083309;4969.627277954131;4972.229304457815;4354.521002689701;4447.466304043503;4460.33095035744;4471.703206070236;4477.126960242754;5956.910646340309;6012.30858451275;6039.547033972426;6056.727447264883;6062.151298818169;5661.7735290588325;5673.631213971079;5705.372409035002;5711.314642242957;5712.200291959129;6432.903506447407;6474.384933431364;6487.594013143091;6492.480601551864;6514.706838192245;4606.170889239936;4869.780859861315;4882.3408839806525;4896.338994235768;4912.926306105412;6616.172978377703;6680.799576012728;6710.563813352116;6772.932270677279;6818.693158807866;4949.851504454872;4951.8266860659705;4973.975130124326;4988;5031.320771695848;5035.118854200574;5065.518775716375;5091.949080509222;5093.134167191586;5111.897762044739;5605.607607467438;5611.7755289788765;5656.490915817596;5704.600677952503;5708.257926469532;6607.174103237096;6611.603303801691;6612.702428390734;6617.272100068989;6644.876907853301;1737.9391721289694;1743.755874177616;1760.4806582058322;1762.4184019273866;1778.208697129765;1156.2079975217002;1205.2949024820402;1219.1587804414926;1238.4303220518482;1238.7832129377323;5587.8824235152315;5616.606837521416;5620.91568626471;5629.677025898911;5658.660480371191;6462.967685161544;6480.546361754632;6522.5760325578685;6554.967225163844;6609;5554.611177217636;5598.836657288619;5618.185363122325;5642.871425714873;5651.406602306781;3482.564679415073;3543.450765131409;3545.1314427965312;3545.94681079784;3550.183457804668;6353.807936261982;6357.936420635827;6416.871662566722;6429.22849258092;6457.418832305115;6701.664916754143;6765.627890466071;6777.3900348967845;6811.148606424197;6822.351876571718;6056.515478761688;6223.253209614875;6378.362163783678;6402.295747467868;6411.4229719325485;6482.416582507597;6490.643014634241;6526.67366631657;6574.8356291093105;6582.4986505127745;6430.64631445275;6461.644609546332;6474.028895665688;6481.222253329442;6506.772262970773;6179.342882560542;6248.625082495065;6267.467265282502;6293.748250069946;6299.748010079728;4945.480724524017;5010.824621138243;5016.720736212243;5026.824061157842;5039.974800125976;6334.334894835976;6506.707198175999;6565.967170164118;6635.8009259722285;6663.600183988976;6255.624662520263;6313.90529142054;6331.398517140864;6386.521010924293;6424.582402143846;6388.233411990591;6402.871942561216;6472.805815825533;6482.773102941374;6483.2544257411;6392.3927226913365;6423.42189202974;6440.355964403522;6446.452051575481;6451.935480645321;6480.675966201766;6500.837479063061;6528.21661400635;6539.934600654123;6586.4401525868925;6202.906956395567;6222.564420490522;6237.469815065589;6295.98528470885;6330.208723909512 18:28:53 INFO - PID 11757 | |5;yui.html;891.0955380289004;917.5962576466354;932.8134373125376;940.14916500567;954.0697819625866;19460.221591136356;20250.59498810023;20272.8986355068;20372.490687732796;20386.796132038693;18202;18605.720914186266;18846.43460696181;18898.055097245116;19067.618647627038;957.5930229652405;982.3565564555192;984.5815528400437;987.8715766950322;989.3124278626358;959.4483172175992;973.2311473935582;974.6296407365191;974.7124598243536;975.7853272280087;1042.405828677654;1046.6546039806844;1050.0969166516788;1053.6470282455386;1053.7576357437795;1023.8822535408449;1030.5671617920416;1034.2915103154364;1043.4209013997267;1043.7390652336953;711.6192836832322;728.2535401213745;734.4785202506174;738.1032046064005;738.3354980517523;19355.4193374199;19633.803661963484;19703.01484925748;19786.208551657917;19819.207231710716;21425.14299428021;21548;22148.114075436963;22351.66472502914;22413.767242801638;16543.338266469327;16879.324827006905;16895.324187032504;16999.57501062471;17080.914595427068;3778.9433158502643;3793.0896584819443;3804.885853424402;3805.562360328542;3808.028952617065;137860.31069844688;138332;141167.29416352854;141896;144599;16962.915185423994;16977.405790797264;17000.23498942526;17088.829111708972;17203.311867525408;30503.542446863314;31075.067747967598;31859.36281274408;31997.36005279928;32001.19997000047;8631.827363452696;8689.956550217335;8734.825303493895;8743.256823170103;8751.124887511198;3648.343298206348;4112.547619761824;4160.022394737295;4169.291220492523;4194.559571245037;5057.266696329012;5142.6914385137015;5155.74221288934;5183.403908550489;5186;966.429806414219;988.7083310423442;999.645125980281;1001.9298649094493;1003.1322905686528;624.8344188789883;634.9841253968596;640.4492136762381;642.877853207889;647.2233320015999;11785.646430607096;11808.291502509705;12100.092493063055;12174.878251217375;12481.438335274934;677.6713294052381;689.4139981016096;690.3062422265691;691.4572060932144;706.2090458686304;5153.896922061613;5180.637355385087;5245.737713114329;5320.627556071116;5409.323834520685;122073.38963305127;126079;128039;129087;129458;104643.4767826156;109459.45270273757;109876.4506177464;113625.43187284011;114954;89840.5507972456;92262.53868730614;92796;94308.52845735864;98600.01399986052;9348.439093654402;9380.390274632127;9448.291378146803;9563.713088607354;9626.181774548962;42939.35590966201;43657;43906;44318.335224972296;44937.32594011027;53903.19145212747;54104.72947635394;55008.449915500336;55370.72314638401;55452.44547554473;15761.211939403212;16011.279492422636;16033.518994430186;16037.518874433787;16192.757108643615;16534.421295254608;16690.666186675957;16869.156542172597;16877.746833797748;16913.15434228259;15470.613234669223;15558.455454059054;15832.92083539621;16006.839931601;16080.276387562822;15168.696626067638;15174;15310.234488275319;15517.379304828131;15547.37810487613;15046;15236.31436585334;15244;15347.07917524952;15382.230888455757;10989.780204396027;10999.725006874893;11093.722656933642;11177.217594768614;11256.943715281372;10754.193435492212;10849.403282819521;10856;10945.616903408343;11044.889551104707 18:28:53 INFO - PID 11757 | __end_tp_report 18:28:53 INFO - PID 11757 | __start_cc_report 18:28:53 INFO - PID 11757 | _x_x_mozilla_cycle_collect,300 18:28:53 INFO - PID 11757 | __end_cc_report 18:28:53 INFO - PID 11757 | __startTimestamp1506475733898__endTimestamp 18:28:53 INFO - PID 11757 | 18:28:53 INFO - PID 11757 | ------- Summary: start ------- 18:28:53 INFO - PID 11757 | Number of tests: 6 18:28:53 INFO - PID 11757 | 18:28:53 INFO - PID 11757 | [#0] dojo.html Cycles:165 Average:6070.04 Median:6334.30 stddev:818.08 (12.9%) stddev-sans-first:786.44 18:28:53 INFO - PID 11757 | Values: 3088.4 3131.6 3137.2 3144.2 3197.6 6068.4 6187.6 6196.9 6231.4 6298.9 6159.0 6206.8 6208.3 6231.3 6242.6 5752.4 5805.6 5808.1 5829.7 5855.3 1292.9 5609.3 5775.5 5862.2 5875.7 5646.5 5676.3 5706.6 5718.7 5771.2 5710.6 5716.1 5747.5 5778.0 5796.3 5614.1 5655.1 5687.6 5735.1 5736.3 6076.0 6156.3 6251.1 6278.3 6285.9 6334.3 6491.4 6592.4 6632.9 6691.5 6595.6 6610.8 6627.7 6628.3 6631.5 6016.4 6064.1 6129.7 6182.4 6187.6 6845.6 6853.7 6908.7 6944.6 6949.4 6310.9 6519.1 6525.2 6573.6 6605.4 6547.1 6552.9 6556.2 6567.9 6613.5 6565.0 6618.3 6664.9 6682.3 6688.6 6622.4 6692.4 6735.3 6735.7 6737.3 5188.2 5204.1 5268.9 5279.0 5284.9 4433.4 4528.7 4547.9 4556.6 4592.7 5643.2 5654.3 5655.2 5666.9 5667.0 6540.4 6555.3 6568.6 6657.2 6684.3 5489.3 5577.7 5649.7 5652.6 5659.7 5621.6 5648.1 5682.5 5690.4 5691.1 6255.9 6353.1 6386.4 6416.5 6429.0 6634.4 6703.2 6728.5 6733.3 6739.5 6364.0 6369.5 6401.6 6449.7 6464.8 6308.9 6425.6 6513.8 6606.3 6616.9 6639.6 6673.1 6682.2 6688.5 6699.0 6450.7 6459.8 6487.9 6504.7 6522.2 6333.7 6424.5 6506.8 6515.3 6529.5 6225.0 6348.2 6350.1 6444.2 6450.2 6475.7 6514.6 6520.7 6534.4 6577.9 6190.1 6300.4 6338.8 6363.7 6390.8 18:28:53 INFO - PID 11757 | 18:28:53 INFO - PID 11757 | [#1] ext.html Cycles:180 Average:37511.16 Median:35166.15 stddev:53564.09 (152.3%) stddev-sans-first:53643.58 18:28:53 INFO - PID 11757 | Values: 841.5 887.1 906.8 907.3 908.7 39675.2 40840.4 40857.6 40895.4 40914.2 38502.2 41498.4 42194.8 42490.6 42682.8 7962.9 8098.8 8101.9 8102.7 8106.2 7934.4 8038.7 8079.8 8132.6 8137.6 8121.1 8152.6 8152.6 8160.9 8171.1 8079.6 8126.0 8127.7 8128.8 8131.2 7887.2 7915.7 7957.0 7962.4 7963.2 37816.6 37836.4 38088.8 38115.0 38131.2 3534.6 3564.9 3565.3 3568.9 3575.2 31187.1 31288.7 31345.5 31389.0 31417.8 4785.8 4951.3 4972.7 5007.8 5127.1 190119.0 190930.0 191040.0 191100.0 191130.0 47101.3 47603.5 47677.8 47778.5 47786.3 53343.0 53916.5 53974.7 54466.7 54482.5 15437.2 15811.4 15820.4 15856.4 15947.1 12893.2 12941.0 12941.5 12951.3 12954.5 13563.2 13654.8 13746.8 13800.4 13963.8 5442.5 5623.6 5629.5 5656.5 5684.5 998.8 1005.9 1021.7 1030.5 1038.2 22964.0 23024.2 23071.8 23100.2 23156.1 1100.9 1102.1 1106.3 1109.9 1115.2 12090.2 12154.4 12232.8 12301.4 12326.7 230707.0 231209.0 231974.0 232009.0 232331.0 43771.1 50964.7 51316.7 51459.2 52049.0 1394.3 1410.7 1418.5 1419.4 1431.3 26917.5 27166.6 27250.2 27426.9 27795.7 36427.5 36480.5 36549.3 36552.8 36565.0 194007.0 194507.0 195840.0 196694.0 197679.0 32712.5 33476.5 33711.8 33727.7 33733.8 43816.8 44003.8 44137.6 44284.1 44350.6 31728.2 31927.0 32070.2 32210.0 32265.0 29245.0 29401.7 30087.7 30135.8 30440.2 34105.5 35122.6 35209.6 35335.5 35511.6 30571.5 30850.4 31114.2 31147.4 31372.5 22879.5 22949.7 22979.4 23407.3 23436.3 18:28:53 INFO - PID 11757 | 18:28:53 INFO - PID 11757 | [#2] jquery.html Cycles:180 Average:33654.96 Median:22572.55 stddev:78900.91 (349.5%) stddev-sans-first:79083.14 18:28:53 INFO - PID 11757 | Values: 570.2 580.4 585.7 586.5 611.4 22193.2 22980.4 22985.1 22995.0 23024.2 20755.8 21212.8 24014.2 24139.6 24343.8 6441.6 6540.3 6582.2 6601.0 6630.1 6184.8 6438.6 6455.0 6479.6 6569.6 6410.3 6414.6 6427.6 6427.6 6429.2 6304.4 6402.6 6431.6 6456.4 6468.2 6116.2 6132.3 6132.4 6153.0 6162.4 28059.7 28408.0 28508.4 28535.6 28557.6 2070.9 2097.3 2106.5 2108.0 2110.8 18979.0 19012.2 19319.0 19477.7 19642.9 2351.7 2382.7 2401.4 2413.0 2442.4 117042.0 122537.4 125472.0 136618.0 141735.3 14388.6 14538.5 14544.3 14637.7 14653.2 22569.4 22575.7 22616.4 22647.9 22653.4 9824.6 9981.8 10019.9 10035.4 10046.9 9156.3 9196.7 9213.5 9303.8 9303.8 8717.0 8755.3 8915.6 8916.6 9028.5 2783.5 2797.8 2808.5 2810.5 2877.5 959.3 971.3 973.8 987.6 988.0 18310.3 18351.4 18573.0 18722.0 18915.8 1028.0 1028.3 1036.8 1037.0 1045.9 9953.6 10095.8 10388.5 10422.6 10543.6 453796.0 467852.0 468929.0 475191.0 475393.0 105759.0 108333.0 109559.5 109670.0 110623.4 1130.5 1154.9 1208.8 1238.6 1239.7 19988.3 20008.0 20022.7 20212.8 20248.3 59610.0 59996.0 60067.0 61078.7 62378.7 75816.6 76378.6 76499.6 76763.0 77032.0 13766.0 14241.7 14246.6 14261.9 14275.9 21659.1 21779.0 21779.9 22063.1 22105.7 17360.1 17608.9 17728.0 17821.3 17834.4 15539.6 15645.1 15744.3 15783.4 15895.4 18153.9 18309.5 18328.5 18388.2 18477.3 13482.9 14049.6 14143.0 14182.0 14301.9 16166.6 16194.9 16206.4 16372.2 16407.3 18:28:53 INFO - PID 11757 | 18:28:53 INFO - PID 11757 | [#3] mootools.html Cycles:180 Average:9211.12 Median:2625.67 stddev:6547.74 (249.4%) stddev-sans-first:6539.24 18:28:53 INFO - PID 11757 | Values: 1317.5 1383.1 1388.0 1390.0 1392.5 14470.2 15134.7 15169.9 15173.2 15178.1 14751.2 14912.7 14918.1 14946.3 14982.5 2636.7 2747.3 2752.5 2753.8 2754.5 2715.2 2769.5 2789.8 2790.1 2795.7 2617.1 2752.1 2765.3 2766.6 2768.1 2801.9 2804.0 2810.7 2828.8 2832.7 2634.2 2709.0 2732.7 2733.9 2740.3 14737.9 14861.3 14863.9 14880.0 14889.0 7735.5 7993.0 7994.6 8001.1 8004.0 4834.6 4942.9 5022.3 5201.8 5304.7 1680.7 1693.7 1703.3 1740.6 1744.3 25346.6 25694.7 25700.4 25709.4 25764.9 7262.1 7282.6 7428.4 7487.5 7494.9 16908.3 17128.1 17140.8 17175.1 17188.7 7703.7 7833.8 7835.3 7845.8 7862.2 7134.9 7155.2 7231.6 7268.0 7456.9 5045.6 5184.4 5195.3 5208.3 5242.7 1554.2 1557.9 1560.1 1562.4 1575.3 874.7 908.7 915.5 922.9 932.5 7204.9 7471.7 7548.0 7608.7 7651.2 712.2 725.2 744.8 751.7 752.3 5152.9 5160.6 5185.4 5193.9 5201.9 20753.0 21343.6 21425.1 21507.4 21572.8 19994.7 21172.4 21697.6 21701.7 21715.7 10989.9 11036.2 11081.4 11130.9 11183.3 8086.5 8127.7 8164.9 8257.9 8272.3 17420.4 17540.3 17961.5 18070.1 18509.7 18926.2 19012.3 19044.0 19360.3 19552.3 10940.8 11073.4 11163.1 11206.9 11213.3 13723.3 13822.5 13851.3 13889.8 13911.2 11283.4 11297.2 11480.6 11588.4 11593.5 10942.5 10991.4 11023.9 11151.2 11206.0 10020.5 10133.1 10183.8 10190.7 10193.1 7049.4 7205.0 7216.7 7227.9 7241.6 6739.3 6968.4 6970.5 7073.4 7135.8 18:28:53 INFO - PID 11757 | 18:28:53 INFO - PID 11757 | [#4] prototype.html Cycles:180 Average:5439.84 Median:6061.95 stddev:1505.33 (24.8%) stddev-sans-first:1463.02 18:28:53 INFO - PID 11757 | Values: 491.8 508.9 523.3 533.9 535.3 6080.9 6124.6 6143.3 6201.7 6265.2 6061.8 6074.3 6087.4 6119.0 6146.5 5120.8 5150.8 5154.6 5164.4 5165.6 4786.3 4941.2 4947.2 5051.9 5067.6 4886.0 4896.5 4900.8 4952.7 4958.7 4905.2 4937.0 4948.6 4969.6 4972.2 4354.5 4447.5 4460.3 4471.7 4477.1 5956.9 6012.3 6039.5 6056.7 6062.2 5661.8 5673.6 5705.4 5711.3 5712.2 6432.9 6474.4 6487.6 6492.5 6514.7 4606.2 4869.8 4882.3 4896.3 4912.9 6616.2 6680.8 6710.6 6772.9 6818.7 4949.9 4951.8 4974.0 4988.0 5031.3 5035.1 5065.5 5091.9 5093.1 5111.9 5605.6 5611.8 5656.5 5704.6 5708.3 6607.2 6611.6 6612.7 6617.3 6644.9 1737.9 1743.8 1760.5 1762.4 1778.2 1156.2 1205.3 1219.2 1238.4 1238.8 5587.9 5616.6 5620.9 5629.7 5658.7 6463.0 6480.5 6522.6 6555.0 6609.0 5554.6 5598.8 5618.2 5642.9 5651.4 3482.6 3543.5 3545.1 3545.9 3550.2 6353.8 6357.9 6416.9 6429.2 6457.4 6701.7 6765.6 6777.4 6811.1 6822.4 6056.5 6223.3 6378.4 6402.3 6411.4 6482.4 6490.6 6526.7 6574.8 6582.5 6430.6 6461.6 6474.0 6481.2 6506.8 6179.3 6248.6 6267.5 6293.7 6299.7 4945.5 5010.8 5016.7 5026.8 5040.0 6334.3 6506.7 6566.0 6635.8 6663.6 6255.6 6313.9 6331.4 6386.5 6424.6 6388.2 6402.9 6472.8 6482.8 6483.3 6392.4 6423.4 6440.4 6446.5 6451.9 6480.7 6500.8 6528.2 6539.9 6586.4 6202.9 6222.6 6237.5 6296.0 6330.2 18:28:53 INFO - PID 11757 | 18:28:53 INFO - PID 11757 | [#5] yui.html Cycles:180 Average:24281.30 Median:20379.64 stddev:35742.52 (175.4%) stddev-sans-first:35799.64 18:28:53 INFO - PID 11757 | Values: 891.1 917.6 932.8 940.1 954.1 19460.2 20250.6 20272.9 20372.5 20386.8 18202.0 18605.7 18846.4 18898.1 19067.6 957.6 982.4 984.6 987.9 989.3 959.4 973.2 974.6 974.7 975.8 1042.4 1046.7 1050.1 1053.6 1053.8 1023.9 1030.6 1034.3 1043.4 1043.7 711.6 728.3 734.5 738.1 738.3 19355.4 19633.8 19703.0 19786.2 19819.2 21425.1 21548.0 22148.1 22351.7 22413.8 16543.3 16879.3 16895.3 16999.6 17080.9 3778.9 3793.1 3804.9 3805.6 3808.0 137860.3 138332.0 141167.3 141896.0 144599.0 16962.9 16977.4 17000.2 17088.8 17203.3 30503.5 31075.1 31859.4 31997.4 32001.2 8631.8 8690.0 8734.8 8743.3 8751.1 3648.3 4112.5 4160.0 4169.3 4194.6 5057.3 5142.7 5155.7 5183.4 5186.0 966.4 988.7 999.6 1001.9 1003.1 624.8 635.0 640.4 642.9 647.2 11785.6 11808.3 12100.1 12174.9 12481.4 677.7 689.4 690.3 691.5 706.2 5153.9 5180.6 5245.7 5320.6 5409.3 122073.4 126079.0 128039.0 129087.0 129458.0 104643.5 109459.5 109876.5 113625.4 114954.0 89840.6 92262.5 92796.0 94308.5 98600.0 9348.4 9380.4 9448.3 9563.7 9626.2 42939.4 43657.0 43906.0 44318.3 44937.3 53903.2 54104.7 55008.4 55370.7 55452.4 15761.2 16011.3 16033.5 16037.5 16192.8 16534.4 16690.7 16869.2 16877.7 16913.2 15470.6 15558.5 15832.9 16006.8 16080.3 15168.7 15174.0 15310.2 15517.4 15547.4 15046.0 15236.3 15244.0 15347.1 15382.2 10989.8 10999.7 11093.7 11177.2 11256.9 10754.2 10849.4 10856.0 10945.6 11044.9 18:28:53 INFO - PID 11757 | -------- Summary: end -------- 18:28:53 INFO - PID 11757 | 18:28:54 INFO - TEST-INFO | 11757: exit 0 18:28:54 INFO - TEST-OK | dromaeo_css | took 1109551ms 18:28:54 INFO - TEST-START | kraken 18:28:54 INFO - Initialising browser for kraken test... 18:28:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42283/getInfo.html -profile /tmp/tmpOfvIQk/profile 18:28:54 INFO - TEST-INFO | started process 12130 (/builds/slave/test/build/application/firefox/firefox http://localhost:42283/getInfo.html) 18:29:00 INFO - TEST-INFO | 12130: exit 0 18:29:00 INFO - Browser initialized. 18:29:00 INFO - Running cycle 1/1 for kraken test... 18:29:00 INFO - Using env: {'DISPLAY': ':0', 18:29:00 INFO - 'HOME': '/home/cltbld', 18:29:00 INFO - 'JSGC_DISABLE_POISONING': '1', 18:29:00 INFO - 'LANG': 'en_US.UTF-8', 18:29:00 INFO - 'LANGUAGE': 'en_US:en', 18:29:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:29:00 INFO - 'LOGNAME': 'cltbld', 18:29:00 INFO - 'MAIL': '/var/mail/cltbld', 18:29:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:29:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:29:00 INFO - 'MOZ_CRASHREPORTER': '1', 18:29:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:29:00 INFO - 'MOZ_NO_REMOTE': '1', 18:29:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:29:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:00 INFO - 'NO_EM_RESTART': '1', 18:29:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:00 INFO - 'PWD': '/builds/slave/test', 18:29:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:29:00 INFO - 'RUST_BACKTRACE': 'full', 18:29:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:29:00 INFO - 'SHELL': '/bin/bash', 18:29:00 INFO - 'SHLVL': '1', 18:29:00 INFO - 'TERM': 'linux', 18:29:00 INFO - 'TMOUT': '86400', 18:29:00 INFO - 'USER': 'cltbld', 18:29:00 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031', 18:29:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:00 INFO - '_': '/tools/buildbot/bin/python'} 18:29:00 INFO - TEST-INFO | started process 12364 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOfvIQk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:29:01 INFO - PID 12364 | 18:29:01 INFO - PID 12364 | (/builds/slave/test/build/application/firefox/firefox:12413): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:29:01 INFO - PID 12364 | 18:29:11 INFO - PID 12364 | RSS: Main: 151797760 18:29:11 INFO - PID 12364 | 18:29:14 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42283/tests/kraken/driver.html?testName=audio-beat-detection) 18:29:15 INFO - PID 12364 | RSS: Main: 158175232 18:29:15 INFO - PID 12364 | 18:29:17 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42283/tests/kraken/driver.html?testName=audio-dft) 18:29:18 INFO - PID 12364 | RSS: Main: 158789632 18:29:18 INFO - PID 12364 | 18:29:21 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42283/tests/kraken/driver.html?testName=audio-fft) 18:29:22 INFO - PID 12364 | RSS: Main: 154791936 18:29:22 INFO - PID 12364 | 18:29:24 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42283/tests/kraken/driver.html?testName=audio-oscillator) 18:29:24 INFO - PID 12364 | RSS: Main: 155164672 18:29:24 INFO - PID 12364 | 18:29:26 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:29:27 INFO - PID 12364 | RSS: Main: 155578368 18:29:27 INFO - PID 12364 | 18:29:30 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42283/tests/kraken/driver.html?testName=imaging-darkroom) 18:29:31 INFO - PID 12364 | RSS: Main: 158031872 18:29:31 INFO - PID 12364 | 18:29:35 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42283/tests/kraken/driver.html?testName=imaging-desaturate) 18:29:35 INFO - PID 12364 | RSS: Main: 158380032 18:29:35 INFO - PID 12364 | 18:29:38 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42283/tests/kraken/driver.html?testName=json-parse-financial) 18:29:39 INFO - PID 12364 | RSS: Main: 157978624 18:29:39 INFO - PID 12364 | 18:29:40 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:29:41 INFO - PID 12364 | RSS: Main: 157933568 18:29:41 INFO - PID 12364 | 18:29:43 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42283/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:29:43 INFO - PID 12364 | RSS: Main: 155226112 18:29:43 INFO - PID 12364 | 18:29:45 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:29:46 INFO - PID 12364 | RSS: Main: 154763264 18:29:46 INFO - PID 12364 | 18:29:49 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:29:49 INFO - PID 12364 | RSS: Main: 154718208 18:29:49 INFO - PID 12364 | 18:29:52 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:29:53 INFO - PID 12364 | RSS: Main: 154828800 18:29:53 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | Cycle 1(1): loaded http://localhost:42283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42283/tests/kraken/driver.html?testName=ai-astar) 18:29:55 INFO - PID 12364 | RSS: Main: 156401664 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | __start_tp_report 18:29:55 INFO - PID 12364 | _x_x_mozilla_page_load 18:29:55 INFO - PID 12364 | _x_x_mozilla_page_load_details 18:29:55 INFO - PID 12364 | |i|pagename|runs| 18:29:55 INFO - PID 12364 | |0;ai-astar;109;109;108;109;108;107;108;107;107;107 18:29:55 INFO - PID 12364 | |1;audio-beat-detection;118;115;119;115;118;158;118;119;115;140 18:29:55 INFO - PID 12364 | |2;audio-dft;165;161;162;163;163;162;162;163;167;164 18:29:55 INFO - PID 12364 | |3;audio-fft;75;74;75;74;74;77;80;76;74;78 18:29:55 INFO - PID 12364 | |4;audio-oscillator;80;81;78;80;81;79;80;79;78;78 18:29:55 INFO - PID 12364 | |5;imaging-gaussian-blur;114;117;113;113;115;113;114;116;115;117 18:29:55 INFO - PID 12364 | |6;imaging-darkroom;150;150;148;147;148;147;148;147;148;149 18:29:55 INFO - PID 12364 | |7;imaging-desaturate;102;94;94;94;94;94;94;94;94;94 18:29:55 INFO - PID 12364 | |8;json-parse-financial;52;52;53;52;54;55;54;52;52;52 18:29:55 INFO - PID 12364 | |9;json-stringify-tinderbox;38;39;39;38;38;39;39;39;38;46 18:29:55 INFO - PID 12364 | |10;stanford-crypto-aes;73;73;74;72;78;77;73;77;74;74 18:29:55 INFO - PID 12364 | |11;stanford-crypto-ccm;111;109;109;121;117;119;121;117;113;114 18:29:55 INFO - PID 12364 | |12;stanford-crypto-pbkdf2;173;176;168;166;164;168;167;173;171;168 18:29:55 INFO - PID 12364 | |13;stanford-crypto-sha256-iterative;67;74;67;73;66;64;66;62;71;65 18:29:55 INFO - PID 12364 | __end_tp_report 18:29:55 INFO - PID 12364 | __start_cc_report 18:29:55 INFO - PID 12364 | _x_x_mozilla_cycle_collect,773 18:29:55 INFO - PID 12364 | __end_cc_report 18:29:55 INFO - PID 12364 | __startTimestamp1506475795445__endTimestamp 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | ------- Summary: start ------- 18:29:55 INFO - PID 12364 | Number of tests: 14 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#0] ai-astar Cycles:10 Average:107.90 Median:108.00 stddev:0.88 (0.8%) stddev-sans-first:0.83 18:29:55 INFO - PID 12364 | Values: 109.0 109.0 108.0 109.0 108.0 107.0 108.0 107.0 107.0 107.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:118.50 stddev:14.18 (12.0%) stddev-sans-first:14.90 18:29:55 INFO - PID 12364 | Values: 118.0 115.0 119.0 115.0 118.0 158.0 118.0 119.0 115.0 140.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:1.75 (1.1%) stddev-sans-first:1.73 18:29:55 INFO - PID 12364 | Values: 165.0 161.0 162.0 163.0 163.0 162.0 162.0 163.0 167.0 164.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#3] audio-fft Cycles:10 Average:75.70 Median:75.50 stddev:2.06 (2.7%) stddev-sans-first:2.17 18:29:55 INFO - PID 12364 | Values: 75.0 74.0 75.0 74.0 74.0 77.0 80.0 76.0 74.0 78.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#4] audio-oscillator Cycles:10 Average:79.40 Median:80.00 stddev:1.17 (1.5%) stddev-sans-first:1.22 18:29:55 INFO - PID 12364 | Values: 80.0 81.0 78.0 80.0 81.0 79.0 80.0 79.0 78.0 78.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:115.00 stddev:1.57 (1.4%) stddev-sans-first:1.64 18:29:55 INFO - PID 12364 | Values: 114.0 117.0 113.0 113.0 115.0 113.0 114.0 116.0 115.0 117.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.00 stddev:1.14 (0.8%) stddev-sans-first:1.00 18:29:55 INFO - PID 12364 | Values: 150.0 150.0 148.0 147.0 148.0 147.0 148.0 147.0 148.0 149.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.00 stddev:2.53 (2.7%) stddev-sans-first:0.00 18:29:55 INFO - PID 12364 | Values: 102.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:52.50 stddev:1.14 (2.2%) stddev-sans-first:1.17 18:29:55 INFO - PID 12364 | Values: 52.0 52.0 53.0 52.0 54.0 55.0 54.0 52.0 52.0 52.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#9] json-stringify-tinderbox Cycles:10 Average:39.30 Median:39.00 stddev:2.41 (6.2%) stddev-sans-first:2.51 18:29:55 INFO - PID 12364 | Values: 38.0 39.0 39.0 38.0 38.0 39.0 39.0 39.0 38.0 46.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:74.00 stddev:2.07 (2.8%) stddev-sans-first:2.12 18:29:55 INFO - PID 12364 | Values: 73.0 73.0 74.0 72.0 78.0 77.0 73.0 77.0 74.0 74.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#11] stanford-crypto-ccm Cycles:10 Average:115.10 Median:117.00 stddev:4.58 (3.9%) stddev-sans-first:4.61 18:29:55 INFO - PID 12364 | Values: 111.0 109.0 109.0 121.0 117.0 119.0 121.0 117.0 113.0 114.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.40 Median:169.50 stddev:3.72 (2.2%) stddev-sans-first:3.71 18:29:55 INFO - PID 12364 | Values: 173.0 176.0 168.0 166.0 164.0 168.0 167.0 173.0 171.0 168.0 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:3.92 (5.9%) stddev-sans-first:4.16 18:29:55 INFO - PID 12364 | Values: 67.0 74.0 67.0 73.0 66.0 64.0 66.0 62.0 71.0 65.0 18:29:55 INFO - PID 12364 | -------- Summary: end -------- 18:29:55 INFO - PID 12364 | 18:29:55 INFO - PID 12364 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:29:56 INFO - TEST-INFO | 12364: exit 0 18:29:56 INFO - TEST-OK | kraken | took 61448ms 18:29:56 INFO - SUITE-END | took 1171s 18:29:56 INFO - Completed test suite (00:19:31) 18:29:56 INFO - javascript benchmark 18:29:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8401.742947746217, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3088.351446196299, 3131.577237072995, 3137.2470607054283, 3144.2453811085347, 3197.600299962505, 6068.39316068393, 6187.597806142598, 6196.876062478754, 6231.439170474657, 6298.937010629898, 6158.96920515398, 6206.813795586139, 6208.286047104584, 6231.3145553989025, 6242.594231374957, 5752.36723960364, 5805.5936084474, 5808.12878068289, 5829.679367634779, 5855.268091488564, 1292.9165969022297, 5609.298837645294, 5775.48020678141, 5862.179294898718, 5875.676837773921, 5646.548276137899, 5676.347220069703, 5706.629069110494, 5718.685472299022, 5771.192033115367, 5710.628809127394, 5716.1425786131995, 5747.540196784247, 5778.0466223073145, 5796.304443466769, 5614.101743721026, 5655.095184770458, 5687.601867869251, 5735.139729040636, 5736.311642602873, 6075.969620151871, 6156.322804491502, 6251.12484252205, 6278.277998030239, 6285.937140628581, 6334.3032266450655, 6491.415772580443, 6592.406683398517, 6632.949917199985, 6691.498137639648, 6595.6372399518, 6610.768623098168, 6627.701753421107, 6628.304028077031, 6631.469482441393, 6016.398360163948, 6064.090386442025, 6129.724162412702, 6182.381761823872, 6187.628742275433, 6845.5550389224545, 6853.691583878737, 6908.723651054002, 6944.618046007518, 6949.3745562899585, 6310.895593271159, 6519.087327774116, 6525.1517302750335, 6573.638449885302, 6605.405513503808, 6547.148870646785, 6552.9344706553275, 6556.213254409501, 6567.934320656828, 6613.503987200979, 6565.0, 6618.30507796684, 6664.933350666528, 6682.264950855402, 6688.598684078874, 6622.37087476689, 6692.364225398676, 6735.326467353323, 6735.663216839139, 6737.326267373223, 5188.195829646411, 5204.141316682781, 5268.947310526923, 5279.0, 5284.920726189111, 4433.357163211316, 4528.660350473728, 4547.931781023287, 4556.612687921497, 4592.747398893026, 5643.209950606918, 5654.293213348332, 5655.208270842086, 5666.943330566724, 5667.0, 6540.37866402691, 6555.278919318871, 6568.573042752206, 6657.201135863727, 6684.2647308795995, 5489.313835770528, 5577.721113944286, 5649.745761440663, 5652.63257888236, 5659.688717120515, 5621.578381621394, 5648.068068768689, 5682.488576028178, 5690.430956904276, 5691.089425691869, 6255.874882502325, 6353.078803573456, 6386.393292637192, 6416.486681065504, 6428.967855160694, 6634.402903738686, 6703.22912865017, 6728.495362847708, 6733.293004234582, 6739.494537909578, 6363.96818015907, 6369.45859601939, 6401.64790936503, 6449.7420103195345, 6464.806055818333, 6308.905366419599, 6425.58233714807, 6513.837154071186, 6606.273309935807, 6616.867662646817, 6639.568428052161, 6673.132492775908, 6682.231543372574, 6688.531802773759, 6698.966505167443, 6450.741970321134, 6459.773907913201, 6487.935120648733, 6504.674766261763, 6522.15212022434, 6333.683315834282, 6424.453921416545, 6506.804795856132, 6515.28331883502, 6529.477641788741, 6224.968875155595, 6348.174737284124, 6350.142730731292, 6444.1622589065, 6450.161479007699, 6475.708593113415, 6514.6416947068365, 6520.706568204348, 6534.379233972765, 6577.901331479936, 6190.133381326528, 6300.432961033529, 6338.841528961812, 6363.713632886624, 6390.808275751735], "value": 6011.447878999309, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [841.5329492131864, 887.0730087059022, 906.8186362727452, 907.2832462354736, 908.7410088124884, 39675.2064958701, 40840.387394189114, 40857.59142408575, 40895.38656920149, 40914.18171636569, 38502.22995540088, 41498.377524337084, 42194.7890260549, 42490.57509424905, 42682.7865860671, 7962.880556791652, 8098.838023239532, 8101.878471822898, 8102.675892964275, 8106.189381061906, 7934.404919631022, 8038.678452861878, 8079.798005049892, 8132.552709600969, 8137.633806478693, 8121.147279535652, 8152.592370381487, 8152.592370381487, 8160.877586836202, 8171.10117887032, 8079.555624440653, 8125.959370203141, 8127.674893004273, 8128.837423251502, 8131.227533384319, 7887.211278872124, 7915.722949696733, 7957.045154581488, 7962.3630109591095, 7963.243491868321, 37816.62183378186, 37836.43245351322, 38088.80955595232, 38115.0, 38131.23737525262, 3534.6465353464705, 3564.8574057037686, 3565.340412023758, 3568.8750893718857, 3575.1777091268896, 31187.064388068393, 31288.687113128806, 31345.529817052764, 31389.0, 31417.8429107853, 4785.808567657289, 4951.306817045616, 4972.651914365996, 5007.824726134604, 5127.128388174019, 190119.0, 190930.0, 191040.0, 191100.0, 191130.0, 47101.29348059782, 47603.5239647606, 47677.76161119172, 47778.522214777404, 47786.28320575194, 53343.0, 53916.46083539193, 53974.73012634912, 54466.72766636142, 54482.455175447736, 15437.228138593026, 15811.36754529829, 15820.446284379992, 15856.44502442432, 15947.122908239926, 12893.161944473579, 12941.0, 12941.482340706267, 12951.287679177736, 12954.481820727065, 13563.186208827501, 13654.795178072336, 13746.793798093036, 13800.378982945791, 13963.79054314186, 5442.537384322377, 5623.634463759842, 5629.54963602919, 5656.547476201894, 5684.54523638108, 998.7702828349375, 1005.9144972677258, 1021.7343490692474, 1030.4899074957943, 1038.2161468091542, 22964.0480559151, 23024.19415320489, 23071.769282306963, 23100.191493297654, 23156.073757049868, 1100.862392200975, 1102.079763397556, 1106.31961343773, 1109.9223054386277, 1115.1803424482998, 12090.153689241668, 12154.392280385946, 12232.75534489323, 12301.446434910451, 12326.691832704255, 230707.0, 231209.0, 231974.0, 232009.0, 232331.0, 43771.12457750827, 50964.74517627462, 51316.743416283425, 51459.228111578355, 52049.0, 1394.2610416479283, 1410.746065708162, 1418.4609848257596, 1419.4109444580383, 1431.3344294902713, 26917.461650767265, 27166.592501112103, 27250.182494525197, 27426.862865686344, 27795.722042779315, 36427.45358819567, 36480.45279320866, 36549.26901461903, 36552.81723591365, 36565.0, 194007.0, 194507.0, 195840.0, 196694.0, 197679.0, 32712.50931236081, 33476.49785253272, 33711.83144084264, 33727.66272337245, 33733.83133084319, 43816.78091609649, 44003.77998109989, 44137.55862441463, 44284.11431771411, 44350.55649443464, 31728.20679482939, 31927.0, 32070.19824504406, 32210.03369898813, 32265.0, 29244.976425824992, 29401.705982939897, 30087.69912300849, 30135.849320753256, 30440.238994025327, 34105.48841767326, 35122.64877351194, 35209.647903520636, 35335.46996794999, 35511.644883550834, 30571.54142687817, 30850.38299233958, 31114.22214444657, 31147.377052459276, 31372.529412058382, 22879.542409152058, 22949.655755164018, 22979.425514361606, 23407.297781066594, 23436.296911092693], "value": 17201.387905494947, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.2102587915736, 580.3963877567328, 585.7334912614759, 586.4633860018083, 611.4160976267665, 22193.22323718668, 22980.425489362773, 22985.08059677615, 22994.965226564804, 23024.19415320464, 20755.792442075573, 21212.787872121313, 24014.15950441735, 24139.63790543143, 24343.75656243437, 6441.581297215689, 6540.313267106957, 6582.17722784652, 6601.0, 6630.071789949411, 6184.752609895599, 6438.645874476902, 6454.9677251613675, 6479.578827376229, 6569.638669873155, 6410.294867564564, 6414.550981431302, 6427.614343139403, 6427.646479443652, 6429.164208652892, 6304.401081897213, 6402.583832050902, 6431.614103153825, 6456.354364563553, 6468.191476065492, 6116.174316467264, 6132.325444201134, 6132.448079672851, 6153.0, 6162.4453799157845, 28059.71940280612, 28408.0, 28508.429831403464, 28535.57196642052, 28557.57163642547, 2070.9482262943397, 2097.339338108506, 2106.536561956367, 2107.9578408431744, 2110.788921107877, 18979.0, 19012.239510419706, 19319.0, 19477.707834382494, 19642.90178549098, 2351.729551101628, 2382.725986511556, 2401.4236583220077, 2413.0, 2442.3894026493376, 117042.0, 122537.38731306286, 125472.0, 136617.95073073913, 141735.29132354376, 14388.640283992774, 14538.491152809602, 14544.345504452325, 14637.707245855023, 14653.194074326015, 22569.435764106027, 22575.661365079537, 22616.434589135402, 22647.886760566093, 22653.43366415853, 9824.607015719434, 9981.80036399268, 10019.949900250598, 10035.448050357301, 10046.949765251273, 9156.267498600097, 9196.678116266032, 9213.539323033821, 9303.76740581491, 9303.76740581491, 8717.0, 8755.255803256794, 8915.598798054103, 8916.55417229136, 9028.458292502472, 2783.5268004439295, 2797.8461184634634, 2808.4523517913813, 2810.4519618674162, 2877.5395936650034, 959.3092973059386, 971.32007594684, 973.8490533967247, 987.6247026130025, 987.9901200988041, 18310.267589296545, 18351.357702480615, 18572.978486183118, 18722.0, 18915.810841891405, 1027.9691609251734, 1028.3264461777549, 1036.8185567525652, 1036.9585216591402, 1045.9320144190604, 9953.601855925826, 10095.848562271573, 10388.532516036645, 10422.5830966762, 10543.630972915911, 453796.0, 467852.0, 468929.0, 475191.0, 475393.0, 105759.0, 108333.0, 109559.45220273848, 109670.0, 110623.44688276507, 1130.5308297056629, 1154.8787377325427, 1208.8005479096028, 1238.6407941696987, 1239.6528971887885, 19988.300409485597, 20008.0, 20022.69965950483, 20212.78723276608, 20248.291309804084, 59610.0, 59996.0, 60067.0, 61078.69460652668, 62378.68810655917, 75816.62091689506, 76378.61810690911, 76499.61750191436, 76763.0, 77032.0, 13766.0, 14241.715165696834, 14246.643833904236, 14261.857381426053, 14275.857241427453, 21659.133634654438, 21779.0, 21779.891100544395, 22063.11747530145, 22105.66841497347, 17360.131993400028, 17608.91195544014, 17728.0, 17821.287148513915, 17834.37579684705, 15539.611509711895, 15645.139517326656, 15744.29150688199, 15783.368665253261, 15895.364185432452, 18153.909230453763, 18309.54226144357, 18328.541786455444, 18388.17253223635, 18477.260909563465, 13482.93258533701, 14049.578512644637, 14143.0, 14182.00725949215, 14301.92849035748, 16166.595835104217, 16194.919025404797, 16206.432774852823, 16372.181390930644, 16407.261673224493], "value": 11765.055684535679, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.5388613985106, 1383.1148065238249, 1388.007574584172, 1390.026981113221, 1392.5265409760686, 14470.204138772377, 15134.697306053873, 15169.92415037926, 15173.241337933114, 15178.089314641129, 14751.18868462234, 14912.701745965102, 14918.104913705185, 14946.327415266313, 14982.475613353536, 2636.670416197975, 2747.3406382468165, 2752.545829938058, 2753.8347699138117, 2754.5454999924987, 2715.2261605442454, 2769.515334816409, 2789.7628701560393, 2790.065328115084, 2795.692473827877, 2617.0840205927866, 2752.1330780803983, 2765.281026933002, 2766.5573508238685, 2768.0588599876037, 2801.921260314773, 2803.957940630892, 2810.747032767061, 2828.7878409119294, 2832.7450529452244, 2634.249238966885, 2709.024751089606, 2732.7130651281727, 2733.876975536106, 2740.287525243431, 14737.926310368379, 14861.256937153206, 14863.925680371636, 14880.0, 14889.0, 7735.535867847891, 7993.0, 7994.640241189102, 8001.1198768135455, 8004.0, 4834.613230941552, 4942.9011419771405, 5022.347094877679, 5201.765920533585, 5304.734763261821, 1680.7226807576735, 1693.669734401792, 1703.335699077361, 1740.5822602575417, 1744.3197153110298, 25346.619800703003, 25694.74305256942, 25700.357491062685, 25709.3572660685, 25764.871175644003, 7262.092238470192, 7282.599457029808, 7428.368588670023, 7487.475876688688, 7494.887576686354, 16908.32366705318, 17128.143592820557, 17140.828591714173, 17175.141242937803, 17188.65622687539, 7703.6918523259565, 7833.804154896059, 7835.255650713173, 7845.843083138306, 7862.213778622092, 7134.892976605355, 7155.248698886646, 7231.63841807918, 7267.982482452461, 7456.888146677804, 5045.571126454292, 5184.37787465499, 5195.2986346843445, 5208.348956380452, 5242.7116508591635, 1554.1762865681208, 1557.9454719085004, 1560.1029408090394, 1562.414094714482, 1575.3304845440641, 874.7026011156238, 908.7410088124852, 915.4736026784625, 922.9446233226028, 932.4684929590069, 7204.92795072053, 7471.663775130132, 7548.0, 7608.657610407545, 7651.234876512304, 712.2307907459931, 725.1624373848214, 744.7877354953812, 751.6579956119953, 752.3379426105101, 5152.896942061213, 5160.638755287094, 5185.429602743695, 5193.922091168635, 5201.921971170435, 20753.0, 21343.573128537344, 21425.142994280053, 21507.354779356643, 21572.784272157078, 19994.70007949882, 21172.364829055154, 21697.56604867894, 21701.67447488289, 21715.674264886038, 10989.945050274697, 11036.227464077438, 11081.445927703582, 11130.888691113147, 11183.329000259848, 8086.474379165335, 8127.674893004214, 8164.918350816653, 8257.917420825956, 8272.296854767412, 17420.390286339916, 17540.298388064333, 17961.461156165336, 18070.09649517545, 18509.72235416443, 18926.242950281834, 19012.334568290044, 19044.0, 19360.322388716326, 19552.31566895152, 10940.781184376108, 11073.446327683745, 11163.051140653135, 11206.943965280121, 11213.327200368005, 13723.313834308045, 13822.516211932534, 13851.30743462843, 13889.791653125414, 13911.234882081179, 11283.379414131974, 11297.209195356247, 11480.598179063692, 11588.420578971187, 11593.478293476983, 10942.507587158438, 10991.395473249047, 11023.944880275547, 11151.163662725166, 11206.047485963785, 10020.549075291776, 10133.13868321201, 10183.796324073624, 10190.694279171637, 10193.133583645176, 7049.400800931979, 7205.0, 7216.7113315466795, 7227.8554428912175, 7241.601711905896, 6739.258681544928, 6968.407685346802, 6970.546914450544, 7073.434125270069, 7135.82160445993], "value": 6569.545638342583, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.8008206676293, 508.94401615822267, 523.3432042786303, 533.8512487238863, 535.3147970597634, 6080.939190608093, 6124.632522048681, 6143.283313595278, 6201.689915504229, 6265.18552588164, 6061.757529698818, 6074.331823499422, 6087.421694938985, 6119.0209566469375, 6146.508279337653, 5120.820771273006, 5150.845474635767, 5154.561862241714, 5164.431912489623, 5165.612579056567, 4786.329913812069, 4941.160002799515, 4947.158982972904, 5051.863330750589, 5067.555746612216, 4886.047220791947, 4896.485868983759, 4900.803967841282, 4952.702837829724, 4958.652894297401, 4905.166121759309, 4937.012597480519, 4948.554630083309, 4969.627277954131, 4972.229304457815, 4354.521002689701, 4447.466304043503, 4460.33095035744, 4471.703206070236, 4477.126960242754, 5956.910646340309, 6012.30858451275, 6039.547033972426, 6056.727447264883, 6062.151298818169, 5661.7735290588325, 5673.631213971079, 5705.372409035002, 5711.314642242957, 5712.200291959129, 6432.903506447407, 6474.384933431364, 6487.594013143091, 6492.480601551864, 6514.706838192245, 4606.170889239936, 4869.780859861315, 4882.3408839806525, 4896.338994235768, 4912.926306105412, 6616.172978377703, 6680.799576012728, 6710.563813352116, 6772.932270677279, 6818.693158807866, 4949.851504454872, 4951.8266860659705, 4973.975130124326, 4988.0, 5031.320771695848, 5035.118854200574, 5065.518775716375, 5091.949080509222, 5093.134167191586, 5111.897762044739, 5605.607607467438, 5611.7755289788765, 5656.490915817596, 5704.600677952503, 5708.257926469532, 6607.174103237096, 6611.603303801691, 6612.702428390734, 6617.272100068989, 6644.876907853301, 1737.9391721289694, 1743.755874177616, 1760.4806582058322, 1762.4184019273866, 1778.208697129765, 1156.2079975217002, 1205.2949024820402, 1219.1587804414926, 1238.4303220518482, 1238.7832129377323, 5587.8824235152315, 5616.606837521416, 5620.91568626471, 5629.677025898911, 5658.660480371191, 6462.967685161544, 6480.546361754632, 6522.5760325578685, 6554.967225163844, 6609.0, 5554.611177217636, 5598.836657288619, 5618.185363122325, 5642.871425714873, 5651.406602306781, 3482.564679415073, 3543.450765131409, 3545.1314427965312, 3545.94681079784, 3550.183457804668, 6353.807936261982, 6357.936420635827, 6416.871662566722, 6429.22849258092, 6457.418832305115, 6701.664916754143, 6765.627890466071, 6777.3900348967845, 6811.148606424197, 6822.351876571718, 6056.515478761688, 6223.253209614875, 6378.362163783678, 6402.295747467868, 6411.4229719325485, 6482.416582507597, 6490.643014634241, 6526.67366631657, 6574.8356291093105, 6582.4986505127745, 6430.64631445275, 6461.644609546332, 6474.028895665688, 6481.222253329442, 6506.772262970773, 6179.342882560542, 6248.625082495065, 6267.467265282502, 6293.748250069946, 6299.748010079728, 4945.480724524017, 5010.824621138243, 5016.720736212243, 5026.824061157842, 5039.974800125976, 6334.334894835976, 6506.707198175999, 6565.967170164118, 6635.8009259722285, 6663.600183988976, 6255.624662520263, 6313.90529142054, 6331.398517140864, 6386.521010924293, 6424.582402143846, 6388.233411990591, 6402.871942561216, 6472.805815825533, 6482.773102941374, 6483.2544257411, 6392.3927226913365, 6423.42189202974, 6440.355964403522, 6446.452051575481, 6451.935480645321, 6480.675966201766, 6500.837479063061, 6528.21661400635, 6539.934600654123, 6586.4401525868925, 6202.906956395567, 6222.564420490522, 6237.469815065589, 6295.98528470885, 6330.208723909512], "value": 4999.588553624792, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [891.0955380289004, 917.5962576466354, 932.8134373125376, 940.14916500567, 954.0697819625866, 19460.221591136356, 20250.59498810023, 20272.8986355068, 20372.490687732796, 20386.796132038693, 18202.0, 18605.720914186266, 18846.43460696181, 18898.055097245116, 19067.618647627038, 957.5930229652405, 982.3565564555192, 984.5815528400437, 987.8715766950322, 989.3124278626358, 959.4483172175992, 973.2311473935582, 974.6296407365191, 974.7124598243536, 975.7853272280087, 1042.405828677654, 1046.6546039806844, 1050.0969166516788, 1053.6470282455386, 1053.7576357437795, 1023.8822535408449, 1030.5671617920416, 1034.2915103154364, 1043.4209013997267, 1043.7390652336953, 711.6192836832322, 728.2535401213745, 734.4785202506174, 738.1032046064005, 738.3354980517523, 19355.4193374199, 19633.803661963484, 19703.01484925748, 19786.208551657917, 19819.207231710716, 21425.14299428021, 21548.0, 22148.114075436963, 22351.66472502914, 22413.767242801638, 16543.338266469327, 16879.324827006905, 16895.324187032504, 16999.57501062471, 17080.914595427068, 3778.9433158502643, 3793.0896584819443, 3804.885853424402, 3805.562360328542, 3808.028952617065, 137860.31069844688, 138332.0, 141167.29416352854, 141896.0, 144599.0, 16962.915185423994, 16977.405790797264, 17000.23498942526, 17088.829111708972, 17203.311867525408, 30503.542446863314, 31075.067747967598, 31859.36281274408, 31997.36005279928, 32001.19997000047, 8631.827363452696, 8689.956550217335, 8734.825303493895, 8743.256823170103, 8751.124887511198, 3648.343298206348, 4112.547619761824, 4160.022394737295, 4169.291220492523, 4194.559571245037, 5057.266696329012, 5142.6914385137015, 5155.74221288934, 5183.403908550489, 5186.0, 966.429806414219, 988.7083310423442, 999.645125980281, 1001.9298649094493, 1003.1322905686528, 624.8344188789883, 634.9841253968596, 640.4492136762381, 642.877853207889, 647.2233320015999, 11785.646430607096, 11808.291502509705, 12100.092493063055, 12174.878251217375, 12481.438335274934, 677.6713294052381, 689.4139981016096, 690.3062422265691, 691.4572060932144, 706.2090458686304, 5153.896922061613, 5180.637355385087, 5245.737713114329, 5320.627556071116, 5409.323834520685, 122073.38963305127, 126079.0, 128039.0, 129087.0, 129458.0, 104643.4767826156, 109459.45270273757, 109876.4506177464, 113625.43187284011, 114954.0, 89840.5507972456, 92262.53868730614, 92796.0, 94308.52845735864, 98600.01399986052, 9348.439093654402, 9380.390274632127, 9448.291378146803, 9563.713088607354, 9626.181774548962, 42939.35590966201, 43657.0, 43906.0, 44318.335224972296, 44937.32594011027, 53903.19145212747, 54104.72947635394, 55008.449915500336, 55370.72314638401, 55452.44547554473, 15761.211939403212, 16011.279492422636, 16033.518994430186, 16037.518874433787, 16192.757108643615, 16534.421295254608, 16690.666186675957, 16869.156542172597, 16877.746833797748, 16913.15434228259, 15470.613234669223, 15558.455454059054, 15832.92083539621, 16006.839931601, 16080.276387562822, 15168.696626067638, 15174.0, 15310.234488275319, 15517.379304828131, 15547.37810487613, 15046.0, 15236.31436585334, 15244.0, 15347.07917524952, 15382.230888455757, 10989.780204396027, 10999.725006874893, 11093.722656933642, 11177.217594768614, 11256.943715281372, 10754.193435492212, 10849.403282819521, 10856.0, 10945.616903408343, 11044.889551104707], "value": 8802.045549640607, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1426.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 108.0, 109.0, 108.0, 107.0, 108.0, 107.0, 107.0, 107.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 115.0, 119.0, 115.0, 118.0, 158.0, 118.0, 119.0, 115.0, 140.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 161.0, 162.0, 163.0, 163.0, 162.0, 162.0, 163.0, 167.0, 164.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 74.0, 74.0, 77.0, 80.0, 76.0, 74.0, 78.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 78.0, 80.0, 81.0, 79.0, 80.0, 79.0, 78.0, 78.0], "value": 79.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 113.0, 113.0, 115.0, 113.0, 114.0, 116.0, 115.0, 117.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 148.0, 147.0, 148.0, 147.0, 148.0, 147.0, 148.0, 149.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 52.0, 54.0, 55.0, 54.0, 52.0, 52.0, 52.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 39.0, 38.0, 38.0, 39.0, 39.0, 39.0, 38.0, 46.0], "value": 39.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 72.0, 78.0, 77.0, 73.0, 77.0, 74.0, 74.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 109.0, 109.0, 121.0, 117.0, 119.0, 121.0, 117.0, 113.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 176.0, 168.0, 166.0, 164.0, 168.0, 167.0, 173.0, 171.0, 168.0], "value": 169.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 67.0, 73.0, 66.0, 64.0, 66.0, 62.0, 71.0, 65.0], "value": 67.5, "unit": "score"}]}]} 18:29:56 INFO - Return code: 0 18:29:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:29:56 INFO - # TBPL SUCCESS # 18:29:56 INFO - Running post-action listener: _package_coverage_data 18:29:56 INFO - Running post-action listener: _resource_record_post_action 18:29:56 INFO - [mozharness: 2017-09-27 01:29:56.256336Z] Finished run-tests step (success) 18:29:56 INFO - Running post-run listener: _resource_record_post_run 18:29:56 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 20480; Write bytes: 402526208; Read time: 1172; Write time: 654088 18:29:56 INFO - TinderboxPrint: CPU usage
13.2% 18:29:56 INFO - TinderboxPrint: I/O read bytes / time
20,480 / 1,172 18:29:56 INFO - TinderboxPrint: I/O write bytes / time
402,526,208 / 654,088 18:29:56 INFO - TinderboxPrint: CPU idle
8,231.6 (86.8%) 18:29:56 INFO - TinderboxPrint: CPU user
1,193.6 (12.6%) 18:29:56 INFO - TinderboxPrint: Swap in / out
0 / 0 18:29:56 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 74956800; Read time: 396; Write time: 92492 18:29:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:29:56 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 12288; Write bytes: 327569408; Read time: 764; Write time: 561596 18:29:56 INFO - Running post-run listener: _upload_blobber_files 18:29:56 INFO - Blob upload gear active. 18:29:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:29:56 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 18:29:56 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'] 18:29:56 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 18:29:57 INFO - (blobuploader) - INFO - Open directory for files ... 18:29:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:29:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:29:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:58 INFO - (blobuploader) - INFO - Done attempting. 18:29:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:29:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:29:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:59 INFO - (blobuploader) - INFO - Done attempting. 18:29:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:29:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:30:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:30:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:30:06 INFO - (blobuploader) - INFO - Done attempting. 18:30:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:30:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:30:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:30:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:30:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:30:08 INFO - (blobuploader) - INFO - Done attempting. 18:30:08 INFO - (blobuploader) - INFO - Iteration through files over. 18:30:08 INFO - Return code: 0 18:30:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:30:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:30:08 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c761d36c6a8185a51065588601132031392970ca630b9ac37ef9a6cd090bf21fa461d59649c83e6968374f5d6d48d2ebd32f12a86eb5400cd629266185af97e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b0fafec88c8a07d502260eaeb72306d82136afb6db0486f2a2cda401ad686b0535777c0ea0254d3ade70f2e9478a40bed59491b84f7bcc90b95695d136b70c36", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1d36f467e18f6085b25006ad001bfdc4fb1f0c6b1e76a8e404948c23c3775e01027443d57326822adc57e09863cc705dc26358c847eb0edffa12009e9ec8154c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3fc1e96017d864b2d4e0a057d06cdb95b1025a4092a7f90b69693fde8e33688012913280e8902deb2d28833720bc14b40c950fb20013f625babdb14aa758e9c1"} 18:30:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:30:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:30:08 INFO - Contents: 18:30:08 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c761d36c6a8185a51065588601132031392970ca630b9ac37ef9a6cd090bf21fa461d59649c83e6968374f5d6d48d2ebd32f12a86eb5400cd629266185af97e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b0fafec88c8a07d502260eaeb72306d82136afb6db0486f2a2cda401ad686b0535777c0ea0254d3ade70f2e9478a40bed59491b84f7bcc90b95695d136b70c36", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1d36f467e18f6085b25006ad001bfdc4fb1f0c6b1e76a8e404948c23c3775e01027443d57326822adc57e09863cc705dc26358c847eb0edffa12009e9ec8154c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3fc1e96017d864b2d4e0a057d06cdb95b1025a4092a7f90b69693fde8e33688012913280e8902deb2d28833720bc14b40c950fb20013f625babdb14aa758e9c1"} 18:30:08 INFO - Running post-run listener: copy_logs_to_upload_dir 18:30:08 INFO - Copying logs to upload dir... 18:30:08 INFO - mkdir: /builds/slave/test/build/upload/logs 18:30:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.687859 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-09-26 18:30:08.103453) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 18:30:08.108093) ========= 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=836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c761d36c6a8185a51065588601132031392970ca630b9ac37ef9a6cd090bf21fa461d59649c83e6968374f5d6d48d2ebd32f12a86eb5400cd629266185af97e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b0fafec88c8a07d502260eaeb72306d82136afb6db0486f2a2cda401ad686b0535777c0ea0254d3ade70f2e9478a40bed59491b84f7bcc90b95695d136b70c36", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1d36f467e18f6085b25006ad001bfdc4fb1f0c6b1e76a8e404948c23c3775e01027443d57326822adc57e09863cc705dc26358c847eb0edffa12009e9ec8154c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3fc1e96017d864b2d4e0a057d06cdb95b1025a4092a7f90b69693fde8e33688012913280e8902deb2d28833720bc14b40c950fb20013f625babdb14aa758e9c1"} build_url:https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c761d36c6a8185a51065588601132031392970ca630b9ac37ef9a6cd090bf21fa461d59649c83e6968374f5d6d48d2ebd32f12a86eb5400cd629266185af97e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b0fafec88c8a07d502260eaeb72306d82136afb6db0486f2a2cda401ad686b0535777c0ea0254d3ade70f2e9478a40bed59491b84f7bcc90b95695d136b70c36", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1d36f467e18f6085b25006ad001bfdc4fb1f0c6b1e76a8e404948c23c3775e01027443d57326822adc57e09863cc705dc26358c847eb0edffa12009e9ec8154c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3fc1e96017d864b2d4e0a057d06cdb95b1025a4092a7f90b69693fde8e33688012913280e8902deb2d28833720bc14b40c950fb20013f625babdb14aa758e9c1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 18:30:08.143783) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:30:08.144100) ========= 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=836cfdc75598d54cb747368e000001a9-1506473472.854231-934097031 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:30:08.226742) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 18:30:08.229812) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 18:30:08.230391) ========= ========= Total master_lag: 0.33 =========