builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-060 starttime: 1506474510.58 results: success (0) revision: 70158e4e215d784d1391db5e517b18727f4b3683 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:30.584250) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:30.584956) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:30.586015) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:30.750710) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:30.751206) ========= 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=337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-26 18:08:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2017-09-26 18:08:30 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119285 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:30.905128) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:30.905440) ========= 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=337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016646 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:30.955150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:30.955457) ========= 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=337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-26 18:08:31,016 truncating revision to first 12 chars 2017-09-26 18:08:31,016 Setting DEBUG logging. 2017-09-26 18:08:31,016 attempt 1/10 2017-09-26 18:08:31,016 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:08:31,683 unpacking tar archive at: mozilla-central-70158e4e215d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.965124 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:31.938134) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:31.938468) ========= script_repo_revision: 70158e4e215d784d1391db5e517b18727f4b3683 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:31.938873) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:31.939138) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 18:08:31.952856) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-26 18:08:31.953232) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:08:32 INFO - MultiFileLogger online at 20170926 18:08:32 in /builds/slave/test 18:08:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:08:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:08:32 INFO - {'append_to_log': False, 18:08:32 INFO - 'base_work_dir': '/builds/slave/test', 18:08:32 INFO - 'blob_upload_branch': 'Firefox', 18:08:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:08:32 INFO - 'branch': 'Firefox', 18:08:32 INFO - 'buildbot_json_path': 'buildprops.json', 18:08:32 INFO - 'code_coverage': False, 18:08:32 INFO - 'config_files': ('talos/linux_config.py',), 18:08:32 INFO - 'default_actions': ('clobber', 18:08:32 INFO - 'read-buildbot-config', 18:08:32 INFO - 'download-and-extract', 18:08:32 INFO - 'populate-webroot', 18:08:32 INFO - 'create-virtualenv', 18:08:32 INFO - 'install', 18:08:32 INFO - 'setup-mitmproxy', 18:08:32 INFO - 'run-tests'), 18:08:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:08:32 INFO - 'disable_ccov_upload': False, 18:08:32 INFO - 'disable_stylo': False, 18:08:32 INFO - 'download_minidump_stackwalk': True, 18:08:32 INFO - 'download_symbols': 'ondemand', 18:08:32 INFO - 'enable_stylo': False, 18:08:32 INFO - 'enable_webrender': False, 18:08:32 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:08:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:08:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:08:32 INFO - 'gecko_profile': False, 18:08:32 INFO - 'gecko_profile_interval': 0, 18:08:32 INFO - 'installer_path': 'installer.exe', 18:08:32 INFO - 'log_level': 'info', 18:08:32 INFO - 'log_name': 'talos', 18:08:32 INFO - 'log_to_console': True, 18:08:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:08:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:08:32 INFO - 'opt_config_files': (), 18:08:32 INFO - 'pip_index': False, 18:08:32 INFO - 'suite': 'dromaeojs-e10s', 18:08:32 INFO - 'system_bits': '32', 18:08:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:08:32 INFO - 'title': 'talos-linux64-ix-060', 18:08:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:08:32 INFO - 'use_talos_json': True, 18:08:32 INFO - 'verify': 'False', 18:08:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:08:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:08:32 INFO - 'work_dir': 'build'} 18:08:32 INFO - [mozharness: 2017-09-27 01:08:32.157618Z] Running clobber step. 18:08:32 INFO - Running pre-action listener: _resource_record_pre_action 18:08:32 INFO - Running main action method: clobber 18:08:32 INFO - rmtree: /builds/slave/test/build 18:08:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:08:33 INFO - Running post-action listener: _resource_record_post_action 18:08:33 INFO - [mozharness: 2017-09-27 01:08:33.149434Z] Finished clobber step (success) 18:08:33 INFO - [mozharness: 2017-09-27 01:08:33.149532Z] Running read-buildbot-config step. 18:08:33 INFO - Running pre-action listener: _resource_record_pre_action 18:08:33 INFO - Running main action method: read_buildbot_config 18:08:33 INFO - Using buildbot properties: 18:08:33 INFO - { 18:08:33 INFO - "project": "", 18:08:33 INFO - "product": "firefox", 18:08:33 INFO - "who": "kwierso@gmail.com", 18:08:33 INFO - "installer_path": "public/build/target.tar.bz2", 18:08:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 18:08:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:08:33 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 18:08:33 INFO - "stage_platform": "linux64-qr", 18:08:33 INFO - "basedir": "/builds/slave/test", 18:08:33 INFO - "buildnumber": 86, 18:08:33 INFO - "platform": "ubuntu64_hw_qr", 18:08:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:08:33 INFO - "slavebuilddir": "test", 18:08:33 INFO - "taskId": "D-InsOd-TwqoK_O_U-xQbQ", 18:08:33 INFO - "branch": "mozilla-central", 18:08:33 INFO - "script_repo_revision": "production", 18:08:33 INFO - "revision": "70158e4e215d784d1391db5e517b18727f4b3683", 18:08:33 INFO - "slavename": "talos-linux64-ix-060", 18:08:33 INFO - "repo_path": "mozilla-central" 18:08:33 INFO - } 18:08:33 INFO - Finding installer, test and symbols from parent task. 18:08:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D-InsOd-TwqoK_O_U-xQbQ'}, attempt #1 18:08:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D-InsOd-TwqoK_O_U-xQbQ'}, attempt #1 18:08:37 INFO - Task dependencies: XCg3p_MvQCykzqwmBsV7eA 18:08:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA'}, attempt #1 18:08:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2 18:08:37 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.test_packages.json 18:08:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.crashreporter-symbols.zip 18:08:37 INFO - Running post-action listener: _resource_record_post_action 18:08:37 INFO - [mozharness: 2017-09-27 01:08:37.485994Z] Finished read-buildbot-config step (success) 18:08:37 INFO - [mozharness: 2017-09-27 01:08:37.486214Z] Running download-and-extract step. 18:08:37 INFO - Running pre-action listener: _resource_record_pre_action 18:08:37 INFO - Running main action method: download_and_extract 18:08:37 INFO - mkdir: /builds/slave/test/build/tests 18:08:37 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:08:37 INFO - trying https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.test_packages.json 18:08:37 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:08:37 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:08:38 INFO - Downloaded 989 bytes. 18:08:38 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:08:38 INFO - Using the following test package requirements: 18:08:38 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:08:38 INFO - u'common': [u'target.common.tests.zip'], 18:08:38 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:08:38 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:08:38 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:08:38 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:08:38 INFO - u'mozbase': [u'target.common.tests.zip'], 18:08:38 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:08:38 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:08:38 INFO - u'web-platform': [u'target.common.tests.zip', 18:08:38 INFO - u'target.web-platform.tests.tar.gz'], 18:08:38 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:08:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:08:38 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:08:38 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:08:38 INFO - Fetch https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.common.tests.zip into memory 18:08:39 INFO - Content-Length response header: 54946143 18:08:39 INFO - Bytes received: 54946143 18:08:49 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:08:49 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:08:49 INFO - Fetch https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.talos.tests.zip into memory 18:08:50 INFO - Content-Length response header: 14513823 18:08:50 INFO - Bytes received: 14513823 18:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:51 INFO - trying https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2 18:08:51 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:08:51 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:08:54 INFO - Downloaded 61661854 bytes. 18:08:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2 18:08:54 INFO - mkdir: /builds/slave/test/properties 18:08:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:08:54 INFO - Writing to file /builds/slave/test/properties/build_url 18:08:54 INFO - Contents: 18:08:54 INFO - build_url:https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2 18:08:54 INFO - Running post-action listener: _resource_record_post_action 18:08:54 INFO - Running post-action listener: find_tests_for_verification 18:08:54 INFO - Running post-action listener: set_extra_try_arguments 18:08:54 INFO - [mozharness: 2017-09-27 01:08:54.423798Z] Finished download-and-extract step (success) 18:08:54 INFO - [mozharness: 2017-09-27 01:08:54.423952Z] Running populate-webroot step. 18:08:54 INFO - Running pre-action listener: _resource_record_pre_action 18:08:54 INFO - Running main action method: populate_webroot 18:08:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:08:54 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['tresize', 18:08:54 INFO - 'tcanvasmark']}, 18:08:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:08:54 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['dromaeo_css', 18:08:54 INFO - 'kraken']}, 18:08:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:08:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:54 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:08:54 INFO - 'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:08:54 INFO - 'tests': ['damp', 'tps']}, 18:08:54 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:08:54 INFO - 'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['damp', 'tps']}, 18:08:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:08:54 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['dromaeo_dom']}, 18:08:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:08:54 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['basic_compositor_video', 18:08:54 INFO - 'glvideo']}, 18:08:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:08:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:08:54 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:08:54 INFO - 'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['ts_paint_webext', 18:08:54 INFO - 'tp5o_webext']}, 18:08:54 INFO - 'other-e10s': {'tests': ['a11yr', 18:08:54 INFO - 'ts_paint', 18:08:54 INFO - 'tpaint', 18:08:54 INFO - 'sessionrestore', 18:08:54 INFO - 'sessionrestore_many_windows', 18:08:54 INFO - 'sessionrestore_no_auto_restore', 18:08:54 INFO - 'tabpaint']}, 18:08:54 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['a11yr', 18:08:54 INFO - 'ts_paint', 18:08:54 INFO - 'tpaint', 18:08:54 INFO - 'sessionrestore', 18:08:54 INFO - 'sessionrestore_many_windows', 18:08:54 INFO - 'sessionrestore_no_auto_restore', 18:08:54 INFO - 'tabpaint']}, 18:08:54 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 18:08:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 18:08:54 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['perf_reftest_singletons']}, 18:08:54 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['perf_reftest']}, 18:08:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:08:54 INFO - 'tsvgr_opacity', 18:08:54 INFO - 'tart', 18:08:54 INFO - 'tscrollx', 18:08:54 INFO - 'tsvg_static']}, 18:08:54 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['tsvgx', 18:08:54 INFO - 'tsvgr_opacity', 18:08:54 INFO - 'tart', 18:08:54 INFO - 'tscrollx', 18:08:54 INFO - 'tsvg_static']}, 18:08:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:08:54 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:08:54 INFO - 'talos_options': ['--disable-stylo'], 18:08:54 INFO - 'tests': ['tp5o']}, 18:08:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:08:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:08:54 INFO - 'talos_options': ['--mitmproxy', 18:08:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:08:54 INFO - '--firstNonBlankPaint'], 18:08:54 INFO - 'tests': ['tp6_google', 18:08:54 INFO - 'tp6_youtube', 18:08:54 INFO - 'tp6_amazon', 18:08:54 INFO - 'tp6_facebook']}, 18:08:54 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:08:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:08:54 INFO - 'talos_options': ['--disable-stylo', 18:08:54 INFO - '--mitmproxy', 18:08:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:08:54 INFO - '--firstNonBlankPaint'], 18:08:54 INFO - 'tests': ['tp6_google', 18:08:54 INFO - 'tp6_youtube', 18:08:54 INFO - 'tp6_amazon', 18:08:54 INFO - 'tp6_facebook']}, 18:08:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:08:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 18:08:54 INFO - 'talos_options': ['--stylo-threads=1', 18:08:54 INFO - '--mitmproxy', 18:08:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:08:54 INFO - '--firstNonBlankPaint'], 18:08:54 INFO - 'tests': ['tp6_google', 18:08:54 INFO - 'tp6_youtube', 18:08:54 INFO - 'tp6_amazon', 18:08:54 INFO - 'tp6_facebook']}, 18:08:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:08:54 INFO - 'talos_options': ['--xperf_path', 18:08:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:54 INFO - 'tests': ['tp5n']}, 18:08:54 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:08:54 INFO - 'talos_options': ['--disable-stylo', 18:08:54 INFO - '--xperf_path', 18:08:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:54 INFO - 'tests': ['tp5n']}}} 18:08:54 INFO - Running post-action listener: _resource_record_post_action 18:08:54 INFO - [mozharness: 2017-09-27 01:08:54.439923Z] Finished populate-webroot step (success) 18:08:54 INFO - [mozharness: 2017-09-27 01:08:54.440013Z] Running create-virtualenv step. 18:08:54 INFO - Running pre-action listener: _resource_record_pre_action 18:08:54 INFO - Running main action method: create_virtualenv 18:08:54 INFO - Creating virtualenv /builds/slave/test/build/venv 18:08:54 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:08:54 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:08:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:08:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:08:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:08:55 INFO - Installing distribute.............................................................................................................................................................................................done. 18:08:55 INFO - Installing pip...............done. 18:08:55 INFO - Return code: 0 18:08:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:08:55 INFO - Reading from file tmpfile_stdout 18:08:55 INFO - Output received: 18:08:55 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:08:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:08: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:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08: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:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2842b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4d60>, '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 0x29e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5ef0>, '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': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', '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:08: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', 'pip>=1.5'] in /builds/slave/test/build 18:08: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 pip>=1.5 18:08:55 INFO - Using env: {'DISPLAY': ':0', 18:08:55 INFO - 'HOME': '/home/cltbld', 18:08:55 INFO - 'LANG': 'en_US.UTF-8', 18:08:55 INFO - 'LANGUAGE': 'en_US:en', 18:08:55 INFO - 'LOGNAME': 'cltbld', 18:08:55 INFO - 'MAIL': '/var/mail/cltbld', 18:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:55 INFO - 'MOZ_NO_REMOTE': '1', 18:08:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:55 INFO - 'NO_EM_RESTART': '1', 18:08:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:55 INFO - 'PWD': '/builds/slave/test', 18:08:55 INFO - 'SHELL': '/bin/bash', 18:08:55 INFO - 'SHLVL': '1', 18:08:55 INFO - 'TERM': 'linux', 18:08:55 INFO - 'TMOUT': '86400', 18:08:55 INFO - 'USER': 'cltbld', 18:08:55 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:55 INFO - '_': '/tools/buildbot/bin/python'} 18:08:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:08:56 INFO - Downloading/unpacking pip>=1.5 18:08:56 INFO - Running setup.py egg_info for package pip 18:08:56 INFO - warning: no files found matching 'pip/cacert.pem' 18:08:56 INFO - warning: no files found matching '*.html' under directory 'docs' 18:08:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:08:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:08:56 INFO - Installing collected packages: pip 18:08:56 INFO - Found existing installation: pip 1.1 18:08:56 INFO - Uninstalling pip: 18:08:56 INFO - Successfully uninstalled pip 18:08:56 INFO - Running setup.py install for pip 18:08:56 INFO - warning: no files found matching 'pip/cacert.pem' 18:08:56 INFO - warning: no files found matching '*.html' under directory 'docs' 18:08:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:08:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:08:56 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:08:56 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:08:56 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:08:56 INFO - Successfully installed pip 18:08:56 INFO - Cleaning up... 18:08:56 INFO - Return code: 0 18:08:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x267a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2842b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4d60>, '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 0x29e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5ef0>, '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': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', '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:08:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:08:56 INFO - Using env: {'DISPLAY': ':0', 18:08:56 INFO - 'HOME': '/home/cltbld', 18:08:56 INFO - 'LANG': 'en_US.UTF-8', 18:08:56 INFO - 'LANGUAGE': 'en_US:en', 18:08:56 INFO - 'LOGNAME': 'cltbld', 18:08:56 INFO - 'MAIL': '/var/mail/cltbld', 18:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:56 INFO - 'MOZ_NO_REMOTE': '1', 18:08:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:56 INFO - 'NO_EM_RESTART': '1', 18:08:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:56 INFO - 'PWD': '/builds/slave/test', 18:08:56 INFO - 'SHELL': '/bin/bash', 18:08:56 INFO - 'SHLVL': '1', 18:08:56 INFO - 'TERM': 'linux', 18:08:56 INFO - 'TMOUT': '86400', 18:08:56 INFO - 'USER': 'cltbld', 18:08:56 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:56 INFO - '_': '/tools/buildbot/bin/python'} 18:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:56 INFO - Downloading/unpacking psutil>=3.1.1 18:08: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:08: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:08: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:08: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:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:08:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:08:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:08:58 INFO - Installing collected packages: psutil 18:08:58 INFO - Running setup.py install for psutil 18:08:58 INFO - building 'psutil._psutil_linux' extension 18:08:58 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:08:58 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:08:58 INFO - building 'psutil._psutil_posix' extension 18:08:58 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:08:58 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:08:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:08:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:08:58 INFO - Successfully installed psutil 18:08:58 INFO - Cleaning up... 18:08:58 INFO - Return code: 0 18:08:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x267a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2842b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4d60>, '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 0x29e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5ef0>, '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': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', '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:08:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:08:58 INFO - Using env: {'DISPLAY': ':0', 18:08:58 INFO - 'HOME': '/home/cltbld', 18:08:58 INFO - 'LANG': 'en_US.UTF-8', 18:08:58 INFO - 'LANGUAGE': 'en_US:en', 18:08:58 INFO - 'LOGNAME': 'cltbld', 18:08:58 INFO - 'MAIL': '/var/mail/cltbld', 18:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:58 INFO - 'MOZ_NO_REMOTE': '1', 18:08:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:58 INFO - 'NO_EM_RESTART': '1', 18:08:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:58 INFO - 'PWD': '/builds/slave/test', 18:08:58 INFO - 'SHELL': '/bin/bash', 18:08:58 INFO - 'SHLVL': '1', 18:08:58 INFO - 'TERM': 'linux', 18:08:58 INFO - 'TMOUT': '86400', 18:08:58 INFO - 'USER': 'cltbld', 18:08:58 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:58 INFO - '_': '/tools/buildbot/bin/python'} 18:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:08:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:09:00 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:00 INFO - Installing collected packages: mozsystemmonitor 18:09:00 INFO - Running setup.py install for mozsystemmonitor 18:09:00 INFO - Successfully installed mozsystemmonitor 18:09:00 INFO - Cleaning up... 18:09:01 INFO - Return code: 0 18:09:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:01 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 0x267a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2842b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4d60>, '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 0x29e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5ef0>, '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': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', '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:01 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:01 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:01 INFO - Using env: {'DISPLAY': ':0', 18:09:01 INFO - 'HOME': '/home/cltbld', 18:09:01 INFO - 'LANG': 'en_US.UTF-8', 18:09:01 INFO - 'LANGUAGE': 'en_US:en', 18:09:01 INFO - 'LOGNAME': 'cltbld', 18:09:01 INFO - 'MAIL': '/var/mail/cltbld', 18:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:01 INFO - 'MOZ_NO_REMOTE': '1', 18:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:01 INFO - 'NO_EM_RESTART': '1', 18:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:01 INFO - 'PWD': '/builds/slave/test', 18:09:01 INFO - 'SHELL': '/bin/bash', 18:09:01 INFO - 'SHLVL': '1', 18:09:01 INFO - 'TERM': 'linux', 18:09:01 INFO - 'TMOUT': '86400', 18:09:01 INFO - 'USER': 'cltbld', 18:09:01 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:01 INFO - '_': '/tools/buildbot/bin/python'} 18:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:01 INFO - Downloading/unpacking jsonschema==2.5.1 18:09:01 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:01 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:01 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:01 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:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:09:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:03 INFO - Downloading functools32-3.2.3-2.tar.gz 18:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:09:03 INFO - warning: no files found matching '*.txt' 18:09:03 INFO - no previously-included directories found matching 'build' 18:09:03 INFO - no previously-included directories found matching 'dist' 18:09:03 INFO - no previously-included directories found matching '.git*' 18:09:03 INFO - Installing collected packages: jsonschema, functools32 18:09:03 INFO - Running setup.py install for functools32 18:09:03 INFO - warning: no files found matching '*.txt' 18:09:03 INFO - no previously-included directories found matching 'build' 18:09:03 INFO - no previously-included directories found matching 'dist' 18:09:03 INFO - no previously-included directories found matching '.git*' 18:09:03 INFO - Successfully installed jsonschema functools32 18:09:03 INFO - Cleaning up... 18:09:03 INFO - Return code: 0 18:09:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:09: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:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09: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:09:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:03 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 0x267a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2842b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4d60>, '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 0x29e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5ef0>, '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': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', '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:03 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:03 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:03 INFO - Using env: {'DISPLAY': ':0', 18:09:03 INFO - 'HOME': '/home/cltbld', 18:09:03 INFO - 'LANG': 'en_US.UTF-8', 18:09:03 INFO - 'LANGUAGE': 'en_US:en', 18:09:03 INFO - 'LOGNAME': 'cltbld', 18:09:03 INFO - 'MAIL': '/var/mail/cltbld', 18:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:03 INFO - 'MOZ_NO_REMOTE': '1', 18:09:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:03 INFO - 'NO_EM_RESTART': '1', 18:09:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:03 INFO - 'PWD': '/builds/slave/test', 18:09:03 INFO - 'SHELL': '/bin/bash', 18:09:03 INFO - 'SHLVL': '1', 18:09:03 INFO - 'TERM': 'linux', 18:09:03 INFO - 'TMOUT': '86400', 18:09:03 INFO - 'USER': 'cltbld', 18:09:03 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:03 INFO - '_': '/tools/buildbot/bin/python'} 18:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:09:03 INFO - Cleaning up... 18:09:03 INFO - Return code: 0 18:09:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:09: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:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09: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:09:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:03 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 0x267a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2842b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4d60>, '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 0x29e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5ef0>, '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': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', '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:03 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:03 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:03 INFO - Using env: {'DISPLAY': ':0', 18:09:03 INFO - 'HOME': '/home/cltbld', 18:09:03 INFO - 'LANG': 'en_US.UTF-8', 18:09:03 INFO - 'LANGUAGE': 'en_US:en', 18:09:03 INFO - 'LOGNAME': 'cltbld', 18:09:03 INFO - 'MAIL': '/var/mail/cltbld', 18:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:03 INFO - 'MOZ_NO_REMOTE': '1', 18:09:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:03 INFO - 'NO_EM_RESTART': '1', 18:09:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:03 INFO - 'PWD': '/builds/slave/test', 18:09:03 INFO - 'SHELL': '/bin/bash', 18:09:03 INFO - 'SHLVL': '1', 18:09:03 INFO - 'TERM': 'linux', 18:09:03 INFO - 'TMOUT': '86400', 18:09:03 INFO - 'USER': 'cltbld', 18:09:03 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:03 INFO - '_': '/tools/buildbot/bin/python'} 18:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:03 INFO - Downloading/unpacking blobuploader==1.2.4 18:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:04 INFO - Downloading blobuploader-1.2.4.tar.gz 18:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:09:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:09:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:05 INFO - Downloading docopt-0.6.1.tar.gz 18:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:09:05 INFO - Installing collected packages: blobuploader, requests, docopt 18:09:05 INFO - Running setup.py install for blobuploader 18:09:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:09:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:09:05 INFO - Running setup.py install for requests 18:09:06 INFO - Running setup.py install for docopt 18:09:06 INFO - Successfully installed blobuploader requests docopt 18:09:06 INFO - Cleaning up... 18:09:06 INFO - Return code: 0 18:09:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:09: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:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09: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:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:06 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 0x267a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2842b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4d60>, '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 0x29e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5ef0>, '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': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', '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:06 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:06 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:06 INFO - Using env: {'DISPLAY': ':0', 18:09:06 INFO - 'HOME': '/home/cltbld', 18:09:06 INFO - 'LANG': 'en_US.UTF-8', 18:09:06 INFO - 'LANGUAGE': 'en_US:en', 18:09:06 INFO - 'LOGNAME': 'cltbld', 18:09:06 INFO - 'MAIL': '/var/mail/cltbld', 18:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:06 INFO - 'MOZ_NO_REMOTE': '1', 18:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:06 INFO - 'NO_EM_RESTART': '1', 18:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:06 INFO - 'PWD': '/builds/slave/test', 18:09:06 INFO - 'SHELL': '/bin/bash', 18:09:06 INFO - 'SHLVL': '1', 18:09:06 INFO - 'TERM': 'linux', 18:09:06 INFO - 'TMOUT': '86400', 18:09:06 INFO - 'USER': 'cltbld', 18:09:06 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:06 INFO - '_': '/tools/buildbot/bin/python'} 18:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:09:06 INFO - Running setup.py (path:/tmp/pip-2nS1Px-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:09:06 INFO - Running setup.py (path:/tmp/pip-Emgqbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:09:06 INFO - Running setup.py (path:/tmp/pip-_W__ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:09:06 INFO - Running setup.py (path:/tmp/pip-sWe2lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:09:06 INFO - Running setup.py (path:/tmp/pip-p375Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:09:06 INFO - Running setup.py (path:/tmp/pip-rSE8a7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:09:06 INFO - Running setup.py (path:/tmp/pip-GZwZo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:09:06 INFO - Running setup.py (path:/tmp/pip-Tis4nM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:09:06 INFO - Running setup.py (path:/tmp/pip-Gx4lQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:09:07 INFO - Running setup.py (path:/tmp/pip-_hM5He-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:09:07 INFO - Running setup.py (path:/tmp/pip-xQXuPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:09:07 INFO - Running setup.py (path:/tmp/pip-n98c5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:09:07 INFO - Running setup.py (path:/tmp/pip-pIrrqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:09:07 INFO - Running setup.py (path:/tmp/pip-mY3vzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:09:07 INFO - Running setup.py (path:/tmp/pip-PFVCM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:09:07 INFO - Running setup.py (path:/tmp/pip-8sJQ9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:09:07 INFO - Running setup.py (path:/tmp/pip-TnAuur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:09:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:09:07 INFO - Running setup.py install for manifestparser 18:09:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:09:07 INFO - Running setup.py install for mozcrash 18:09:07 INFO - Running setup.py install for mozdebug 18:09:07 INFO - Running setup.py install for mozdevice 18:09:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:09:07 INFO - Running setup.py install for mozfile 18:09:08 INFO - Running setup.py install for mozhttpd 18:09:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:09:08 INFO - Running setup.py install for mozinfo 18:09:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:09:08 INFO - Running setup.py install for mozInstall 18:09:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:09:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:09:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:09:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:09:08 INFO - Running setup.py install for mozleak 18:09:08 INFO - Running setup.py install for mozlog 18:09:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:09:08 INFO - Running setup.py install for moznetwork 18:09:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:09:08 INFO - Running setup.py install for mozprocess 18:09:08 INFO - Running setup.py install for mozprofile 18:09:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:09:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:09:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:09:08 INFO - Running setup.py install for mozrunner 18:09:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:09:09 INFO - Running setup.py install for mozscreenshot 18:09:09 INFO - Running setup.py install for moztest 18:09:09 INFO - Running setup.py install for mozversion 18:09:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:09:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:09:09 INFO - Cleaning up... 18:09:09 INFO - Return code: 0 18:09:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:09 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 0x267a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2842b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4d60>, '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 0x29e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5ef0>, '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': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', '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:09 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:09:09 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:09:09 INFO - Using env: {'DISPLAY': ':0', 18:09:09 INFO - 'HOME': '/home/cltbld', 18:09:09 INFO - 'LANG': 'en_US.UTF-8', 18:09:09 INFO - 'LANGUAGE': 'en_US:en', 18:09:09 INFO - 'LOGNAME': 'cltbld', 18:09:09 INFO - 'MAIL': '/var/mail/cltbld', 18:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:09 INFO - 'MOZ_NO_REMOTE': '1', 18:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:09 INFO - 'NO_EM_RESTART': '1', 18:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:09 INFO - 'PWD': '/builds/slave/test', 18:09:09 INFO - 'SHELL': '/bin/bash', 18:09:09 INFO - 'SHLVL': '1', 18:09:09 INFO - 'TERM': 'linux', 18:09:09 INFO - 'TMOUT': '86400', 18:09:09 INFO - 'USER': 'cltbld', 18:09:09 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:09 INFO - '_': '/tools/buildbot/bin/python'} 18:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:09:09 INFO - Running setup.py (path:/tmp/pip-8EXIbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:09:09 INFO - Running setup.py (path:/tmp/pip-H_xAcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:09:09 INFO - Running setup.py (path:/tmp/pip-rw6gvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:09:09 INFO - Running setup.py (path:/tmp/pip-QWt9RK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:09:09 INFO - Running setup.py (path:/tmp/pip-n9IEZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:09:09 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:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:09:09 INFO - Running setup.py (path:/tmp/pip-kt2bJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:09:10 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:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:09:10 INFO - Running setup.py (path:/tmp/pip-FirUE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:09:10 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:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:09:10 INFO - Running setup.py (path:/tmp/pip-tESgOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:09:10 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:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:09:10 INFO - Running setup.py (path:/tmp/pip-68osmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:09:10 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:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:09:10 INFO - Running setup.py (path:/tmp/pip-IYQe53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:09:10 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:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:09:10 INFO - Running setup.py (path:/tmp/pip-_Duu82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:09:10 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:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:09:10 INFO - Running setup.py (path:/tmp/pip-pbmEa7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:09:10 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:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:09:10 INFO - Running setup.py (path:/tmp/pip-jDif7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:09:10 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:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:09:10 INFO - Running setup.py (path:/tmp/pip-OMrPgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:09:10 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:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:09:10 INFO - Running setup.py (path:/tmp/pip-Tu6Vlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:09:10 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:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:09:10 INFO - Running setup.py (path:/tmp/pip-yWD6Qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:09:10 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:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:09:10 INFO - Running setup.py (path:/tmp/pip-ZzMkyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:09:10 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:09:10 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:09:10 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:09:10 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:09:10 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:09:10 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:09:10 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:09:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:09:10 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:10 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:10 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:10 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:11 INFO - Downloading blessings-1.6.tar.gz 18:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:09:12 INFO - Installing collected packages: blessings 18:09:12 INFO - Running setup.py install for blessings 18:09:12 INFO - Successfully installed blessings 18:09:12 INFO - Cleaning up... 18:09:12 INFO - Return code: 0 18:09:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:09:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:09:12 INFO - Reading from file tmpfile_stdout 18:09:12 INFO - Current package versions: 18:09:12 INFO - argparse == 1.2.1 18:09:12 INFO - blessings == 1.6 18:09:12 INFO - blobuploader == 1.2.4 18:09:12 INFO - docopt == 0.6.1 18:09:12 INFO - functools32 == 3.2.3-2 18:09:12 INFO - jsonschema == 2.5.1 18:09:12 INFO - manifestparser == 1.1 18:09:12 INFO - mozInstall == 1.13 18:09:12 INFO - mozcrash == 1.0 18:09:12 INFO - mozdebug == 0.1 18:09:12 INFO - mozdevice == 0.51 18:09:12 INFO - mozfile == 1.2 18:09:12 INFO - mozhttpd == 0.7 18:09:12 INFO - mozinfo == 0.10 18:09:12 INFO - mozleak == 0.1 18:09:12 INFO - mozlog == 3.5 18:09:12 INFO - moznetwork == 0.27 18:09:12 INFO - mozprocess == 0.25 18:09:12 INFO - mozprofile == 0.28 18:09:12 INFO - mozrunner == 6.13 18:09:12 INFO - mozscreenshot == 0.1 18:09:12 INFO - mozsystemmonitor == 0.3 18:09:12 INFO - moztest == 0.8 18:09:12 INFO - mozversion == 1.4 18:09:12 INFO - psutil == 3.1.1 18:09:12 INFO - requests == 1.2.3 18:09:12 INFO - wsgiref == 0.1.2 18:09:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:09:12 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:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:12 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:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:12 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 0x267a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2842b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4d60>, '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 0x29e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5ef0>, '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': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', '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:12 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:09:12 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:09:12 INFO - Using env: {'DISPLAY': ':0', 18:09:12 INFO - 'HOME': '/home/cltbld', 18:09:12 INFO - 'LANG': 'en_US.UTF-8', 18:09:12 INFO - 'LANGUAGE': 'en_US:en', 18:09:12 INFO - 'LOGNAME': 'cltbld', 18:09:12 INFO - 'MAIL': '/var/mail/cltbld', 18:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:12 INFO - 'MOZ_NO_REMOTE': '1', 18:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:12 INFO - 'NO_EM_RESTART': '1', 18:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:12 INFO - 'PWD': '/builds/slave/test', 18:09:12 INFO - 'SHELL': '/bin/bash', 18:09:12 INFO - 'SHLVL': '1', 18:09:12 INFO - 'TERM': 'linux', 18:09:12 INFO - 'TMOUT': '86400', 18:09:12 INFO - 'USER': 'cltbld', 18:09:12 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:12 INFO - '_': '/tools/buildbot/bin/python'} 18:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:12 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:09:12 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:09:12 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:09:12 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:09:12 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:09:12 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:09:12 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:09:12 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:09:12 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:09:12 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:09:12 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:12 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:12 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:12 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:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:09:13 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:09:13 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:09:13 INFO - Installing collected packages: simplejson 18:09:13 INFO - Running setup.py install for simplejson 18:09:13 INFO - building 'simplejson._speedups' extension 18:09:13 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:09:14 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:09:14 INFO - Successfully installed simplejson 18:09:14 INFO - Cleaning up... 18:09:14 INFO - Return code: 0 18:09:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:09:14 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:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:14 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:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:14 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 0x267a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2842b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4d60>, '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 0x29e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5ef0>, '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': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', '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:14 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:09:14 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:09:14 INFO - Using env: {'DISPLAY': ':0', 18:09:14 INFO - 'HOME': '/home/cltbld', 18:09:14 INFO - 'LANG': 'en_US.UTF-8', 18:09:14 INFO - 'LANGUAGE': 'en_US:en', 18:09:14 INFO - 'LOGNAME': 'cltbld', 18:09:14 INFO - 'MAIL': '/var/mail/cltbld', 18:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:14 INFO - 'MOZ_NO_REMOTE': '1', 18:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:14 INFO - 'NO_EM_RESTART': '1', 18:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:14 INFO - 'PWD': '/builds/slave/test', 18:09:14 INFO - 'SHELL': '/bin/bash', 18:09:14 INFO - 'SHLVL': '1', 18:09:14 INFO - 'TERM': 'linux', 18:09:14 INFO - 'TMOUT': '86400', 18:09:14 INFO - 'USER': 'cltbld', 18:09:14 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:14 INFO - '_': '/tools/buildbot/bin/python'} 18:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:09:15 INFO - Cleaning up... 18:09:15 INFO - Return code: 0 18:09:15 INFO - Running post-action listener: _resource_record_post_action 18:09:15 INFO - Running post-action listener: _start_resource_monitoring 18:09:15 INFO - Starting resource monitoring. 18:09:15 INFO - [mozharness: 2017-09-27 01:09:15.118420Z] Finished create-virtualenv step (success) 18:09:15 INFO - [mozharness: 2017-09-27 01:09:15.118947Z] Running install step. 18:09:15 INFO - Running pre-action listener: _resource_record_pre_action 18:09:15 INFO - Running main action method: install 18:09:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:09:15 INFO - Reading from file tmpfile_stdout 18:09:15 INFO - Detecting whether we're running mozinstall >=1.0... 18:09:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:09:15 INFO - Reading from file tmpfile_stdout 18:09:15 INFO - Output received: 18:09:15 INFO - Usage: mozinstall [options] installer 18:09:15 INFO - Options: 18:09:15 INFO - -h, --help show this help message and exit 18:09:15 INFO - -d DEST, --destination=DEST 18:09:15 INFO - Directory to install application into. [default: 18:09:15 INFO - "/builds/slave/test"] 18:09:15 INFO - --app=APP Application being installed. [default: firefox] 18:09:15 INFO - mkdir: /builds/slave/test/build/application 18:09:15 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:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:09:33 INFO - Reading from file tmpfile_stdout 18:09:33 INFO - Output received: 18:09:33 INFO - /builds/slave/test/build/application/firefox/firefox 18:09:33 INFO - Running post-action listener: _resource_record_post_action 18:09:33 INFO - [mozharness: 2017-09-27 01:09:33.289032Z] Finished install step (success) 18:09:33 INFO - [mozharness: 2017-09-27 01:09:33.289373Z] Running setup-mitmproxy step. 18:09:33 INFO - Running pre-action listener: _resource_record_pre_action 18:09:33 INFO - Running main action method: setup_mitmproxy 18:09:33 INFO - Skipping: mitmproxy is not required 18:09:33 INFO - Running post-action listener: _resource_record_post_action 18:09:33 INFO - [mozharness: 2017-09-27 01:09:33.290507Z] Finished setup-mitmproxy step (success) 18:09:33 INFO - [mozharness: 2017-09-27 01:09:33.290727Z] Running run-tests step. 18:09:33 INFO - Running pre-action listener: _resource_record_pre_action 18:09:33 INFO - Running pre-action listener: _set_gcov_prefix 18:09:33 INFO - Running main action method: run_tests 18:09:33 WARNING - Try message not found. 18:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:09:33 INFO - Python 2.7.3 18:09:33 INFO - Return code: 0 18:09:33 INFO - grabbing minidump binary from tooltool 18:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:09:33 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 0x29e4d60>, '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 0x29e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:09:33 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:09:33 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:09:33 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:09:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:09:33 INFO - Return code: 0 18:09:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:09:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:09:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:09:33 INFO - ENV: RUST_BACKTRACE is now full 18:09:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:09:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-060 --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:09:33 INFO - Using env: {'DISPLAY': ':0', 18:09:33 INFO - 'HOME': '/home/cltbld', 18:09:33 INFO - 'LANG': 'en_US.UTF-8', 18:09:33 INFO - 'LANGUAGE': 'en_US:en', 18:09:33 INFO - 'LOGNAME': 'cltbld', 18:09:33 INFO - 'MAIL': '/var/mail/cltbld', 18:09:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:09:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:09:33 INFO - 'MOZ_ACCELERATED': '1', 18:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:33 INFO - 'MOZ_NO_REMOTE': '1', 18:09:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:09:33 INFO - 'MOZ_WEBRENDER': '1', 18:09:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:33 INFO - 'NO_EM_RESTART': '1', 18:09:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:33 INFO - 'PWD': '/builds/slave/test', 18:09:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:09:33 INFO - 'RUST_BACKTRACE': 'full', 18:09:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:09:33 INFO - 'SHELL': '/bin/bash', 18:09:33 INFO - 'SHLVL': '1', 18:09:33 INFO - 'TERM': 'linux', 18:09:33 INFO - 'TMOUT': '86400', 18:09:33 INFO - 'USER': 'cltbld', 18:09:33 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:33 INFO - '_': '/tools/buildbot/bin/python'} 18:09:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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:09:33 INFO - mozversion application_buildid: 20170927001127 18:09:33 INFO - mozversion application_changeset: 70158e4e215d784d1391db5e517b18727f4b3683 18:09:33 INFO - mozversion application_display_name: Nightly 18:09:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:09:33 INFO - mozversion application_name: Firefox 18:09:33 INFO - mozversion application_remotingname: firefox 18:09:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:09:33 INFO - mozversion application_vendor: Mozilla 18:09:33 INFO - mozversion application_version: 58.0a1 18:09:33 INFO - mozversion platform_buildid: 20170927001127 18:09:33 INFO - mozversion platform_changeset: 70158e4e215d784d1391db5e517b18727f4b3683 18:09:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:09:33 INFO - mozversion platform_version: 58.0a1 18:09:33 INFO - using testdate: 1506474573 18:09:33 INFO - actual date: 1506474573 18:09:33 INFO - starting webserver on 'localhost:51048' 18:09:33 INFO - SUITE-START | Running 2 tests 18:09:33 INFO - TEST-START | dromaeo_css 18:09:33 INFO - Initialising browser for dromaeo_css test... 18:09:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51048/getInfo.html -profile /tmp/tmpZpjObk/profile 18:09:33 INFO - TEST-INFO | started process 10515 (/builds/slave/test/build/application/firefox/firefox http://localhost:51048/getInfo.html) 18:09:39 INFO - TEST-INFO | 10515: exit 0 18:09:39 INFO - Browser initialized. 18:09:39 INFO - Running cycle 1/1 for dromaeo_css test... 18:09:39 INFO - Using env: {'DISPLAY': ':0', 18:09:39 INFO - 'HOME': '/home/cltbld', 18:09:39 INFO - 'JSGC_DISABLE_POISONING': '1', 18:09:39 INFO - 'LANG': 'en_US.UTF-8', 18:09:39 INFO - 'LANGUAGE': 'en_US:en', 18:09:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:09:39 INFO - 'LOGNAME': 'cltbld', 18:09:39 INFO - 'MAIL': '/var/mail/cltbld', 18:09:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:09:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:09:39 INFO - 'MOZ_ACCELERATED': '1', 18:09:39 INFO - 'MOZ_CRASHREPORTER': '1', 18:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:09:39 INFO - 'MOZ_NO_REMOTE': '1', 18:09:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:09:39 INFO - 'MOZ_WEBRENDER': '1', 18:09:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:09:39 INFO - 'NO_EM_RESTART': '1', 18:09:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:39 INFO - 'PWD': '/builds/slave/test', 18:09:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:09:39 INFO - 'RUST_BACKTRACE': 'full', 18:09:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:09:39 INFO - 'SHELL': '/bin/bash', 18:09:39 INFO - 'SHLVL': '1', 18:09:39 INFO - 'TERM': 'linux', 18:09:39 INFO - 'TMOUT': '86400', 18:09:39 INFO - 'USER': 'cltbld', 18:09:39 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:39 INFO - '_': '/tools/buildbot/bin/python'} 18:09:39 INFO - TEST-INFO | started process 10764 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZpjObk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:09:40 INFO - PID 10764 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:09:40 INFO - PID 10764 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:09:41 INFO - PID 10764 | 18:09:41 INFO - PID 10764 | (/builds/slave/test/build/application/firefox/firefox:10824): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:09:41 INFO - PID 10764 | 18:09:51 INFO - PID 10764 | RSS: Main: 194695168 18:09:51 INFO - PID 10764 | 18:12:48 INFO - PID 10764 | Cycle 1(1): loaded http://localhost:51048/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51048/tests/dromaeo/cssquery-ext.html) 18:12:50 INFO - PID 10764 | RSS: Main: 193327104 18:12:50 INFO - PID 10764 | 18:15:52 INFO - PID 10764 | Cycle 1(1): loaded http://localhost:51048/tests/dromaeo/cssquery-ext.html (next: http://localhost:51048/tests/dromaeo/cssquery-jquery.html) 18:15:53 INFO - PID 10764 | RSS: Main: 191598592 18:15:53 INFO - PID 10764 | 18:18:55 INFO - PID 10764 | Cycle 1(1): loaded http://localhost:51048/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51048/tests/dromaeo/cssquery-mootools.html) 18:18:56 INFO - PID 10764 | RSS: Main: 193724416 18:18:56 INFO - PID 10764 | 18:21:58 INFO - PID 10764 | Cycle 1(1): loaded http://localhost:51048/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51048/tests/dromaeo/cssquery-prototype.html) 18:21:58 INFO - PID 10764 | RSS: Main: 192217088 18:21:58 INFO - PID 10764 | 18:25:02 INFO - PID 10764 | Cycle 1(1): loaded http://localhost:51048/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51048/tests/dromaeo/cssquery-yui.html) 18:25:02 INFO - PID 10764 | RSS: Main: 192962560 18:25:02 INFO - PID 10764 | 18:28:04 INFO - PID 10764 | Cycle 1(1): loaded http://localhost:51048/tests/dromaeo/cssquery-yui.html (next: http://localhost:51048/tests/dromaeo/cssquery-dojo.html) 18:28:05 INFO - PID 10764 | RSS: Main: 195395584 18:28:05 INFO - PID 10764 | 18:28:05 INFO - PID 10764 | __start_tp_report 18:28:05 INFO - PID 10764 | _x_x_mozilla_page_load 18:28:05 INFO - PID 10764 | _x_x_mozilla_page_load_details 18:28:05 INFO - PID 10764 | |i|pagename|runs| 18:28:05 INFO - PID 10764 | |0;dojo.html;3057.526083457065;3072.8924487642935;3080.2145452909504;3093.9845300773495;3101.4417404867113;5923;6058.303295121063;6068.211132552773;6068.332483426825;6173.38266173383;6018.518518518508;6087.269527656687;6087.78692745754;6106.511479081674;6110.694465276741;5626.240457538221;5644.153376993463;5648.152777083429;5663.3204015518195;5719.027765279891;5659.405762394951;5701.059325211356;5702.629329093616;5734.369219385864;5793.4785869271755;5658.207850900857;5666;5721.370649228582;5740.339860915986;5797.594168408213;5491.28613280271;5638.407967163471;5740.54075673945;5752.36723960364;5784.826455206308;5539.833804985857;5589.385167631557;5627.240322556443;5650.943490565124;5706.743196556124;6049.183360246355;6080.574359794816;6127.5710700251;6150.7847225346895;6217.56477046607;6349.873002539969;6492.707828147744;6501.187351581052;6550.639714815683;6585.407313341774;6416.486681065504;6433.613983161025;6460.257070436913;6482.481401487869;6556.311587283314;6038.939610603926;6081.878362432726;6159.692015399212;6182.381761823826;6205.503559715212;6630.900536491957;6750.932490675129;6779.220389655253;6784;6828.317168283232;6339;6422.550421470546;6431.517636177306;6442.871142577123;6516.2506311773805;6332.968335158294;6393.264774550986;6396.328385519547;6405.19935008124;6440.967795160994;6495.447886929664;6511.576747511396;6540.9672951635885;6565.113709649232;6573.211214654272;6441.1626488556185;6457.483401327882;6572.441342485942;6593.76921807744;6608.041833934052;5094.668846525038;5138.306328645585;5178;5235.450277720861;5239.580833533309;4501.212287849678;4506.59440650343;4510.233260345749;4591.839285625054;4613.215753321943;5674.801381951612;5677.091665333527;5684;5690.061139911868;5701.914471282934;6579.703913323912;6583.341665833378;6658.4340331071435;6669.699863506154;6670.799876003728;5612.242347283146;5622.775088996476;5634.605577609528;5681.176229446707;5682.573806964411;5557.138643510262;5559.63862348946;5602.915956260659;5651.321841378978;5655.5475561954945;6238.500919926395;6291;6329;6335.334789847092;6406.199225096863;6501.609903405905;6570.244421891543;6586.604803711697;6620.702068406837;6634.436072933855;6275.152854364603;6288.905666415099;6373.936260637334;6389.456896163878;6402.743890244338;6372.139761132188;6422.646754428364;6453;6541.280459149585;6545.345465453416;6409.647469389229;6512.511561632802;6547.901781473186;6551.082848401133;6627.37039981201;6227.9377206227355;6257.4681152102585;6267.592606480564;6290.339514350927;6350.079238510298;6233.314335423191;6244.2819075806865;6280.214973128359;6305.274893387319;6320.62076275425;6259.405356491126;6276.215473065867;6324.430801227728;6352.618842869442;6379.457746091634;6228.22147231596;6380.393862583047;6446.29090800021;6480.57876238043;6518.282988871315;6148.508119350352;6174.845628859314;6190;6215;6215.192025036717 18:28:05 INFO - PID 10764 | |1;ext.html;864.2351518905766;875.964961401544;897.4391005621485;899.3389858454035;900.0774206438404;37706;37891.621083789185;38394.616053839454;41020;41416.55042073528;37561.43657845134;37632;37771.433428498596;37785.81107094468;37891;7119.89320160198;7222.674979625929;7259.092613423322;7763.767086987399;7947.205279472064;7848.018997625297;7874.330681892046;7915.4063445241545;7929.207079292053;7976.322012628933;7861.3317867981;7870.606469676522;7871.488353257048;7927.128015918244;7979.800504987364;7812.570308633023;7847.882281765778;7921.643526041342;7934.880976785353;7966.0839003514175;7768.262015108556;7784.649690763929;7801.0638723352995;7845.490043147177;7846.960765196138;36565.634343656486;36847;37110.25779484422;37192.44211336832;37239.81380093109;3572.821358932069;3591.191981804099;3613.927721445583;3616.168281295317;3622.166901612644;30774.84612576945;31057.378852422826;31124.06627801147;31200.37599248002;31303.53044704331;4976.825811096631;5254.6847189168775;5274.4198138204765;5300.072487314705;5319.281896943901;178458;178807.10596447065;178833.1058344713;179022.10488947603;180078;41470.37794433086;47132.764336178785;47134.05731885343;47232.291515627294;47402.52597473981;43554.128917421476;48743.75628121907;49179;49648;49652.50347496479;15119.319630616648;15135.167565783988;15332.15673137988;15349.9232503839;15389.153596552296;12568.685782855318;12609.117361784587;12646.683832904251;12689.36553172356;12741.490340386466;13127.277999709917;13200.273984930729;13290.20258784476;13337.73324533504;13489.66275843092;5486.259354987105;5494.478024587658;5588.413216612278;5629.605927585111;5693.206639908416;993.6174572789383;1013.5489707080426;1025.5795123999123;1040.1834559870617;1047.1465755409317;22635.773642263695;23008;23040.423989400064;23394.06423743065;23426.414339641644;1108.551036830069;1118.6364431559775;1119.6696974392573;1127.0701671121358;1135.4152611405134;12080.21478603888;12317.199382040319;12373.690657733629;12403.75192496145;12416.192947458387;222856;224099;224852;225036;226364;50497.49502505001;50663.746681266355;50922;51073;51078.744606276734;1408.830940287172;1466.30350583472;1470.8602682745122;1472.3006571878486;1475.6827282134393;21956.121755129618;22295;22324.330270091923;22424;22870.542589148456;35977;36417;36446.27107457783;36611.450828237066;36619.450708238866;180370;181202;181557;181930.09034954742;182785;29415.26461838471;30241.546376803926;30431.391372172875;31599.526007109453;33972.66027339695;42363.788181058895;42687;43079.569204307554;43210.13579728325;43700.78149609231;31835.68164318327;31908.361832762752;32041.519377208893;32183.51724724178;32404;30778.384432311675;31067.22331941629;31112.533312000793;31250.21874453066;31416.685833141375;34307.313853722284;35064.29871402609;35213.471797923565;35768;35813.28373432569;30247;30367.544486833158;30520;31522.527162093047;31562.526562102044;23125.768742313034;23235;23255.18606848752;23468.530629387657;23754 18:28:05 INFO - PID 10764 | |2;jquery.html;573.6902072880645;573.9611303540592;575.5223164773238;581.4040608376414;581.9098039803829;21906.671399929015;22500.774992250073;22552.097916083338;22643;22690.319290421274;24368.390790230253;24763.13329033484;24831.50336993259;24853.875730621367;26254.737452625468;6306.4324210820905;6529.738810447577;6554.344565543432;6590.901136482957;6605.273419923828;6473.44975677068;6545.214574251097;6578.901316480256;6584.93415065848;6590.275069742349;6140.201773769426;6412.294647588761;6427;6494;6558;6446.129772480702;6473.514486413538;6474.935250647481;6497.252815926182;6500.057491663706;5962.731677074542;6024.21685180928;6073.967425537669;6097.268327800648;6113.816585502397;28294.43411131766;28800.855995720096;29712.851435742898;29985.250368740955;30027.099187024425;2037.7350944377135;2086.2176683743596;2087.039961617658;2090.1639344262267;2093.8324934005245;19338.80661193384;19341.12964916582;19698.409047728448;19736.605267894563;19739.506512337164;2322.280093171122;2370.158593699229;2423.3820375804235;2429.842060266095;2432.416220107179;123589.38205309006;131488.34255828755;131692;133536.33231833778;143786.56213437836;14681.58527658698;14963.476278330207;14969;15030.455660655562;15050.473233436776;22760.089596415957;23075.423114422272;23085.653715194287;23160.884195578914;23229.9546520407;9671.323007389416;9674;9681.70954871355;9691.176250018827;10006.349587276804;8895.599698013604;8933.910660893438;8963.417377870417;8968.372213944962;9005.414648047856;9128;9168.404053736485;9249.537523123818;9360.4383736976;9502.144806967406;2707.5803250496206;2711.9728802712116;2753.986230068837;2796.1751283371455;2837.6169217155834;950.9714708558754;970.9271255262832;981.5288661442405;989.4360214677565;1000.8048430556117;19048.238070477026;19186.424407267805;19521.219151234076;19554.072016070186;20007.699884501744;1012.7518757904361;1034.425893629043;1038.657243109772;1044.6187141693226;1048.895110488945;10710.410927399067;10895.237333386587;10980.670579882617;10995.615153469591;11100.444977751242;459893;464155;466716.6664166725;473880;484865;112117;113748.43125784319;114494.42752786183;115258;115399;1147.363213416545;1167.8656954450178;1203.2419575667273;1228.3060071059822;1240.0699475393455;21222.787772122698;21344.252951146635;21529.246476373253;21779.891100545028;21842.235521756662;57177.428225718875;58013.709931450074;58379;58623.70688146703;59560;69409.65295173491;70972;72164.63917680379;73462.26537734554;73758.63120684362;14773;14959.102453852369;15116.54650360491;15134.545963620669;15137.545873623809;21935.122595096018;22790.974406151432;22969.310920672408;23045.07819687194;23424.765752342257;18408;18526.536836579195;18583.72124418162;18738.531536711693;18774.71837922405;16310.429134980219;16490.58773530671;16785;16882.831171688125;16892.493225203263;18984.52538686544;19052.809471905657;19059.90470047641;19339.90330048341;19377.128029238436;14262.07296525764;14740.262986850828;14867.405303787727;14889;14919.32863021145;18033.639327213645;18303.35938242155;18311.90844045771;18522.0738963054;18542.072896354857 18:28:05 INFO - PID 10764 | |3;mootools.html;1288.7164823738776;1385.106606238976;1388.3544151969331;1388.791681247812;1398.9999999999993;15479.845201547996;15983.760243596354;16092.51722448327;16187.109708966002;16199;15502.534923952271;15644.921775391136;15657.217139143026;15702;15704.607384815357;2680.32991752062;2723.7684796792296;2752.8073034887566;2767.681716602586;2789.4002789400274;2617.397998460355;2698.568229083347;2721.687005994306;2729.5223335916235;2731.549294366427;2461.950760984779;2584.470183612355;2595.2603508000225;2597.467519158568;2654.9601755973677;2692.4480481501246;2718.768904643098;2753.9586906196423;2756.2144788735213;2760.8205466644704;2626.4484458263787;2654.9867250663815;2661.840289582612;2667.6398686177504;2673.6791585009923;14758.483453079198;15060.698786024219;15086.321115549827;15100.39598416073;15124.848751512563;8098.433109682324;8415.242628163434;8416.579171041485;8438.535880526568;8446.23983841451;4990.750462476862;5198.610104242197;5217.791288348461;5311.097113490716;5330.706811125387;1756.192151610261;1757.8593712502968;1762.189392879277;1792.3010026089835;1808.9095545222685;27273.863630681917;27366.315842103908;27370.178894633194;27493.9999999998;27534.724652753415;6628.5360024798765;6684.2647308795995;6723.831904202434;6873.5875847449315;6903.447724182053;17676.734848977274;17716.645667086585;17726.645467090842;17731;17753.556161096076;7657.808554786064;7686.961565192139;7748.961255193688;7805.41459390548;7816.8827467588035;6958.860822783516;7115.964420177969;7138.964305178441;7348.816279593053;7381.557106573516;5162.586993040548;5274.208868669731;5319.547838433699;5348.839534813962;5395.892082158413;1403.4456389726001;1462.1300326305832;1529.7170023545457;1558.7350150474447;1570.1128862192825;861.4701958295728;893.0801274687083;916.8212198621205;921.5853664624902;932.3100905330007;8386.496810191407;8514.191151840565;8602.35482338827;8683.609237584324;8762.430442021248;890.2166093837511;999.2355847776457;1004.9397036177853;1009.2128140050772;1018.2414101494434;5050.722210278396;5133.383993920679;5170.896582068338;5253.842384728464;5480.725963701799;21094;22216.666749998763;22218.88890555537;22223.333300001024;22224.444388890082;21295.574088518453;21678.89160554187;21946.780532194793;22152.446188845406;22687.52531085474;10250.487475626189;10317.587296508205;10413.11488523484;10425.165986721044;10528;8277.296429803535;8344.666213351398;8361.247487726134;8361.623726932181;8409.327253819558;18309;19056.714149288047;19083;19103;19461.221551137794;19698.90150549238;19887.502812429804;19963.60072798565;20116.798832011493;20438.386848394573;11005.394703291397;11013.229073964745;11110.222284440011;11123.888761112285;11142.387168705798;13777.517786877812;13924.443022278996;14064.718705626035;14072.577822665336;14115.647108822362;11254.38100904458;11301.943490282496;11312.886871131182;11514.19400641947;11653.242539234923;10961.945190273998;11065.391403472886;11267.830982535104;11274.492647830702;11397.943010284895;9125.589348479467;9143.63425462974;9168.679096231599;9177.862332064891;9203.76990575241;7324.230955749569;7355.1541572719825;7360.484766066324;7415.1101867776215;7522.022137122139;7185.784426467214;7328.743493977685;7387.852242955219;7408.851822963618;7497.737579184703 18:28:05 INFO - PID 10764 | |4;prototype.html;494.4857348357706;505.3985756949229;528.9523942845148;532.4223217808677;536.9060414427477;5964.598319394939;6123.693815309239;6126;6130.417610327017;6135.32511423743;6020.621854367522;6046.697665116749;6075.238180927537;6080.452759251666;6105.6947152642415;4829.492903245161;4833.927491087637;4856.198727210024;4858.975705121487;4903.607711383099;4775.2598347256235;4944.381952255968;4954.7522623868845;4972.25416187573;4988.675736077162;4880.438749543795;4903.239997800347;4928.728919909404;4959.677620954644;4984;4849.127157111719;4914.557689807899;4926.507349265081;4935.259711043336;4981.750912454381;4431.711938723973;4440.600345968846;4445.844395446144;4457.576530229623;4473.865784026484;5979.282486101695;5979.701014949279;6023.277206735176;6023.45788879003;6073.665948372882;5645.491905728504;5658.773649054033;5666.5183459406;5690.374058853523;5701.116326969326;6378.170837791057;6394.904076438857;6473.414013566659;6479.514036447238;6537.509686773511;4743.786529606176;4782.832600858953;4789.736564488951;4823.2282834746275;4849.709017458964;6727.55981052503;6761.087253220752;6796.966015169942;6801.693923773442;6819.693113809891;4932.728699921467;4948.455669876311;5022.824201152942;5038.521340472649;5058.114829904752;5031.465403052104;5078.619103567247;5081.364829396325;5103.157978933435;5116.692998420107;5597.552195824324;5616;5627.662340259516;5642.492175704207;5700.857478563069;6484;6544.803655890331;6580.243272023683;6586.73653053882;6591.373819487141;1647.4398704440548;1662.5178698177635;1670.2567357526027;1742.6427582345589;1770.9468715938542;1208.4139192491634;1212.902967762577;1225.5477259447582;1249.4939549482292;1255.0900597067052;5456.590755693339;5544.722763861791;5605.859853503695;5618.353889302701;5618.85952851182;6315.810525684236;6364.236291645032;6494.870102597922;6548.869022619521;6591.242007169141;5564.944350556442;5602.159676048625;5609.382967873531;5633.155026745939;5642.379338272786;3463.6882680558365;3496.7727097739075;3504.351694936445;3517.648235176513;3541.13242255649;6314.873702525923;6317.96841015792;6331.778387756406;6353.5870168438905;6358.968205158944;6571.817072926919;6580.638064906476;6755.459563234929;6759.073664005735;6762.425193858478;6220.813375598739;6311.274203466476;6329.556931014877;6344.936550634527;6350.872982540323;6430.453411460078;6528.661624366899;6627.304133065959;6641.070250164884;6646.90029649546;6356.650384228727;6397.712102955285;6425.421712045938;6478.838029049311;6490;6224.813255602339;6240.594361366497;6259.655718935503;6268.717907694074;6281;4874.683145595525;4885.487023862585;4934.457209706858;4937.308776771183;4950.306957026091;6439.227292724903;6441.3880681335195;6465.418112369909;6482.481401487776;6593.967030164818;6201.503879689704;6204.81385558434;6228.501719862491;6289.182406287154;6345.238571371465;6347.270063942706;6401.743930242925;6404.871902562016;6411.4229719325485;6454.354564543696;5975.342712301556;6208.161898143693;6265.216847894013;6271.96864015677;6371.458426033653;5821.825345239649;6305.873882522232;6337.651429171256;6365;6381.649009304533;6154.415330543591;6217.689115544114;6223.159873417031;6235.532335074797;6293.181886354744 18:28:05 INFO - PID 10764 | |5;yui.html;861.9353548483865;892.3218354050921;906.7370462565856;909.4634165842156;921.8525035994238;19243.807561924375;20309.593808123827;20318.08568614412;20348.796512034874;20362.89818550909;18313.90843045786;19018;19247.133878975445;19283.325083622076;19394.903025484888;952.2715122930946;959.3045042344314;961.3703024518951;962.5091203486203;966.6954909203579;974.1524873360187;976.1409959235898;977.2524019125375;979.6718099436697;986.1765425869407;969.7284760267137;1014.9492525373739;1025.5333823110466;1038.7662775875442;1041.661460025491;1005.3515482513737;1005.9949700251489;1013.4020927652647;1014.0569270578359;1014.1734486393566;740.6111791309552;742.4172024960434;744.5049042386806;745.1021519069508;746.0562388578453;20786.168553257852;21061.368158955254;21071.894640526698;21200.78799212019;21242;22177.556448871095;22275.220367287227;22715.432114197112;22875.771242287698;22968.77031229683;17416.912915435467;17442.47672569825;17527.824721752746;17534.386296479563;17615.5596110097;3761.5298087739034;3845.9230815383817;3846.519185101862;3848.76907385555;3850.4609354690365;133794;135516;137931;138557.3072134633;138578.30710846483;16798;17221.913890430467;17373.47879563615;17428.564285892953;17609.55976100582;30907.536386954213;31232.21919452032;31381.372372552876;31486.685133148832;31679.683203167675;8481.575921203916;8558.486490810572;8595.3553483489;8637.395382323177;8660.523671198018;3749.1751814600743;3791.9241615167543;3855.614438556178;3863.5363756349416;3894.084890050836;5251.10731175701;5343.465653434625;5368.624196306299;5370.409254981949;5370.409254981949;972.2173650211506;995.4922989275377;996.3274789517047;1015.94920253987;1018.0837246478229;626.1828314050208;626.2109741725459;639.4916041746823;659.1398225316009;661.9768308109193;10934.781304373868;11067.833982490269;11249.606263780728;11492.138089643144;11654.417279136009;649.2274193709565;667.7829705345684;674.6592970549939;687.4534744877834;689.917209934811;5347.3048503695045;5394.757235924393;5402.864928376744;5441.428649991773;5574.052411090124;118215;119304.40347798205;119445;120614;125621.37189313995;101028;103665;105656;105962.47018764856;106148;88628;91583.54208228916;93707.53146234225;93853.53073234453;95418.5229073864;10043.799124017625;10100;10102.696919092145;10144.036316549917;10181.592736290466;43087;43284.35073473837;43628.56371436372;45291.54708453005;45772.31341529813;55712.44287557235;58396;58477.24568262959;58482.12276815936;58492.83014339775;15024.849751502783;15101.47144849925;15147.091174529123;15172.089674619558;15640.765388518954;16058.197090145213;16139.354425822836;16266.430674926321;16366.263518141473;16458.670826583642;15835;16211.756823647418;16316;16409.67180656356;16506.33974641001;15369.231538423257;15389.614934655934;15432.691346173238;15476;15666.138362390178;14703.705925881208;14862.925685371503;15166;15169.848301516844;15245.542633721006;11437.370944597795;11540.538378464767;11787;11806.055515558563;11897.286162830258;10679.946600266949;10734.302270352402;10775.461226938465;10790.622328218475;10912.781744365227 18:28:05 INFO - PID 10764 | __end_tp_report 18:28:05 INFO - PID 10764 | __start_cc_report 18:28:05 INFO - PID 10764 | _x_x_mozilla_cycle_collect,316 18:28:05 INFO - PID 10764 | __end_cc_report 18:28:05 INFO - PID 10764 | __startTimestamp1506475685113__endTimestamp 18:28:05 INFO - PID 10764 | 18:28:05 INFO - PID 10764 | ------- Summary: start ------- 18:28:05 INFO - PID 10764 | Number of tests: 6 18:28:05 INFO - PID 10764 | 18:28:05 INFO - PID 10764 | [#0] dojo.html Cycles:165 Average:6015.36 Median:6233.31 stddev:703.26 (11.3%) stddev-sans-first:666.04 18:28:05 INFO - PID 10764 | Values: 3057.5 3072.9 3080.2 3094.0 3101.4 5923.0 6058.3 6068.2 6068.3 6173.4 6018.5 6087.3 6087.8 6106.5 6110.7 5626.2 5644.2 5648.2 5663.3 5719.0 5659.4 5701.1 5702.6 5734.4 5793.5 5658.2 5666.0 5721.4 5740.3 5797.6 5491.3 5638.4 5740.5 5752.4 5784.8 5539.8 5589.4 5627.2 5650.9 5706.7 6049.2 6080.6 6127.6 6150.8 6217.6 6349.9 6492.7 6501.2 6550.6 6585.4 6416.5 6433.6 6460.3 6482.5 6556.3 6038.9 6081.9 6159.7 6182.4 6205.5 6630.9 6750.9 6779.2 6784.0 6828.3 6339.0 6422.6 6431.5 6442.9 6516.3 6333.0 6393.3 6396.3 6405.2 6441.0 6495.4 6511.6 6541.0 6565.1 6573.2 6441.2 6457.5 6572.4 6593.8 6608.0 5094.7 5138.3 5178.0 5235.5 5239.6 4501.2 4506.6 4510.2 4591.8 4613.2 5674.8 5677.1 5684.0 5690.1 5701.9 6579.7 6583.3 6658.4 6669.7 6670.8 5612.2 5622.8 5634.6 5681.2 5682.6 5557.1 5559.6 5602.9 5651.3 5655.5 6238.5 6291.0 6329.0 6335.3 6406.2 6501.6 6570.2 6586.6 6620.7 6634.4 6275.2 6288.9 6373.9 6389.5 6402.7 6372.1 6422.6 6453.0 6541.3 6545.3 6409.6 6512.5 6547.9 6551.1 6627.4 6227.9 6257.5 6267.6 6290.3 6350.1 6233.3 6244.3 6280.2 6305.3 6320.6 6259.4 6276.2 6324.4 6352.6 6379.5 6228.2 6380.4 6446.3 6480.6 6518.3 6148.5 6174.8 6190.0 6215.0 6215.2 18:28:05 INFO - PID 10764 | 18:28:05 INFO - PID 10764 | [#1] ext.html Cycles:180 Average:35986.09 Median:35138.89 stddev:50757.52 (144.4%) stddev-sans-first:50831.39 18:28:05 INFO - PID 10764 | Values: 864.2 876.0 897.4 899.3 900.1 37706.0 37891.6 38394.6 41020.0 41416.6 37561.4 37632.0 37771.4 37785.8 37891.0 7119.9 7222.7 7259.1 7763.8 7947.2 7848.0 7874.3 7915.4 7929.2 7976.3 7861.3 7870.6 7871.5 7927.1 7979.8 7812.6 7847.9 7921.6 7934.9 7966.1 7768.3 7784.6 7801.1 7845.5 7847.0 36565.6 36847.0 37110.3 37192.4 37239.8 3572.8 3591.2 3613.9 3616.2 3622.2 30774.8 31057.4 31124.1 31200.4 31303.5 4976.8 5254.7 5274.4 5300.1 5319.3 178458.0 178807.1 178833.1 179022.1 180078.0 41470.4 47132.8 47134.1 47232.3 47402.5 43554.1 48743.8 49179.0 49648.0 49652.5 15119.3 15135.2 15332.2 15349.9 15389.2 12568.7 12609.1 12646.7 12689.4 12741.5 13127.3 13200.3 13290.2 13337.7 13489.7 5486.3 5494.5 5588.4 5629.6 5693.2 993.6 1013.5 1025.6 1040.2 1047.1 22635.8 23008.0 23040.4 23394.1 23426.4 1108.6 1118.6 1119.7 1127.1 1135.4 12080.2 12317.2 12373.7 12403.8 12416.2 222856.0 224099.0 224852.0 225036.0 226364.0 50497.5 50663.7 50922.0 51073.0 51078.7 1408.8 1466.3 1470.9 1472.3 1475.7 21956.1 22295.0 22324.3 22424.0 22870.5 35977.0 36417.0 36446.3 36611.5 36619.5 180370.0 181202.0 181557.0 181930.1 182785.0 29415.3 30241.5 30431.4 31599.5 33972.7 42363.8 42687.0 43079.6 43210.1 43700.8 31835.7 31908.4 32041.5 32183.5 32404.0 30778.4 31067.2 31112.5 31250.2 31416.7 34307.3 35064.3 35213.5 35768.0 35813.3 30247.0 30367.5 30520.0 31522.5 31562.5 23125.8 23235.0 23255.2 23468.5 23754.0 18:28:05 INFO - PID 10764 | 18:28:05 INFO - PID 10764 | [#2] jquery.html Cycles:180 Average:34165.95 Median:23007.19 stddev:79285.85 (344.6%) stddev-sans-first:79468.15 18:28:05 INFO - PID 10764 | Values: 573.7 574.0 575.5 581.4 581.9 21906.7 22500.8 22552.1 22643.0 22690.3 24368.4 24763.1 24831.5 24853.9 26254.7 6306.4 6529.7 6554.3 6590.9 6605.3 6473.4 6545.2 6578.9 6584.9 6590.3 6140.2 6412.3 6427.0 6494.0 6558.0 6446.1 6473.5 6474.9 6497.3 6500.1 5962.7 6024.2 6074.0 6097.3 6113.8 28294.4 28800.9 29712.9 29985.3 30027.1 2037.7 2086.2 2087.0 2090.2 2093.8 19338.8 19341.1 19698.4 19736.6 19739.5 2322.3 2370.2 2423.4 2429.8 2432.4 123589.4 131488.3 131692.0 133536.3 143786.6 14681.6 14963.5 14969.0 15030.5 15050.5 22760.1 23075.4 23085.7 23160.9 23230.0 9671.3 9674.0 9681.7 9691.2 10006.3 8895.6 8933.9 8963.4 8968.4 9005.4 9128.0 9168.4 9249.5 9360.4 9502.1 2707.6 2712.0 2754.0 2796.2 2837.6 951.0 970.9 981.5 989.4 1000.8 19048.2 19186.4 19521.2 19554.1 20007.7 1012.8 1034.4 1038.7 1044.6 1048.9 10710.4 10895.2 10980.7 10995.6 11100.4 459893.0 464155.0 466716.7 473880.0 484865.0 112117.0 113748.4 114494.4 115258.0 115399.0 1147.4 1167.9 1203.2 1228.3 1240.1 21222.8 21344.3 21529.2 21779.9 21842.2 57177.4 58013.7 58379.0 58623.7 59560.0 69409.7 70972.0 72164.6 73462.3 73758.6 14773.0 14959.1 15116.5 15134.5 15137.5 21935.1 22791.0 22969.3 23045.1 23424.8 18408.0 18526.5 18583.7 18738.5 18774.7 16310.4 16490.6 16785.0 16882.8 16892.5 18984.5 19052.8 19059.9 19339.9 19377.1 14262.1 14740.3 14867.4 14889.0 14919.3 18033.6 18303.4 18311.9 18522.1 18542.1 18:28:05 INFO - PID 10764 | 18:28:05 INFO - PID 10764 | [#3] mootools.html Cycles:180 Average:9428.24 Median:2658.41 stddev:6876.53 (258.7%) stddev-sans-first:6868.63 18:28:05 INFO - PID 10764 | Values: 1288.7 1385.1 1388.4 1388.8 1399.0 15479.8 15983.8 16092.5 16187.1 16199.0 15502.5 15644.9 15657.2 15702.0 15704.6 2680.3 2723.8 2752.8 2767.7 2789.4 2617.4 2698.6 2721.7 2729.5 2731.5 2462.0 2584.5 2595.3 2597.5 2655.0 2692.4 2718.8 2754.0 2756.2 2760.8 2626.4 2655.0 2661.8 2667.6 2673.7 14758.5 15060.7 15086.3 15100.4 15124.8 8098.4 8415.2 8416.6 8438.5 8446.2 4990.8 5198.6 5217.8 5311.1 5330.7 1756.2 1757.9 1762.2 1792.3 1808.9 27273.9 27366.3 27370.2 27494.0 27534.7 6628.5 6684.3 6723.8 6873.6 6903.4 17676.7 17716.6 17726.6 17731.0 17753.6 7657.8 7687.0 7749.0 7805.4 7816.9 6958.9 7116.0 7139.0 7348.8 7381.6 5162.6 5274.2 5319.5 5348.8 5395.9 1403.4 1462.1 1529.7 1558.7 1570.1 861.5 893.1 916.8 921.6 932.3 8386.5 8514.2 8602.4 8683.6 8762.4 890.2 999.2 1004.9 1009.2 1018.2 5050.7 5133.4 5170.9 5253.8 5480.7 21094.0 22216.7 22218.9 22223.3 22224.4 21295.6 21678.9 21946.8 22152.4 22687.5 10250.5 10317.6 10413.1 10425.2 10528.0 8277.3 8344.7 8361.2 8361.6 8409.3 18309.0 19056.7 19083.0 19103.0 19461.2 19698.9 19887.5 19963.6 20116.8 20438.4 11005.4 11013.2 11110.2 11123.9 11142.4 13777.5 13924.4 14064.7 14072.6 14115.6 11254.4 11301.9 11312.9 11514.2 11653.2 10961.9 11065.4 11267.8 11274.5 11397.9 9125.6 9143.6 9168.7 9177.9 9203.8 7324.2 7355.2 7360.5 7415.1 7522.0 7185.8 7328.7 7387.9 7408.9 7497.7 18:28:05 INFO - PID 10764 | 18:28:05 INFO - PID 10764 | [#4] prototype.html Cycles:180 Average:5393.81 Median:6021.95 stddev:1493.60 (24.8%) stddev-sans-first:1451.82 18:28:05 INFO - PID 10764 | Values: 494.5 505.4 529.0 532.4 536.9 5964.6 6123.7 6126.0 6130.4 6135.3 6020.6 6046.7 6075.2 6080.5 6105.7 4829.5 4833.9 4856.2 4859.0 4903.6 4775.3 4944.4 4954.8 4972.3 4988.7 4880.4 4903.2 4928.7 4959.7 4984.0 4849.1 4914.6 4926.5 4935.3 4981.8 4431.7 4440.6 4445.8 4457.6 4473.9 5979.3 5979.7 6023.3 6023.5 6073.7 5645.5 5658.8 5666.5 5690.4 5701.1 6378.2 6394.9 6473.4 6479.5 6537.5 4743.8 4782.8 4789.7 4823.2 4849.7 6727.6 6761.1 6797.0 6801.7 6819.7 4932.7 4948.5 5022.8 5038.5 5058.1 5031.5 5078.6 5081.4 5103.2 5116.7 5597.6 5616.0 5627.7 5642.5 5700.9 6484.0 6544.8 6580.2 6586.7 6591.4 1647.4 1662.5 1670.3 1742.6 1770.9 1208.4 1212.9 1225.5 1249.5 1255.1 5456.6 5544.7 5605.9 5618.4 5618.9 6315.8 6364.2 6494.9 6548.9 6591.2 5564.9 5602.2 5609.4 5633.2 5642.4 3463.7 3496.8 3504.4 3517.6 3541.1 6314.9 6318.0 6331.8 6353.6 6359.0 6571.8 6580.6 6755.5 6759.1 6762.4 6220.8 6311.3 6329.6 6344.9 6350.9 6430.5 6528.7 6627.3 6641.1 6646.9 6356.7 6397.7 6425.4 6478.8 6490.0 6224.8 6240.6 6259.7 6268.7 6281.0 4874.7 4885.5 4934.5 4937.3 4950.3 6439.2 6441.4 6465.4 6482.5 6594.0 6201.5 6204.8 6228.5 6289.2 6345.2 6347.3 6401.7 6404.9 6411.4 6454.4 5975.3 6208.2 6265.2 6272.0 6371.5 5821.8 6305.9 6337.7 6365.0 6381.6 6154.4 6217.7 6223.2 6235.5 6293.2 18:28:05 INFO - PID 10764 | 18:28:05 INFO - PID 10764 | [#5] yui.html Cycles:180 Average:23966.26 Median:20574.53 stddev:34507.33 (167.7%) stddev-sans-first:34560.52 18:28:05 INFO - PID 10764 | Values: 861.9 892.3 906.7 909.5 921.9 19243.8 20309.6 20318.1 20348.8 20362.9 18313.9 19018.0 19247.1 19283.3 19394.9 952.3 959.3 961.4 962.5 966.7 974.2 976.1 977.3 979.7 986.2 969.7 1014.9 1025.5 1038.8 1041.7 1005.4 1006.0 1013.4 1014.1 1014.2 740.6 742.4 744.5 745.1 746.1 20786.2 21061.4 21071.9 21200.8 21242.0 22177.6 22275.2 22715.4 22875.8 22968.8 17416.9 17442.5 17527.8 17534.4 17615.6 3761.5 3845.9 3846.5 3848.8 3850.5 133794.0 135516.0 137931.0 138557.3 138578.3 16798.0 17221.9 17373.5 17428.6 17609.6 30907.5 31232.2 31381.4 31486.7 31679.7 8481.6 8558.5 8595.4 8637.4 8660.5 3749.2 3791.9 3855.6 3863.5 3894.1 5251.1 5343.5 5368.6 5370.4 5370.4 972.2 995.5 996.3 1015.9 1018.1 626.2 626.2 639.5 659.1 662.0 10934.8 11067.8 11249.6 11492.1 11654.4 649.2 667.8 674.7 687.5 689.9 5347.3 5394.8 5402.9 5441.4 5574.1 118215.0 119304.4 119445.0 120614.0 125621.4 101028.0 103665.0 105656.0 105962.5 106148.0 88628.0 91583.5 93707.5 93853.5 95418.5 10043.8 10100.0 10102.7 10144.0 10181.6 43087.0 43284.4 43628.6 45291.5 45772.3 55712.4 58396.0 58477.2 58482.1 58492.8 15024.8 15101.5 15147.1 15172.1 15640.8 16058.2 16139.4 16266.4 16366.3 16458.7 15835.0 16211.8 16316.0 16409.7 16506.3 15369.2 15389.6 15432.7 15476.0 15666.1 14703.7 14862.9 15166.0 15169.8 15245.5 11437.4 11540.5 11787.0 11806.1 11897.3 10679.9 10734.3 10775.5 10790.6 10912.8 18:28:05 INFO - PID 10764 | -------- Summary: end -------- 18:28:05 INFO - PID 10764 | 18:28:06 INFO - TEST-INFO | 10764: exit 0 18:28:06 INFO - TEST-OK | dromaeo_css | took 1112410ms 18:28:06 INFO - TEST-START | kraken 18:28:06 INFO - Initialising browser for kraken test... 18:28:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51048/getInfo.html -profile /tmp/tmpm9ioi5/profile 18:28:06 INFO - TEST-INFO | started process 11152 (/builds/slave/test/build/application/firefox/firefox http://localhost:51048/getInfo.html) 18:28:11 INFO - TEST-INFO | 11152: exit 0 18:28:11 INFO - Browser initialized. 18:28:11 INFO - Running cycle 1/1 for kraken test... 18:28:11 INFO - Using env: {'DISPLAY': ':0', 18:28:11 INFO - 'HOME': '/home/cltbld', 18:28:11 INFO - 'JSGC_DISABLE_POISONING': '1', 18:28:11 INFO - 'LANG': 'en_US.UTF-8', 18:28:11 INFO - 'LANGUAGE': 'en_US:en', 18:28:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:28:11 INFO - 'LOGNAME': 'cltbld', 18:28:11 INFO - 'MAIL': '/var/mail/cltbld', 18:28:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:28:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:28:11 INFO - 'MOZ_ACCELERATED': '1', 18:28:11 INFO - 'MOZ_CRASHREPORTER': '1', 18:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:28:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:28:11 INFO - 'MOZ_NO_REMOTE': '1', 18:28:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:28:11 INFO - 'MOZ_WEBRENDER': '1', 18:28:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:28:11 INFO - 'NO_EM_RESTART': '1', 18:28:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:28:11 INFO - 'PWD': '/builds/slave/test', 18:28:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:28:11 INFO - 'RUST_BACKTRACE': 'full', 18:28:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:28:11 INFO - 'SHELL': '/bin/bash', 18:28:11 INFO - 'SHLVL': '1', 18:28:11 INFO - 'TERM': 'linux', 18:28:11 INFO - 'TMOUT': '86400', 18:28:11 INFO - 'USER': 'cltbld', 18:28:11 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851', 18:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:28:11 INFO - '_': '/tools/buildbot/bin/python'} 18:28:11 INFO - TEST-INFO | started process 11393 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm9ioi5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:28:12 INFO - PID 11393 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:28:12 INFO - PID 11393 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 18:28:13 INFO - PID 11393 | 18:28:13 INFO - PID 11393 | (/builds/slave/test/build/application/firefox/firefox:11452): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:28:13 INFO - PID 11393 | 18:28:23 INFO - PID 11393 | RSS: Main: 195686400 18:28:23 INFO - PID 11393 | 18:28:26 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51048/tests/kraken/driver.html?testName=audio-beat-detection) 18:28:27 INFO - PID 11393 | RSS: Main: 192323584 18:28:27 INFO - PID 11393 | 18:28:29 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51048/tests/kraken/driver.html?testName=audio-dft) 18:28:30 INFO - PID 11393 | RSS: Main: 191631360 18:28:30 INFO - PID 11393 | 18:28:33 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51048/tests/kraken/driver.html?testName=audio-fft) 18:28:34 INFO - PID 11393 | RSS: Main: 188923904 18:28:34 INFO - PID 11393 | 18:28:36 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51048/tests/kraken/driver.html?testName=audio-oscillator) 18:28:36 INFO - PID 11393 | RSS: Main: 189526016 18:28:36 INFO - PID 11393 | 18:28:39 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51048/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:28:39 INFO - PID 11393 | RSS: Main: 189739008 18:28:39 INFO - PID 11393 | 18:28:42 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51048/tests/kraken/driver.html?testName=imaging-darkroom) 18:28:43 INFO - PID 11393 | RSS: Main: 191840256 18:28:43 INFO - PID 11393 | 18:28:47 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51048/tests/kraken/driver.html?testName=imaging-desaturate) 18:28:47 INFO - PID 11393 | RSS: Main: 191770624 18:28:47 INFO - PID 11393 | 18:28:51 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51048/tests/kraken/driver.html?testName=json-parse-financial) 18:28:51 INFO - PID 11393 | RSS: Main: 192057344 18:28:51 INFO - PID 11393 | 18:28:53 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51048/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:28:53 INFO - PID 11393 | RSS: Main: 192303104 18:28:53 INFO - PID 11393 | 18:28:55 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51048/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:28:56 INFO - PID 11393 | RSS: Main: 189394944 18:28:56 INFO - PID 11393 | 18:28:58 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51048/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:28:59 INFO - PID 11393 | RSS: Main: 189116416 18:28:59 INFO - PID 11393 | 18:29:01 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51048/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:29:02 INFO - PID 11393 | RSS: Main: 190406656 18:29:02 INFO - PID 11393 | 18:29:05 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51048/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:29:05 INFO - PID 11393 | RSS: Main: 189976576 18:29:05 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | Cycle 1(1): loaded http://localhost:51048/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51048/tests/kraken/driver.html?testName=ai-astar) 18:29:07 INFO - PID 11393 | RSS: Main: 191266816 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | __start_tp_report 18:29:07 INFO - PID 11393 | _x_x_mozilla_page_load 18:29:07 INFO - PID 11393 | _x_x_mozilla_page_load_details 18:29:07 INFO - PID 11393 | |i|pagename|runs| 18:29:07 INFO - PID 11393 | |0;ai-astar;118;110;109;107;102;110;110;115;120;118 18:29:07 INFO - PID 11393 | |1;audio-beat-detection;120;117;118;115;116;147;125;121;120;124 18:29:07 INFO - PID 11393 | |2;audio-dft;168;173;165;167;164;182;168;174;165;165 18:29:07 INFO - PID 11393 | |3;audio-fft;80;76;82;77;84;75;77;75;90;77 18:29:07 INFO - PID 11393 | |4;audio-oscillator;80;85;79;79;84;79;85;79;89;85 18:29:07 INFO - PID 11393 | |5;imaging-gaussian-blur;115;114;114;113;115;114;118;115;115;116 18:29:07 INFO - PID 11393 | |6;imaging-darkroom;149;154;161;151;155;158;150;152;155;149 18:29:07 INFO - PID 11393 | |7;imaging-desaturate;104;102;95;98;95;94;95;94;95;97 18:29:07 INFO - PID 11393 | |8;json-parse-financial;53;56;55;63;55;52;55;58;54;53 18:29:07 INFO - PID 11393 | |9;json-stringify-tinderbox;49;41;46;46;47;38;38;45;48;39 18:29:07 INFO - PID 11393 | |10;stanford-crypto-aes;78;81;74;73;74;76;80;80;74;80 18:29:07 INFO - PID 11393 | |11;stanford-crypto-ccm;112;120;123;116;117;121;115;123;119;108 18:29:07 INFO - PID 11393 | |12;stanford-crypto-pbkdf2;170;168;176;175;168;167;167;176;168;169 18:29:07 INFO - PID 11393 | |13;stanford-crypto-sha256-iterative;66;64;66;68;65;75;67;65;65;64 18:29:07 INFO - PID 11393 | __end_tp_report 18:29:07 INFO - PID 11393 | __start_cc_report 18:29:07 INFO - PID 11393 | _x_x_mozilla_cycle_collect,748 18:29:07 INFO - PID 11393 | __end_cc_report 18:29:07 INFO - PID 11393 | __startTimestamp1506475747932__endTimestamp 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | ------- Summary: start ------- 18:29:07 INFO - PID 11393 | Number of tests: 14 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.50 stddev:5.69 (5.1%) stddev-sans-first:5.59 18:29:07 INFO - PID 11393 | Values: 118.0 110.0 109.0 107.0 102.0 110.0 110.0 115.0 120.0 118.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#1] audio-beat-detection Cycles:10 Average:122.30 Median:120.50 stddev:9.26 (7.7%) stddev-sans-first:9.79 18:29:07 INFO - PID 11393 | Values: 120.0 117.0 118.0 115.0 116.0 147.0 125.0 121.0 120.0 124.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#2] audio-dft Cycles:10 Average:169.10 Median:168.00 stddev:5.67 (3.4%) stddev-sans-first:6.00 18:29:07 INFO - PID 11393 | Values: 168.0 173.0 165.0 167.0 164.0 182.0 168.0 174.0 165.0 165.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#3] audio-fft Cycles:10 Average:79.30 Median:78.50 stddev:4.81 (6.1%) stddev-sans-first:5.09 18:29:07 INFO - PID 11393 | Values: 80.0 76.0 82.0 77.0 84.0 75.0 77.0 75.0 90.0 77.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:84.50 stddev:3.63 (4.3%) stddev-sans-first:3.74 18:29:07 INFO - PID 11393 | Values: 80.0 85.0 79.0 79.0 84.0 79.0 85.0 79.0 89.0 85.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:115.00 stddev:1.37 (1.2%) stddev-sans-first:1.45 18:29:07 INFO - PID 11393 | Values: 115.0 114.0 114.0 113.0 115.0 114.0 118.0 115.0 115.0 116.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#6] imaging-darkroom Cycles:10 Average:153.40 Median:154.50 stddev:3.98 (2.6%) stddev-sans-first:3.89 18:29:07 INFO - PID 11393 | Values: 149.0 154.0 161.0 151.0 155.0 158.0 150.0 152.0 155.0 149.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:95.00 stddev:3.48 (3.7%) stddev-sans-first:2.57 18:29:07 INFO - PID 11393 | Values: 104.0 102.0 95.0 98.0 95.0 94.0 95.0 94.0 95.0 97.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.00 stddev:3.17 (5.8%) stddev-sans-first:3.24 18:29:07 INFO - PID 11393 | Values: 53.0 56.0 55.0 63.0 55.0 52.0 55.0 58.0 54.0 53.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#9] json-stringify-tinderbox Cycles:10 Average:43.70 Median:46.00 stddev:4.27 (9.3%) stddev-sans-first:4.08 18:29:07 INFO - PID 11393 | Values: 49.0 41.0 46.0 46.0 47.0 38.0 38.0 45.0 48.0 39.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:79.00 stddev:3.13 (4.0%) stddev-sans-first:3.30 18:29:07 INFO - PID 11393 | Values: 78.0 81.0 74.0 73.0 74.0 76.0 80.0 80.0 74.0 80.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#11] stanford-crypto-ccm Cycles:10 Average:117.40 Median:119.50 stddev:4.84 (4.0%) stddev-sans-first:4.72 18:29:07 INFO - PID 11393 | Values: 112.0 120.0 123.0 116.0 117.0 121.0 115.0 123.0 119.0 108.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.40 Median:169.50 stddev:3.75 (2.2%) stddev-sans-first:3.97 18:29:07 INFO - PID 11393 | Values: 170.0 168.0 176.0 175.0 168.0 167.0 167.0 176.0 168.0 169.0 18:29:07 INFO - PID 11393 | 18:29:07 INFO - PID 11393 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.00 stddev:3.24 (4.9%) stddev-sans-first:3.43 18:29:07 INFO - PID 11393 | Values: 66.0 64.0 66.0 68.0 65.0 75.0 67.0 65.0 65.0 64.0 18:29:07 INFO - PID 11393 | -------- Summary: end -------- 18:29:07 INFO - PID 11393 | 18:29:08 INFO - PID 11393 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:29:08 INFO - TEST-INFO | 11393: exit 0 18:29:08 INFO - TEST-OK | kraken | took 62684ms 18:29:08 INFO - SUITE-END | took 1175s 18:29:09 INFO - Completed test suite (00:19:35) 18:29:09 INFO - javascript benchmark 18:29:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8396.552000062624, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3057.526083457065, 3072.8924487642935, 3080.2145452909504, 3093.9845300773495, 3101.4417404867113, 5923.0, 6058.303295121063, 6068.211132552773, 6068.332483426825, 6173.38266173383, 6018.518518518508, 6087.269527656687, 6087.78692745754, 6106.511479081674, 6110.694465276741, 5626.240457538221, 5644.153376993463, 5648.152777083429, 5663.3204015518195, 5719.027765279891, 5659.405762394951, 5701.059325211356, 5702.629329093616, 5734.369219385864, 5793.4785869271755, 5658.207850900857, 5666.0, 5721.370649228582, 5740.339860915986, 5797.594168408213, 5491.28613280271, 5638.407967163471, 5740.54075673945, 5752.36723960364, 5784.826455206308, 5539.833804985857, 5589.385167631557, 5627.240322556443, 5650.943490565124, 5706.743196556124, 6049.183360246355, 6080.574359794816, 6127.5710700251, 6150.7847225346895, 6217.56477046607, 6349.873002539969, 6492.707828147744, 6501.187351581052, 6550.639714815683, 6585.407313341774, 6416.486681065504, 6433.613983161025, 6460.257070436913, 6482.481401487869, 6556.311587283314, 6038.939610603926, 6081.878362432726, 6159.692015399212, 6182.381761823826, 6205.503559715212, 6630.900536491957, 6750.932490675129, 6779.220389655253, 6784.0, 6828.317168283232, 6339.0, 6422.550421470546, 6431.517636177306, 6442.871142577123, 6516.2506311773805, 6332.968335158294, 6393.264774550986, 6396.328385519547, 6405.19935008124, 6440.967795160994, 6495.447886929664, 6511.576747511396, 6540.9672951635885, 6565.113709649232, 6573.211214654272, 6441.1626488556185, 6457.483401327882, 6572.441342485942, 6593.76921807744, 6608.041833934052, 5094.668846525038, 5138.306328645585, 5178.0, 5235.450277720861, 5239.580833533309, 4501.212287849678, 4506.59440650343, 4510.233260345749, 4591.839285625054, 4613.215753321943, 5674.801381951612, 5677.091665333527, 5684.0, 5690.061139911868, 5701.914471282934, 6579.703913323912, 6583.341665833378, 6658.4340331071435, 6669.699863506154, 6670.799876003728, 5612.242347283146, 5622.775088996476, 5634.605577609528, 5681.176229446707, 5682.573806964411, 5557.138643510262, 5559.63862348946, 5602.915956260659, 5651.321841378978, 5655.5475561954945, 6238.500919926395, 6291.0, 6329.0, 6335.334789847092, 6406.199225096863, 6501.609903405905, 6570.244421891543, 6586.604803711697, 6620.702068406837, 6634.436072933855, 6275.152854364603, 6288.905666415099, 6373.936260637334, 6389.456896163878, 6402.743890244338, 6372.139761132188, 6422.646754428364, 6453.0, 6541.280459149585, 6545.345465453416, 6409.647469389229, 6512.511561632802, 6547.901781473186, 6551.082848401133, 6627.37039981201, 6227.9377206227355, 6257.4681152102585, 6267.592606480564, 6290.339514350927, 6350.079238510298, 6233.314335423191, 6244.2819075806865, 6280.214973128359, 6305.274893387319, 6320.62076275425, 6259.405356491126, 6276.215473065867, 6324.430801227728, 6352.618842869442, 6379.457746091634, 6228.22147231596, 6380.393862583047, 6446.29090800021, 6480.57876238043, 6518.282988871315, 6148.508119350352, 6174.845628859314, 6190.0, 6215.0, 6215.192025036717], "value": 5962.758231882834, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [864.2351518905766, 875.964961401544, 897.4391005621485, 899.3389858454035, 900.0774206438404, 37706.0, 37891.621083789185, 38394.616053839454, 41020.0, 41416.55042073528, 37561.43657845134, 37632.0, 37771.433428498596, 37785.81107094468, 37891.0, 7119.89320160198, 7222.674979625929, 7259.092613423322, 7763.767086987399, 7947.205279472064, 7848.018997625297, 7874.330681892046, 7915.4063445241545, 7929.207079292053, 7976.322012628933, 7861.3317867981, 7870.606469676522, 7871.488353257048, 7927.128015918244, 7979.800504987364, 7812.570308633023, 7847.882281765778, 7921.643526041342, 7934.880976785353, 7966.0839003514175, 7768.262015108556, 7784.649690763929, 7801.0638723352995, 7845.490043147177, 7846.960765196138, 36565.634343656486, 36847.0, 37110.25779484422, 37192.44211336832, 37239.81380093109, 3572.821358932069, 3591.191981804099, 3613.927721445583, 3616.168281295317, 3622.166901612644, 30774.84612576945, 31057.378852422826, 31124.06627801147, 31200.37599248002, 31303.53044704331, 4976.825811096631, 5254.6847189168775, 5274.4198138204765, 5300.072487314705, 5319.281896943901, 178458.0, 178807.10596447065, 178833.1058344713, 179022.10488947603, 180078.0, 41470.37794433086, 47132.764336178785, 47134.05731885343, 47232.291515627294, 47402.52597473981, 43554.128917421476, 48743.75628121907, 49179.0, 49648.0, 49652.50347496479, 15119.319630616648, 15135.167565783988, 15332.15673137988, 15349.9232503839, 15389.153596552296, 12568.685782855318, 12609.117361784587, 12646.683832904251, 12689.36553172356, 12741.490340386466, 13127.277999709917, 13200.273984930729, 13290.20258784476, 13337.73324533504, 13489.66275843092, 5486.259354987105, 5494.478024587658, 5588.413216612278, 5629.605927585111, 5693.206639908416, 993.6174572789383, 1013.5489707080426, 1025.5795123999123, 1040.1834559870617, 1047.1465755409317, 22635.773642263695, 23008.0, 23040.423989400064, 23394.06423743065, 23426.414339641644, 1108.551036830069, 1118.6364431559775, 1119.6696974392573, 1127.0701671121358, 1135.4152611405134, 12080.21478603888, 12317.199382040319, 12373.690657733629, 12403.75192496145, 12416.192947458387, 222856.0, 224099.0, 224852.0, 225036.0, 226364.0, 50497.49502505001, 50663.746681266355, 50922.0, 51073.0, 51078.744606276734, 1408.830940287172, 1466.30350583472, 1470.8602682745122, 1472.3006571878486, 1475.6827282134393, 21956.121755129618, 22295.0, 22324.330270091923, 22424.0, 22870.542589148456, 35977.0, 36417.0, 36446.27107457783, 36611.450828237066, 36619.450708238866, 180370.0, 181202.0, 181557.0, 181930.09034954742, 182785.0, 29415.26461838471, 30241.546376803926, 30431.391372172875, 31599.526007109453, 33972.66027339695, 42363.788181058895, 42687.0, 43079.569204307554, 43210.13579728325, 43700.78149609231, 31835.68164318327, 31908.361832762752, 32041.519377208893, 32183.51724724178, 32404.0, 30778.384432311675, 31067.22331941629, 31112.533312000793, 31250.21874453066, 31416.685833141375, 34307.313853722284, 35064.29871402609, 35213.471797923565, 35768.0, 35813.28373432569, 30247.0, 30367.544486833158, 30520.0, 31522.527162093047, 31562.526562102044, 23125.768742313034, 23235.0, 23255.18606848752, 23468.530629387657, 23754.0], "value": 16795.35728597279, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.6902072880645, 573.9611303540592, 575.5223164773238, 581.4040608376414, 581.9098039803829, 21906.671399929015, 22500.774992250073, 22552.097916083338, 22643.0, 22690.319290421274, 24368.390790230253, 24763.13329033484, 24831.50336993259, 24853.875730621367, 26254.737452625468, 6306.4324210820905, 6529.738810447577, 6554.344565543432, 6590.901136482957, 6605.273419923828, 6473.44975677068, 6545.214574251097, 6578.901316480256, 6584.93415065848, 6590.275069742349, 6140.201773769426, 6412.294647588761, 6427.0, 6494.0, 6558.0, 6446.129772480702, 6473.514486413538, 6474.935250647481, 6497.252815926182, 6500.057491663706, 5962.731677074542, 6024.21685180928, 6073.967425537669, 6097.268327800648, 6113.816585502397, 28294.43411131766, 28800.855995720096, 29712.851435742898, 29985.250368740955, 30027.099187024425, 2037.7350944377135, 2086.2176683743596, 2087.039961617658, 2090.1639344262267, 2093.8324934005245, 19338.80661193384, 19341.12964916582, 19698.409047728448, 19736.605267894563, 19739.506512337164, 2322.280093171122, 2370.158593699229, 2423.3820375804235, 2429.842060266095, 2432.416220107179, 123589.38205309006, 131488.34255828755, 131692.0, 133536.33231833778, 143786.56213437836, 14681.58527658698, 14963.476278330207, 14969.0, 15030.455660655562, 15050.473233436776, 22760.089596415957, 23075.423114422272, 23085.653715194287, 23160.884195578914, 23229.9546520407, 9671.323007389416, 9674.0, 9681.70954871355, 9691.176250018827, 10006.349587276804, 8895.599698013604, 8933.910660893438, 8963.417377870417, 8968.372213944962, 9005.414648047856, 9128.0, 9168.404053736485, 9249.537523123818, 9360.4383736976, 9502.144806967406, 2707.5803250496206, 2711.9728802712116, 2753.986230068837, 2796.1751283371455, 2837.6169217155834, 950.9714708558754, 970.9271255262832, 981.5288661442405, 989.4360214677565, 1000.8048430556117, 19048.238070477026, 19186.424407267805, 19521.219151234076, 19554.072016070186, 20007.699884501744, 1012.7518757904361, 1034.425893629043, 1038.657243109772, 1044.6187141693226, 1048.895110488945, 10710.410927399067, 10895.237333386587, 10980.670579882617, 10995.615153469591, 11100.444977751242, 459893.0, 464155.0, 466716.6664166725, 473880.0, 484865.0, 112117.0, 113748.43125784319, 114494.42752786183, 115258.0, 115399.0, 1147.363213416545, 1167.8656954450178, 1203.2419575667273, 1228.3060071059822, 1240.0699475393455, 21222.787772122698, 21344.252951146635, 21529.246476373253, 21779.891100545028, 21842.235521756662, 57177.428225718875, 58013.709931450074, 58379.0, 58623.70688146703, 59560.0, 69409.65295173491, 70972.0, 72164.63917680379, 73462.26537734554, 73758.63120684362, 14773.0, 14959.102453852369, 15116.54650360491, 15134.545963620669, 15137.545873623809, 21935.122595096018, 22790.974406151432, 22969.310920672408, 23045.07819687194, 23424.765752342257, 18408.0, 18526.536836579195, 18583.72124418162, 18738.531536711693, 18774.71837922405, 16310.429134980219, 16490.58773530671, 16785.0, 16882.831171688125, 16892.493225203263, 18984.52538686544, 19052.809471905657, 19059.90470047641, 19339.90330048341, 19377.128029238436, 14262.07296525764, 14740.262986850828, 14867.405303787727, 14889.0, 14919.32863021145, 18033.639327213645, 18303.35938242155, 18311.90844045771, 18522.0738963054, 18542.072896354857], "value": 11999.781826635219, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.7164823738776, 1385.106606238976, 1388.3544151969331, 1388.791681247812, 1398.9999999999993, 15479.845201547996, 15983.760243596354, 16092.51722448327, 16187.109708966002, 16199.0, 15502.534923952271, 15644.921775391136, 15657.217139143026, 15702.0, 15704.607384815357, 2680.32991752062, 2723.7684796792296, 2752.8073034887566, 2767.681716602586, 2789.4002789400274, 2617.397998460355, 2698.568229083347, 2721.687005994306, 2729.5223335916235, 2731.549294366427, 2461.950760984779, 2584.470183612355, 2595.2603508000225, 2597.467519158568, 2654.9601755973677, 2692.4480481501246, 2718.768904643098, 2753.9586906196423, 2756.2144788735213, 2760.8205466644704, 2626.4484458263787, 2654.9867250663815, 2661.840289582612, 2667.6398686177504, 2673.6791585009923, 14758.483453079198, 15060.698786024219, 15086.321115549827, 15100.39598416073, 15124.848751512563, 8098.433109682324, 8415.242628163434, 8416.579171041485, 8438.535880526568, 8446.23983841451, 4990.750462476862, 5198.610104242197, 5217.791288348461, 5311.097113490716, 5330.706811125387, 1756.192151610261, 1757.8593712502968, 1762.189392879277, 1792.3010026089835, 1808.9095545222685, 27273.863630681917, 27366.315842103908, 27370.178894633194, 27493.9999999998, 27534.724652753415, 6628.5360024798765, 6684.2647308795995, 6723.831904202434, 6873.5875847449315, 6903.447724182053, 17676.734848977274, 17716.645667086585, 17726.645467090842, 17731.0, 17753.556161096076, 7657.808554786064, 7686.961565192139, 7748.961255193688, 7805.41459390548, 7816.8827467588035, 6958.860822783516, 7115.964420177969, 7138.964305178441, 7348.816279593053, 7381.557106573516, 5162.586993040548, 5274.208868669731, 5319.547838433699, 5348.839534813962, 5395.892082158413, 1403.4456389726001, 1462.1300326305832, 1529.7170023545457, 1558.7350150474447, 1570.1128862192825, 861.4701958295728, 893.0801274687083, 916.8212198621205, 921.5853664624902, 932.3100905330007, 8386.496810191407, 8514.191151840565, 8602.35482338827, 8683.609237584324, 8762.430442021248, 890.2166093837511, 999.2355847776457, 1004.9397036177853, 1009.2128140050772, 1018.2414101494434, 5050.722210278396, 5133.383993920679, 5170.896582068338, 5253.842384728464, 5480.725963701799, 21094.0, 22216.666749998763, 22218.88890555537, 22223.333300001024, 22224.444388890082, 21295.574088518453, 21678.89160554187, 21946.780532194793, 22152.446188845406, 22687.52531085474, 10250.487475626189, 10317.587296508205, 10413.11488523484, 10425.165986721044, 10528.0, 8277.296429803535, 8344.666213351398, 8361.247487726134, 8361.623726932181, 8409.327253819558, 18309.0, 19056.714149288047, 19083.0, 19103.0, 19461.221551137794, 19698.90150549238, 19887.502812429804, 19963.60072798565, 20116.798832011493, 20438.386848394573, 11005.394703291397, 11013.229073964745, 11110.222284440011, 11123.888761112285, 11142.387168705798, 13777.517786877812, 13924.443022278996, 14064.718705626035, 14072.577822665336, 14115.647108822362, 11254.38100904458, 11301.943490282496, 11312.886871131182, 11514.19400641947, 11653.242539234923, 10961.945190273998, 11065.391403472886, 11267.830982535104, 11274.492647830702, 11397.943010284895, 9125.589348479467, 9143.63425462974, 9168.679096231599, 9177.862332064891, 9203.76990575241, 7324.230955749569, 7355.1541572719825, 7360.484766066324, 7415.1101867776215, 7522.022137122139, 7185.784426467214, 7328.743493977685, 7387.852242955219, 7408.851822963618, 7497.737579184703], "value": 6686.0349356217575, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.4857348357706, 505.3985756949229, 528.9523942845148, 532.4223217808677, 536.9060414427477, 5964.598319394939, 6123.693815309239, 6126.0, 6130.417610327017, 6135.32511423743, 6020.621854367522, 6046.697665116749, 6075.238180927537, 6080.452759251666, 6105.6947152642415, 4829.492903245161, 4833.927491087637, 4856.198727210024, 4858.975705121487, 4903.607711383099, 4775.2598347256235, 4944.381952255968, 4954.7522623868845, 4972.25416187573, 4988.675736077162, 4880.438749543795, 4903.239997800347, 4928.728919909404, 4959.677620954644, 4984.0, 4849.127157111719, 4914.557689807899, 4926.507349265081, 4935.259711043336, 4981.750912454381, 4431.711938723973, 4440.600345968846, 4445.844395446144, 4457.576530229623, 4473.865784026484, 5979.282486101695, 5979.701014949279, 6023.277206735176, 6023.45788879003, 6073.665948372882, 5645.491905728504, 5658.773649054033, 5666.5183459406, 5690.374058853523, 5701.116326969326, 6378.170837791057, 6394.904076438857, 6473.414013566659, 6479.514036447238, 6537.509686773511, 4743.786529606176, 4782.832600858953, 4789.736564488951, 4823.2282834746275, 4849.709017458964, 6727.55981052503, 6761.087253220752, 6796.966015169942, 6801.693923773442, 6819.693113809891, 4932.728699921467, 4948.455669876311, 5022.824201152942, 5038.521340472649, 5058.114829904752, 5031.465403052104, 5078.619103567247, 5081.364829396325, 5103.157978933435, 5116.692998420107, 5597.552195824324, 5616.0, 5627.662340259516, 5642.492175704207, 5700.857478563069, 6484.0, 6544.803655890331, 6580.243272023683, 6586.73653053882, 6591.373819487141, 1647.4398704440548, 1662.5178698177635, 1670.2567357526027, 1742.6427582345589, 1770.9468715938542, 1208.4139192491634, 1212.902967762577, 1225.5477259447582, 1249.4939549482292, 1255.0900597067052, 5456.590755693339, 5544.722763861791, 5605.859853503695, 5618.353889302701, 5618.85952851182, 6315.810525684236, 6364.236291645032, 6494.870102597922, 6548.869022619521, 6591.242007169141, 5564.944350556442, 5602.159676048625, 5609.382967873531, 5633.155026745939, 5642.379338272786, 3463.6882680558365, 3496.7727097739075, 3504.351694936445, 3517.648235176513, 3541.13242255649, 6314.873702525923, 6317.96841015792, 6331.778387756406, 6353.5870168438905, 6358.968205158944, 6571.817072926919, 6580.638064906476, 6755.459563234929, 6759.073664005735, 6762.425193858478, 6220.813375598739, 6311.274203466476, 6329.556931014877, 6344.936550634527, 6350.872982540323, 6430.453411460078, 6528.661624366899, 6627.304133065959, 6641.070250164884, 6646.90029649546, 6356.650384228727, 6397.712102955285, 6425.421712045938, 6478.838029049311, 6490.0, 6224.813255602339, 6240.594361366497, 6259.655718935503, 6268.717907694074, 6281.0, 4874.683145595525, 4885.487023862585, 4934.457209706858, 4937.308776771183, 4950.306957026091, 6439.227292724903, 6441.3880681335195, 6465.418112369909, 6482.481401487776, 6593.967030164818, 6201.503879689704, 6204.81385558434, 6228.501719862491, 6289.182406287154, 6345.238571371465, 6347.270063942706, 6401.743930242925, 6404.871902562016, 6411.4229719325485, 6454.354564543696, 5975.342712301556, 6208.161898143693, 6265.216847894013, 6271.96864015677, 6371.458426033653, 5821.825345239649, 6305.873882522232, 6337.651429171256, 6365.0, 6381.649009304533, 6154.415330543591, 6217.689115544114, 6223.159873417031, 6235.532335074797, 6293.181886354744], "value": 4958.746021339087, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [861.9353548483865, 892.3218354050921, 906.7370462565856, 909.4634165842156, 921.8525035994238, 19243.807561924375, 20309.593808123827, 20318.08568614412, 20348.796512034874, 20362.89818550909, 18313.90843045786, 19018.0, 19247.133878975445, 19283.325083622076, 19394.903025484888, 952.2715122930946, 959.3045042344314, 961.3703024518951, 962.5091203486203, 966.6954909203579, 974.1524873360187, 976.1409959235898, 977.2524019125375, 979.6718099436697, 986.1765425869407, 969.7284760267137, 1014.9492525373739, 1025.5333823110466, 1038.7662775875442, 1041.661460025491, 1005.3515482513737, 1005.9949700251489, 1013.4020927652647, 1014.0569270578359, 1014.1734486393566, 740.6111791309552, 742.4172024960434, 744.5049042386806, 745.1021519069508, 746.0562388578453, 20786.168553257852, 21061.368158955254, 21071.894640526698, 21200.78799212019, 21242.0, 22177.556448871095, 22275.220367287227, 22715.432114197112, 22875.771242287698, 22968.77031229683, 17416.912915435467, 17442.47672569825, 17527.824721752746, 17534.386296479563, 17615.5596110097, 3761.5298087739034, 3845.9230815383817, 3846.519185101862, 3848.76907385555, 3850.4609354690365, 133794.0, 135516.0, 137931.0, 138557.3072134633, 138578.30710846483, 16798.0, 17221.913890430467, 17373.47879563615, 17428.564285892953, 17609.55976100582, 30907.536386954213, 31232.21919452032, 31381.372372552876, 31486.685133148832, 31679.683203167675, 8481.575921203916, 8558.486490810572, 8595.3553483489, 8637.395382323177, 8660.523671198018, 3749.1751814600743, 3791.9241615167543, 3855.614438556178, 3863.5363756349416, 3894.084890050836, 5251.10731175701, 5343.465653434625, 5368.624196306299, 5370.409254981949, 5370.409254981949, 972.2173650211506, 995.4922989275377, 996.3274789517047, 1015.94920253987, 1018.0837246478229, 626.1828314050208, 626.2109741725459, 639.4916041746823, 659.1398225316009, 661.9768308109193, 10934.781304373868, 11067.833982490269, 11249.606263780728, 11492.138089643144, 11654.417279136009, 649.2274193709565, 667.7829705345684, 674.6592970549939, 687.4534744877834, 689.917209934811, 5347.3048503695045, 5394.757235924393, 5402.864928376744, 5441.428649991773, 5574.052411090124, 118215.0, 119304.40347798205, 119445.0, 120614.0, 125621.37189313995, 101028.0, 103665.0, 105656.0, 105962.47018764856, 106148.0, 88628.0, 91583.54208228916, 93707.53146234225, 93853.53073234453, 95418.5229073864, 10043.799124017625, 10100.0, 10102.696919092145, 10144.036316549917, 10181.592736290466, 43087.0, 43284.35073473837, 43628.56371436372, 45291.54708453005, 45772.31341529813, 55712.44287557235, 58396.0, 58477.24568262959, 58482.12276815936, 58492.83014339775, 15024.849751502783, 15101.47144849925, 15147.091174529123, 15172.089674619558, 15640.765388518954, 16058.197090145213, 16139.354425822836, 16266.430674926321, 16366.263518141473, 16458.670826583642, 15835.0, 16211.756823647418, 16316.0, 16409.67180656356, 16506.33974641001, 15369.231538423257, 15389.614934655934, 15432.691346173238, 15476.0, 15666.138362390178, 14703.705925881208, 14862.925685371503, 15166.0, 15169.848301516844, 15245.542633721006, 11437.370944597795, 11540.538378464767, 11787.0, 11806.055515558563, 11897.286162830258, 10679.946600266949, 10734.302270352402, 10775.461226938465, 10790.622328218475, 10912.781744365227], "value": 8794.864031824938, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1460.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 110.0, 109.0, 107.0, 102.0, 110.0, 110.0, 115.0, 120.0, 118.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 118.0, 115.0, 116.0, 147.0, 125.0, 121.0, 120.0, 124.0], "value": 122.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 173.0, 165.0, 167.0, 164.0, 182.0, 168.0, 174.0, 165.0, 165.0], "value": 169.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 82.0, 77.0, 84.0, 75.0, 77.0, 75.0, 90.0, 77.0], "value": 79.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 85.0, 79.0, 79.0, 84.0, 79.0, 85.0, 79.0, 89.0, 85.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 113.0, 115.0, 114.0, 118.0, 115.0, 115.0, 116.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 154.0, 161.0, 151.0, 155.0, 158.0, 150.0, 152.0, 155.0, 149.0], "value": 153.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 102.0, 95.0, 98.0, 95.0, 94.0, 95.0, 94.0, 95.0, 97.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 56.0, 55.0, 63.0, 55.0, 52.0, 55.0, 58.0, 54.0, 53.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 41.0, 46.0, 46.0, 47.0, 38.0, 38.0, 45.0, 48.0, 39.0], "value": 43.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 81.0, 74.0, 73.0, 74.0, 76.0, 80.0, 80.0, 74.0, 80.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 120.0, 123.0, 116.0, 117.0, 121.0, 115.0, 123.0, 119.0, 108.0], "value": 117.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 176.0, 175.0, 168.0, 167.0, 167.0, 176.0, 168.0, 169.0], "value": 170.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 68.0, 65.0, 75.0, 67.0, 65.0, 65.0, 64.0], "value": 66.5, "unit": "score"}]}]} 18:29:09 INFO - Return code: 0 18:29:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:29:09 INFO - # TBPL SUCCESS # 18:29:09 INFO - Running post-action listener: _package_coverage_data 18:29:09 INFO - Running post-action listener: _resource_record_post_action 18:29:09 INFO - [mozharness: 2017-09-27 01:29:09.225814Z] Finished run-tests step (success) 18:29:09 INFO - Running post-run listener: _resource_record_post_run 18:29:09 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 24576; Write bytes: 343453696; Read time: 28; Write time: 228972 18:29:09 INFO - TinderboxPrint: CPU usage
13.2% 18:29:09 INFO - TinderboxPrint: I/O read bytes / time
24,576 / 28 18:29:09 INFO - TinderboxPrint: I/O write bytes / time
343,453,696 / 228,972 18:29:09 INFO - TinderboxPrint: CPU idle
8,254.7 (86.8%) 18:29:09 INFO - TinderboxPrint: CPU user
1,199.3 (12.6%) 18:29:09 INFO - TinderboxPrint: Swap in / out
0 / 0 18:29:09 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10190848; Read time: 0; Write time: 640 18:29:09 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:09 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 20480; Write bytes: 333262848; Read time: 12; Write time: 228332 18:29:09 INFO - Running post-run listener: _upload_blobber_files 18:29:09 INFO - Blob upload gear active. 18:29:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:29:09 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:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:29:10 INFO - (blobuploader) - INFO - Open directory for files ... 18:29:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:29:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:29:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:11 INFO - (blobuploader) - INFO - Done attempting. 18:29:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:29:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:29:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:12 INFO - (blobuploader) - INFO - Done attempting. 18:29:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:29:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:29:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:20 INFO - (blobuploader) - INFO - Done attempting. 18:29:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:29:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:29:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:21 INFO - (blobuploader) - INFO - Done attempting. 18:29:21 INFO - (blobuploader) - INFO - Iteration through files over. 18:29:21 INFO - Return code: 0 18:29:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:29:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:29:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/447f3b9accdd244dd7fbe63ce7f775aeaccbb4865e1da64afa05b79f373ec234f42768c72dc3fac456ceb74efa2bddabc19c55f446b0dcbfe47e52ae008a0ce1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caf0820310952c3c4865435f2a47ca34ca6c487797a62f7bc0cf3c116ac5099c62902e3c021a7980e63b27460837585cab75ec61f436dc6fe0932aac8b2f2394", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c83966a6b3db9512ab190e602dd86bd0b85a28a4c76929d8cd1923c4bc7eca04f2a6bf49a82443c19d5d10b731772f9a33f72d05b71b06efff03a10487eab0a8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2d1a0ef3cca2202278a271c17b37b69eaab53d13f244e6f10b9718130fcb5d2a618902ba0be1a48def2afb425db43614831009a2542eaf256c5b4de679899a2"} 18:29:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:29:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:29:21 INFO - Contents: 18:29:21 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/447f3b9accdd244dd7fbe63ce7f775aeaccbb4865e1da64afa05b79f373ec234f42768c72dc3fac456ceb74efa2bddabc19c55f446b0dcbfe47e52ae008a0ce1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caf0820310952c3c4865435f2a47ca34ca6c487797a62f7bc0cf3c116ac5099c62902e3c021a7980e63b27460837585cab75ec61f436dc6fe0932aac8b2f2394", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c83966a6b3db9512ab190e602dd86bd0b85a28a4c76929d8cd1923c4bc7eca04f2a6bf49a82443c19d5d10b731772f9a33f72d05b71b06efff03a10487eab0a8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2d1a0ef3cca2202278a271c17b37b69eaab53d13f244e6f10b9718130fcb5d2a618902ba0be1a48def2afb425db43614831009a2542eaf256c5b4de679899a2"} 18:29:21 INFO - Running post-run listener: copy_logs_to_upload_dir 18:29:21 INFO - Copying logs to upload dir... 18:29:21 INFO - mkdir: /builds/slave/test/build/upload/logs 18:29:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.217688 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-26 18:29:21.214580) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 18:29:21.219242) ========= 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=337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/447f3b9accdd244dd7fbe63ce7f775aeaccbb4865e1da64afa05b79f373ec234f42768c72dc3fac456ceb74efa2bddabc19c55f446b0dcbfe47e52ae008a0ce1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caf0820310952c3c4865435f2a47ca34ca6c487797a62f7bc0cf3c116ac5099c62902e3c021a7980e63b27460837585cab75ec61f436dc6fe0932aac8b2f2394", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c83966a6b3db9512ab190e602dd86bd0b85a28a4c76929d8cd1923c4bc7eca04f2a6bf49a82443c19d5d10b731772f9a33f72d05b71b06efff03a10487eab0a8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2d1a0ef3cca2202278a271c17b37b69eaab53d13f244e6f10b9718130fcb5d2a618902ba0be1a48def2afb425db43614831009a2542eaf256c5b4de679899a2"} build_url:https://queue.taskcluster.net/v1/task/XCg3p_MvQCykzqwmBsV7eA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011292 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/sha512/447f3b9accdd244dd7fbe63ce7f775aeaccbb4865e1da64afa05b79f373ec234f42768c72dc3fac456ceb74efa2bddabc19c55f446b0dcbfe47e52ae008a0ce1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/caf0820310952c3c4865435f2a47ca34ca6c487797a62f7bc0cf3c116ac5099c62902e3c021a7980e63b27460837585cab75ec61f436dc6fe0932aac8b2f2394", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c83966a6b3db9512ab190e602dd86bd0b85a28a4c76929d8cd1923c4bc7eca04f2a6bf49a82443c19d5d10b731772f9a33f72d05b71b06efff03a10487eab0a8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2d1a0ef3cca2202278a271c17b37b69eaab53d13f244e6f10b9718130fcb5d2a618902ba0be1a48def2afb425db43614831009a2542eaf256c5b4de679899a2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 18:29:21.250821) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:29:21.251359) ========= 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=337fe3f4b1febe37b85fccc40000019e-1506463103.921693-2128240851 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 18:29:21.335013) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 18:29:21.335522) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 18:29:21.336059) ========= ========= Total master_lag: 0.23 =========