builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-010 starttime: 1503701388.64 results: success (0) revision: bb813dab9832c1b780293edbc5477f920bf0c88e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:48.639028) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:48.639451) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:48.639723) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:48.733293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:48.733618) ========= 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=eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-25 15:49:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 110M=0s 2017-08-25 15:49:48 (110 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127997 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:48.879949) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:48.880276) ========= 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=eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017307 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:48.929740) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-25 15:49:48.933101) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bb813dab9832c1b780293edbc5477f920bf0c88e --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 bb813dab9832c1b780293edbc5477f920bf0c88e --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=eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-25 15:49:48,873 truncating revision to first 12 chars 2017-08-25 15:49:48,873 Setting DEBUG logging. 2017-08-25 15:49:48,873 attempt 1/10 2017-08-25 15:49:48,873 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bb813dab9832?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-25 15:49:50,255 unpacking tar archive at: mozilla-central-bb813dab9832/testing/mozharness/ program finished with exit code 0 elapsedTime=1.694332 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-25 15:49:50.645252) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:50.645574) ========= script_repo_revision: bb813dab9832c1b780293edbc5477f920bf0c88e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:50.645978) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:50.646244) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 15:49:50.658451) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-25 15:49:50.658776) ========= /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=eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:49:50 INFO - MultiFileLogger online at 20170825 15:49:50 in /builds/slave/test 15:49:50 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 15:49:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:49:50 INFO - {'append_to_log': False, 15:49:50 INFO - 'base_work_dir': '/builds/slave/test', 15:49:50 INFO - 'blob_upload_branch': 'Firefox', 15:49:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:49:50 INFO - 'branch': 'Firefox', 15:49:50 INFO - 'buildbot_json_path': 'buildprops.json', 15:49:50 INFO - 'code_coverage': False, 15:49:50 INFO - 'config_files': ('talos/linux_config.py',), 15:49:50 INFO - 'default_actions': ('clobber', 15:49:50 INFO - 'read-buildbot-config', 15:49:50 INFO - 'download-and-extract', 15:49:50 INFO - 'populate-webroot', 15:49:50 INFO - 'create-virtualenv', 15:49:50 INFO - 'install', 15:49:50 INFO - 'setup-mitmproxy', 15:49:50 INFO - 'run-tests'), 15:49:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:49:50 INFO - 'disable_ccov_upload': False, 15:49:50 INFO - 'download_minidump_stackwalk': True, 15:49:50 INFO - 'download_symbols': 'ondemand', 15:49:50 INFO - 'enable_stylo': False, 15:49:50 INFO - 'enable_webrender': False, 15:49:50 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:49:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:49:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:49:50 INFO - 'gecko_profile': False, 15:49:50 INFO - 'gecko_profile_interval': 0, 15:49:50 INFO - 'installer_path': 'installer.exe', 15:49:50 INFO - 'log_level': 'info', 15:49:50 INFO - 'log_name': 'talos', 15:49:50 INFO - 'log_to_console': True, 15:49:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:49:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:49:50 INFO - 'opt_config_files': (), 15:49:50 INFO - 'pip_index': False, 15:49:50 INFO - 'suite': 'dromaeojs-e10s', 15:49:50 INFO - 'system_bits': '32', 15:49:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:49:50 INFO - 'title': 'talos-linux64-ix-010', 15:49:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:49:50 INFO - 'use_talos_json': True, 15:49:50 INFO - 'verify': 'False', 15:49:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:49:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:49:50 INFO - 'work_dir': 'build'} 15:49:50 INFO - [mozharness: 2017-08-25 22:49:50.737670Z] Running clobber step. 15:49:50 INFO - Running pre-action listener: _resource_record_pre_action 15:49:50 INFO - Running main action method: clobber 15:49:50 INFO - rmtree: /builds/slave/test/build 15:49:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:49:52 INFO - Running post-action listener: _resource_record_post_action 15:49:52 INFO - [mozharness: 2017-08-25 22:49:52.276945Z] Finished clobber step (success) 15:49:52 INFO - [mozharness: 2017-08-25 22:49:52.277043Z] Running read-buildbot-config step. 15:49:52 INFO - Running pre-action listener: _resource_record_pre_action 15:49:52 INFO - Running main action method: read_buildbot_config 15:49:52 INFO - Using buildbot properties: 15:49:52 INFO - { 15:49:52 INFO - "project": "", 15:49:52 INFO - "product": "firefox", 15:49:52 INFO - "who": "ffxbld@noreply.mozilla.org", 15:49:52 INFO - "installer_path": "public/build/target.tar.bz2", 15:49:52 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 15:49:52 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:49:52 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 15:49:52 INFO - "stage_platform": "linux64-stylo", 15:49:52 INFO - "basedir": "/builds/slave/test", 15:49:52 INFO - "buildnumber": 105, 15:49:52 INFO - "platform": "ubuntu64_hw_stylo", 15:49:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:49:52 INFO - "slavebuilddir": "test", 15:49:52 INFO - "taskId": "N93mjFU1SvGqc84ZrtPaTg", 15:49:52 INFO - "branch": "mozilla-central", 15:49:52 INFO - "script_repo_revision": "production", 15:49:52 INFO - "revision": "bb813dab9832c1b780293edbc5477f920bf0c88e", 15:49:52 INFO - "slavename": "talos-linux64-ix-010", 15:49:52 INFO - "repo_path": "mozilla-central" 15:49:52 INFO - } 15:49:52 INFO - Finding installer, test and symbols from parent task. 15:49:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N93mjFU1SvGqc84ZrtPaTg'}, attempt #1 15:49:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N93mjFU1SvGqc84ZrtPaTg'}, attempt #1 15:49:53 INFO - Task dependencies: dZXUCCYSRt2PJV-HC3Hz2w 15:49:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w'}, attempt #1 15:49:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 15:49:53 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.test_packages.json 15:49:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.crashreporter-symbols.zip 15:49:53 INFO - Running post-action listener: _resource_record_post_action 15:49:53 INFO - [mozharness: 2017-08-25 22:49:53.679999Z] Finished read-buildbot-config step (success) 15:49:53 INFO - [mozharness: 2017-08-25 22:49:53.680126Z] Running download-and-extract step. 15:49:53 INFO - Running pre-action listener: _resource_record_pre_action 15:49:53 INFO - Running main action method: download_and_extract 15:49:53 INFO - mkdir: /builds/slave/test/build/tests 15:49:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:53 INFO - trying https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.test_packages.json 15:49:53 INFO - Downloading https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:49:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:49:54 INFO - Downloaded 989 bytes. 15:49:54 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:49:54 INFO - Using the following test package requirements: 15:49:54 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:49:54 INFO - u'common': [u'target.common.tests.zip'], 15:49:54 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:49:54 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:49:54 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:49:54 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:49:54 INFO - u'mozbase': [u'target.common.tests.zip'], 15:49:54 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:49:54 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:49:54 INFO - u'web-platform': [u'target.common.tests.zip', 15:49:54 INFO - u'target.web-platform.tests.tar.gz'], 15:49:54 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:49:54 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:49:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip 15:49:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:49:54 INFO - Fetch https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip into memory 15:49:55 INFO - Content-Length response header: 54979320 15:49:55 INFO - Bytes received: 54979320 15:50:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:50:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip 15:50:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:50:06 INFO - Fetch https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.common.tests.zip into memory 15:50:07 INFO - Content-Length response header: 54979320 15:50:07 INFO - Bytes received: 54979320 15:50:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.talos.tests.zip 15:50:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:50:19 INFO - Fetch https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.talos.tests.zip into memory 15:50:20 INFO - Content-Length response header: 14496611 15:50:20 INFO - Bytes received: 14496611 15:50:20 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')]} 15:50:20 INFO - trying https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 15:50:20 INFO - Downloading https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:50:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:50:21 INFO - Downloaded 61183659 bytes. 15:50:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 15:50:21 INFO - mkdir: /builds/slave/test/properties 15:50:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:50:21 INFO - Writing to file /builds/slave/test/properties/build_url 15:50:21 INFO - Contents: 15:50:21 INFO - build_url:https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 15:50:21 INFO - Running post-action listener: _resource_record_post_action 15:50:21 INFO - Running post-action listener: find_tests_for_verification 15:50:21 INFO - Running post-action listener: set_extra_try_arguments 15:50:21 INFO - [mozharness: 2017-08-25 22:50:21.451776Z] Finished download-and-extract step (success) 15:50:21 INFO - [mozharness: 2017-08-25 22:50:21.451873Z] Running populate-webroot step. 15:50:21 INFO - Running pre-action listener: _resource_record_pre_action 15:50:21 INFO - Running main action method: populate_webroot 15:50:21 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:50:21 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 15:50:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:50:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:50:21 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 15:50:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:50:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:50:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:21 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:50:21 INFO - 'talos_options': ['--stylo'], 15:50:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:50:21 INFO - 'tests': ['damp', 'tps']}, 15:50:21 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:50:21 INFO - 'talos_options': ['--stylo'], 15:50:21 INFO - 'tests': ['damp', 'tps']}, 15:50:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:50:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:50:21 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 15:50:21 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:50:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:50:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:50:21 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:50:21 INFO - 'talos_options': ['--stylo'], 15:50:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:50:21 INFO - 'other-e10s': {'tests': ['a11yr', 15:50:21 INFO - 'ts_paint', 15:50:21 INFO - 'tpaint', 15:50:21 INFO - 'sessionrestore', 15:50:21 INFO - 'sessionrestore_many_windows', 15:50:21 INFO - 'sessionrestore_no_auto_restore', 15:50:21 INFO - 'tabpaint']}, 15:50:21 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 15:50:21 INFO - 'tests': ['a11yr', 15:50:21 INFO - 'ts_paint', 15:50:21 INFO - 'tpaint', 15:50:21 INFO - 'sessionrestore', 15:50:21 INFO - 'sessionrestore_many_windows', 15:50:21 INFO - 'sessionrestore_no_auto_restore', 15:50:21 INFO - 'tabpaint']}, 15:50:21 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:50:21 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:50:21 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 15:50:21 INFO - 'tests': ['bloom_basic_singleton']}, 15:50:21 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 15:50:21 INFO - 'tests': ['bloom_basic']}, 15:50:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:50:21 INFO - 'tsvgr_opacity', 15:50:21 INFO - 'tart', 15:50:21 INFO - 'tscrollx', 15:50:21 INFO - 'tsvg_static']}, 15:50:21 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 15:50:21 INFO - 'tests': ['tsvgx', 15:50:21 INFO - 'tsvgr_opacity', 15:50:21 INFO - 'tart', 15:50:21 INFO - 'tscrollx', 15:50:21 INFO - 'tsvg_static']}, 15:50:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:50:21 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:50:21 INFO - 'talos_options': ['--stylo'], 15:50:21 INFO - 'tests': ['tp5o']}, 15:50:21 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:21 INFO - 'talos_options': ['--mitmproxy', 15:50:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:21 INFO - '--firstNonBlankPaint'], 15:50:21 INFO - 'tests': ['tp6_google', 15:50:21 INFO - 'tp6_youtube', 15:50:21 INFO - 'tp6_amazon', 15:50:21 INFO - 'tp6_facebook']}, 15:50:21 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:21 INFO - 'talos_options': ['--stylo', 15:50:21 INFO - '--mitmproxy', 15:50:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:21 INFO - '--firstNonBlankPaint'], 15:50:21 INFO - 'tests': ['tp6_google', 15:50:21 INFO - 'tp6_youtube', 15:50:21 INFO - 'tp6_amazon', 15:50:21 INFO - 'tp6_facebook']}, 15:50:21 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:21 INFO - 'talos_options': ['--stylo', 15:50:21 INFO - '--stylo-threads=1', 15:50:21 INFO - '--mitmproxy', 15:50:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:21 INFO - '--firstNonBlankPaint'], 15:50:21 INFO - 'tests': ['tp6_google', 15:50:21 INFO - 'tp6_youtube', 15:50:21 INFO - 'tp6_amazon', 15:50:21 INFO - 'tp6_facebook']}, 15:50:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:50:21 INFO - 'talos_options': ['--xperf_path', 15:50:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:21 INFO - 'tests': ['tp5n']}, 15:50:21 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:50:21 INFO - 'talos_options': ['--stylo', 15:50:21 INFO - '--xperf_path', 15:50:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:21 INFO - 'tests': ['tp5n']}}} 15:50:21 INFO - Running post-action listener: _resource_record_post_action 15:50:21 INFO - [mozharness: 2017-08-25 22:50:21.466030Z] Finished populate-webroot step (success) 15:50:21 INFO - [mozharness: 2017-08-25 22:50:21.466119Z] Running create-virtualenv step. 15:50:21 INFO - Running pre-action listener: _resource_record_pre_action 15:50:21 INFO - Running main action method: create_virtualenv 15:50:21 INFO - Creating virtualenv /builds/slave/test/build/venv 15:50:21 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:50:21 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:50:21 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:50:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:50:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:50:22 INFO - Installing distribute.............................................................................................................................................................................................done. 15:50:22 INFO - Installing pip...............done. 15:50:22 INFO - Return code: 0 15:50:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:50:22 INFO - Reading from file tmpfile_stdout 15:50:22 INFO - Output received: 15:50:22 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:50:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:50:22 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')]} 15:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:22 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')]} 15:50:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:22 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 0x11741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e2f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e0130>, '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 0x14e1030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e14c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', '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 15:50:22 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 15:50:22 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 15:50:22 INFO - Using env: {'DISPLAY': ':0', 15:50:22 INFO - 'HOME': '/home/cltbld', 15:50:22 INFO - 'LANG': 'en_US.UTF-8', 15:50:22 INFO - 'LANGUAGE': 'en_US:en', 15:50:22 INFO - 'LOGNAME': 'cltbld', 15:50:22 INFO - 'MAIL': '/var/mail/cltbld', 15:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:22 INFO - 'MOZ_NO_REMOTE': '1', 15:50:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:22 INFO - 'NO_EM_RESTART': '1', 15:50:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:22 INFO - 'PWD': '/builds/slave/test', 15:50:22 INFO - 'SHELL': '/bin/bash', 15:50:22 INFO - 'SHLVL': '1', 15:50:22 INFO - 'TERM': 'linux', 15:50:22 INFO - 'TMOUT': '86400', 15:50:22 INFO - 'USER': 'cltbld', 15:50:22 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:22 INFO - '_': '/tools/buildbot/bin/python'} 15:50:23 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:50:23 INFO - Downloading/unpacking pip>=1.5 15:50:23 INFO - Running setup.py egg_info for package pip 15:50:23 INFO - warning: no files found matching 'pip/cacert.pem' 15:50:23 INFO - warning: no files found matching '*.html' under directory 'docs' 15:50:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:50:23 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:50:23 INFO - Installing collected packages: pip 15:50:23 INFO - Found existing installation: pip 1.1 15:50:23 INFO - Uninstalling pip: 15:50:23 INFO - Successfully uninstalled pip 15:50:23 INFO - Running setup.py install for pip 15:50:23 INFO - warning: no files found matching 'pip/cacert.pem' 15:50:23 INFO - warning: no files found matching '*.html' under directory 'docs' 15:50:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:50:23 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:50:23 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:50:23 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:50:23 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:50:23 INFO - Successfully installed pip 15:50:23 INFO - Cleaning up... 15:50:23 INFO - Return code: 0 15:50:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:23 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 0x11741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e2f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e0130>, '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 0x14e1030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e14c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', '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 15:50:23 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 15:50:23 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 15:50:23 INFO - Using env: {'DISPLAY': ':0', 15:50:23 INFO - 'HOME': '/home/cltbld', 15:50:23 INFO - 'LANG': 'en_US.UTF-8', 15:50:23 INFO - 'LANGUAGE': 'en_US:en', 15:50:23 INFO - 'LOGNAME': 'cltbld', 15:50:23 INFO - 'MAIL': '/var/mail/cltbld', 15:50:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:23 INFO - 'MOZ_NO_REMOTE': '1', 15:50:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:23 INFO - 'NO_EM_RESTART': '1', 15:50:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:23 INFO - 'PWD': '/builds/slave/test', 15:50:23 INFO - 'SHELL': '/bin/bash', 15:50:23 INFO - 'SHLVL': '1', 15:50:23 INFO - 'TERM': 'linux', 15:50:23 INFO - 'TMOUT': '86400', 15:50:23 INFO - 'USER': 'cltbld', 15:50:23 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:50:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:23 INFO - '_': '/tools/buildbot/bin/python'} 15:50:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:23 INFO - Downloading/unpacking psutil>=3.1.1 15:50:23 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 15:50:23 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 15:50:23 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 15:50:23 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 15:50:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:50:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:25 INFO - Installing collected packages: psutil 15:50:25 INFO - Running setup.py install for psutil 15:50:25 INFO - building 'psutil._psutil_linux' extension 15:50:25 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 15:50:25 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 15:50:25 INFO - building 'psutil._psutil_posix' extension 15:50:25 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 15:50:25 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 15:50:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:25 INFO - Successfully installed psutil 15:50:25 INFO - Cleaning up... 15:50:25 INFO - Return code: 0 15:50:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:50:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:25 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 0x11741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e2f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e0130>, '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 0x14e1030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e14c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', '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 15:50:25 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 15:50:25 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 15:50:25 INFO - Using env: {'DISPLAY': ':0', 15:50:25 INFO - 'HOME': '/home/cltbld', 15:50:25 INFO - 'LANG': 'en_US.UTF-8', 15:50:25 INFO - 'LANGUAGE': 'en_US:en', 15:50:25 INFO - 'LOGNAME': 'cltbld', 15:50:25 INFO - 'MAIL': '/var/mail/cltbld', 15:50:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:25 INFO - 'MOZ_NO_REMOTE': '1', 15:50:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:25 INFO - 'NO_EM_RESTART': '1', 15:50:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:25 INFO - 'PWD': '/builds/slave/test', 15:50:25 INFO - 'SHELL': '/bin/bash', 15:50:25 INFO - 'SHLVL': '1', 15:50:25 INFO - 'TERM': 'linux', 15:50:25 INFO - 'TMOUT': '86400', 15:50:25 INFO - 'USER': 'cltbld', 15:50:25 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:50:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:25 INFO - '_': '/tools/buildbot/bin/python'} 15:50:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:50:25 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 15:50:25 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 15:50:25 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 15:50:25 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 15:50:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:50:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:50:26 INFO - Installing collected packages: mozsystemmonitor 15:50:26 INFO - Running setup.py install for mozsystemmonitor 15:50:27 INFO - Successfully installed mozsystemmonitor 15:50:27 INFO - Cleaning up... 15:50:27 INFO - Return code: 0 15:50:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:27 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 0x11741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e2f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e0130>, '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 0x14e1030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e14c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', '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 15:50:27 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 15:50:27 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 15:50:27 INFO - Using env: {'DISPLAY': ':0', 15:50:27 INFO - 'HOME': '/home/cltbld', 15:50:27 INFO - 'LANG': 'en_US.UTF-8', 15:50:27 INFO - 'LANGUAGE': 'en_US:en', 15:50:27 INFO - 'LOGNAME': 'cltbld', 15:50:27 INFO - 'MAIL': '/var/mail/cltbld', 15:50:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:27 INFO - 'MOZ_NO_REMOTE': '1', 15:50:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:27 INFO - 'NO_EM_RESTART': '1', 15:50:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:27 INFO - 'PWD': '/builds/slave/test', 15:50:27 INFO - 'SHELL': '/bin/bash', 15:50:27 INFO - 'SHLVL': '1', 15:50:27 INFO - 'TERM': 'linux', 15:50:27 INFO - 'TMOUT': '86400', 15:50:27 INFO - 'USER': 'cltbld', 15:50:27 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:50:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:27 INFO - '_': '/tools/buildbot/bin/python'} 15:50:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:27 INFO - Downloading/unpacking jsonschema==2.5.1 15:50:27 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 15:50:27 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 15:50:27 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 15:50:27 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 15:50:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:50:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:50:28 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 15:50:28 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 15:50:28 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 15:50:28 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 15:50:28 INFO - Downloading functools32-3.2.3-2.tar.gz 15:50:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:50:28 INFO - warning: no files found matching '*.txt' 15:50:28 INFO - no previously-included directories found matching 'build' 15:50:28 INFO - no previously-included directories found matching 'dist' 15:50:28 INFO - no previously-included directories found matching '.git*' 15:50:28 INFO - Installing collected packages: jsonschema, functools32 15:50:28 INFO - Running setup.py install for functools32 15:50:28 INFO - warning: no files found matching '*.txt' 15:50:28 INFO - no previously-included directories found matching 'build' 15:50:28 INFO - no previously-included directories found matching 'dist' 15:50:28 INFO - no previously-included directories found matching '.git*' 15:50:28 INFO - Successfully installed jsonschema functools32 15:50:28 INFO - Cleaning up... 15:50:28 INFO - Return code: 0 15:50:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:50:28 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')]} 15:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:28 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')]} 15:50:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:28 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 0x11741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e2f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e0130>, '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 0x14e1030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e14c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', '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 15:50:28 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 15:50:28 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 15:50:28 INFO - Using env: {'DISPLAY': ':0', 15:50:28 INFO - 'HOME': '/home/cltbld', 15:50:28 INFO - 'LANG': 'en_US.UTF-8', 15:50:28 INFO - 'LANGUAGE': 'en_US:en', 15:50:28 INFO - 'LOGNAME': 'cltbld', 15:50:28 INFO - 'MAIL': '/var/mail/cltbld', 15:50:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:28 INFO - 'MOZ_NO_REMOTE': '1', 15:50:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:28 INFO - 'NO_EM_RESTART': '1', 15:50:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:28 INFO - 'PWD': '/builds/slave/test', 15:50:28 INFO - 'SHELL': '/bin/bash', 15:50:28 INFO - 'SHLVL': '1', 15:50:28 INFO - 'TERM': 'linux', 15:50:28 INFO - 'TMOUT': '86400', 15:50:28 INFO - 'USER': 'cltbld', 15:50:28 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:50:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:28 INFO - '_': '/tools/buildbot/bin/python'} 15:50:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:50:28 INFO - Cleaning up... 15:50:28 INFO - Return code: 0 15:50:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:50:28 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')]} 15:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:28 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')]} 15:50:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:28 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 0x11741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e2f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e0130>, '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 0x14e1030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e14c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', '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 15:50:28 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 15:50:29 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 15:50:29 INFO - Using env: {'DISPLAY': ':0', 15:50:29 INFO - 'HOME': '/home/cltbld', 15:50:29 INFO - 'LANG': 'en_US.UTF-8', 15:50:29 INFO - 'LANGUAGE': 'en_US:en', 15:50:29 INFO - 'LOGNAME': 'cltbld', 15:50:29 INFO - 'MAIL': '/var/mail/cltbld', 15:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:29 INFO - 'MOZ_NO_REMOTE': '1', 15:50:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:29 INFO - 'NO_EM_RESTART': '1', 15:50:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:29 INFO - 'PWD': '/builds/slave/test', 15:50:29 INFO - 'SHELL': '/bin/bash', 15:50:29 INFO - 'SHLVL': '1', 15:50:29 INFO - 'TERM': 'linux', 15:50:29 INFO - 'TMOUT': '86400', 15:50:29 INFO - 'USER': 'cltbld', 15:50:29 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:29 INFO - '_': '/tools/buildbot/bin/python'} 15:50:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:29 INFO - Downloading/unpacking blobuploader==1.2.4 15:50:29 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 15:50:29 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 15:50:29 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 15:50:29 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 15:50:30 INFO - Downloading blobuploader-1.2.4.tar.gz 15:50:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:50:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:50:30 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 15:50:30 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 15:50:30 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 15:50:30 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 15:50:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:50:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:50:30 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 15:50:30 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 15:50:30 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 15:50:30 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 15:50:30 INFO - Downloading docopt-0.6.1.tar.gz 15:50:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:50:30 INFO - Installing collected packages: blobuploader, requests, docopt 15:50:30 INFO - Running setup.py install for blobuploader 15:50:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:50:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:50:31 INFO - Running setup.py install for requests 15:50:31 INFO - Running setup.py install for docopt 15:50:31 INFO - Successfully installed blobuploader requests docopt 15:50:31 INFO - Cleaning up... 15:50:31 INFO - Return code: 0 15:50:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:31 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')]} 15:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:31 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')]} 15:50:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:31 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 0x11741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e2f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e0130>, '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 0x14e1030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e14c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', '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 15:50:31 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 15:50:31 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 15:50:31 INFO - Using env: {'DISPLAY': ':0', 15:50:31 INFO - 'HOME': '/home/cltbld', 15:50:31 INFO - 'LANG': 'en_US.UTF-8', 15:50:31 INFO - 'LANGUAGE': 'en_US:en', 15:50:31 INFO - 'LOGNAME': 'cltbld', 15:50:31 INFO - 'MAIL': '/var/mail/cltbld', 15:50:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:31 INFO - 'MOZ_NO_REMOTE': '1', 15:50:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:31 INFO - 'NO_EM_RESTART': '1', 15:50:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:31 INFO - 'PWD': '/builds/slave/test', 15:50:31 INFO - 'SHELL': '/bin/bash', 15:50:31 INFO - 'SHLVL': '1', 15:50:31 INFO - 'TERM': 'linux', 15:50:31 INFO - 'TMOUT': '86400', 15:50:31 INFO - 'USER': 'cltbld', 15:50:31 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:50:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:31 INFO - '_': '/tools/buildbot/bin/python'} 15:50:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:31 INFO - Running setup.py (path:/tmp/pip-YfmnZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:31 INFO - Running setup.py (path:/tmp/pip-L5baJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:31 INFO - Running setup.py (path:/tmp/pip-HBSsqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:31 INFO - Running setup.py (path:/tmp/pip-uzktnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:32 INFO - Running setup.py (path:/tmp/pip-gOQn_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:32 INFO - Running setup.py (path:/tmp/pip-LA45AU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:32 INFO - Running setup.py (path:/tmp/pip-QTiwzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:32 INFO - Running setup.py (path:/tmp/pip-gufBUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:32 INFO - Running setup.py (path:/tmp/pip-K2DPpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:32 INFO - Running setup.py (path:/tmp/pip-tUE2yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:32 INFO - Running setup.py (path:/tmp/pip-YuwCjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:32 INFO - Running setup.py (path:/tmp/pip-BkLfBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:32 INFO - Running setup.py (path:/tmp/pip-1S5jsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:32 INFO - Running setup.py (path:/tmp/pip-ki0e7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:32 INFO - Running setup.py (path:/tmp/pip-meUwux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:32 INFO - Running setup.py (path:/tmp/pip-YIIr4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:32 INFO - Running setup.py (path:/tmp/pip-2bozEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:50:32 INFO - Running setup.py install for manifestparser 15:50:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:50:33 INFO - Running setup.py install for mozcrash 15:50:33 INFO - Running setup.py install for mozdebug 15:50:33 INFO - Running setup.py install for mozdevice 15:50:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:50:33 INFO - Running setup.py install for mozfile 15:50:33 INFO - Running setup.py install for mozhttpd 15:50:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:50:33 INFO - Running setup.py install for mozinfo 15:50:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:50:33 INFO - Running setup.py install for mozInstall 15:50:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:50:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:50:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:50:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:50:33 INFO - Running setup.py install for mozleak 15:50:33 INFO - Running setup.py install for mozlog 15:50:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:50:33 INFO - Running setup.py install for moznetwork 15:50:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:50:34 INFO - Running setup.py install for mozprocess 15:50:34 INFO - Running setup.py install for mozprofile 15:50:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:50:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:50:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:50:34 INFO - Running setup.py install for mozrunner 15:50:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:50:34 INFO - Running setup.py install for mozscreenshot 15:50:34 INFO - Running setup.py install for moztest 15:50:34 INFO - Running setup.py install for mozversion 15:50:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:50:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:50:34 INFO - Cleaning up... 15:50:34 INFO - Return code: 0 15:50:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:34 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 0x11741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e2f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e0130>, '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 0x14e1030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e14c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', '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 15:50:34 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 15:50:34 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 15:50:34 INFO - Using env: {'DISPLAY': ':0', 15:50:34 INFO - 'HOME': '/home/cltbld', 15:50:34 INFO - 'LANG': 'en_US.UTF-8', 15:50:34 INFO - 'LANGUAGE': 'en_US:en', 15:50:34 INFO - 'LOGNAME': 'cltbld', 15:50:34 INFO - 'MAIL': '/var/mail/cltbld', 15:50:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:34 INFO - 'MOZ_NO_REMOTE': '1', 15:50:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:34 INFO - 'NO_EM_RESTART': '1', 15:50:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:34 INFO - 'PWD': '/builds/slave/test', 15:50:34 INFO - 'SHELL': '/bin/bash', 15:50:34 INFO - 'SHLVL': '1', 15:50:34 INFO - 'TERM': 'linux', 15:50:34 INFO - 'TMOUT': '86400', 15:50:34 INFO - 'USER': 'cltbld', 15:50:34 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:50:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:34 INFO - '_': '/tools/buildbot/bin/python'} 15:50:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:34 INFO - Running setup.py (path:/tmp/pip-f7UNaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:34 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)) 15:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:34 INFO - Running setup.py (path:/tmp/pip-ZEOLUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:35 INFO - Running setup.py (path:/tmp/pip-aEVOdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:35 INFO - Running setup.py (path:/tmp/pip-x4FVK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:35 INFO - Running setup.py (path:/tmp/pip-Om22Cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:35 INFO - Running setup.py (path:/tmp/pip-UlGrwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:35 INFO - Running setup.py (path:/tmp/pip-f3vuu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:35 INFO - Running setup.py (path:/tmp/pip-Jr_Yi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:35 INFO - Running setup.py (path:/tmp/pip-nijZHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:35 INFO - Running setup.py (path:/tmp/pip-iWWtRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:35 INFO - Running setup.py (path:/tmp/pip-WkolSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:35 INFO - Running setup.py (path:/tmp/pip-u8NRhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:35 INFO - Running setup.py (path:/tmp/pip-FISNw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:35 INFO - Running setup.py (path:/tmp/pip-LUOO6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:35 INFO - Running setup.py (path:/tmp/pip-ebelcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:35 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)) 15:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:35 INFO - Running setup.py (path:/tmp/pip-4rNu5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:36 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)) 15:50:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:36 INFO - Running setup.py (path:/tmp/pip-XbBneX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:36 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)) 15:50:36 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)) 15:50:36 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)) 15:50:36 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)) 15:50:36 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)) 15:50:36 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)) 15:50:36 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)) 15:50:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:50:36 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 15:50:36 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 15:50:36 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 15:50:36 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 15:50:37 INFO - Downloading blessings-1.6.tar.gz 15:50:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:50:37 INFO - Installing collected packages: blessings 15:50:37 INFO - Running setup.py install for blessings 15:50:37 INFO - Successfully installed blessings 15:50:37 INFO - Cleaning up... 15:50:37 INFO - Return code: 0 15:50:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:50:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:37 INFO - Reading from file tmpfile_stdout 15:50:37 INFO - Current package versions: 15:50:37 INFO - argparse == 1.2.1 15:50:37 INFO - blessings == 1.6 15:50:37 INFO - blobuploader == 1.2.4 15:50:37 INFO - docopt == 0.6.1 15:50:37 INFO - functools32 == 3.2.3-2 15:50:37 INFO - jsonschema == 2.5.1 15:50:37 INFO - manifestparser == 1.1 15:50:37 INFO - mozInstall == 1.13 15:50:37 INFO - mozcrash == 1.0 15:50:37 INFO - mozdebug == 0.1 15:50:37 INFO - mozdevice == 0.51 15:50:37 INFO - mozfile == 1.2 15:50:37 INFO - mozhttpd == 0.7 15:50:37 INFO - mozinfo == 0.10 15:50:37 INFO - mozleak == 0.1 15:50:37 INFO - mozlog == 3.5 15:50:37 INFO - moznetwork == 0.27 15:50:37 INFO - mozprocess == 0.25 15:50:37 INFO - mozprofile == 0.28 15:50:37 INFO - mozrunner == 6.13 15:50:37 INFO - mozscreenshot == 0.1 15:50:37 INFO - mozsystemmonitor == 0.3 15:50:37 INFO - moztest == 0.8 15:50:37 INFO - mozversion == 1.4 15:50:37 INFO - psutil == 3.1.1 15:50:37 INFO - requests == 1.2.3 15:50:37 INFO - wsgiref == 0.1.2 15:50:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50: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')]} 15:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:37 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 0x11741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e2f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e0130>, '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 0x14e1030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e14c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', '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 15:50:37 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 15:50:37 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 15:50:37 INFO - Using env: {'DISPLAY': ':0', 15:50:37 INFO - 'HOME': '/home/cltbld', 15:50:37 INFO - 'LANG': 'en_US.UTF-8', 15:50:37 INFO - 'LANGUAGE': 'en_US:en', 15:50:37 INFO - 'LOGNAME': 'cltbld', 15:50:37 INFO - 'MAIL': '/var/mail/cltbld', 15:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:37 INFO - 'MOZ_NO_REMOTE': '1', 15:50:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:37 INFO - 'NO_EM_RESTART': '1', 15:50:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:37 INFO - 'PWD': '/builds/slave/test', 15:50:37 INFO - 'SHELL': '/bin/bash', 15:50:37 INFO - 'SHLVL': '1', 15:50:37 INFO - 'TERM': 'linux', 15:50:37 INFO - 'TMOUT': '86400', 15:50:37 INFO - 'USER': 'cltbld', 15:50:37 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:37 INFO - '_': '/tools/buildbot/bin/python'} 15:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:37 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)) 15:50:37 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)) 15:50:37 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)) 15:50:37 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)) 15:50:37 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)) 15:50:37 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)) 15:50:37 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)) 15:50:37 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)) 15:50:37 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)) 15:50:37 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:50:37 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 15:50:37 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 15:50:37 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 15:50:37 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 15:50:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:50:39 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)) 15:50:39 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)) 15:50:39 INFO - Installing collected packages: simplejson 15:50:39 INFO - Running setup.py install for simplejson 15:50:39 INFO - building 'simplejson._speedups' extension 15:50:39 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 15:50:39 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 15:50:40 INFO - Successfully installed simplejson 15:50:40 INFO - Cleaning up... 15:50:40 INFO - Return code: 0 15:50:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:50:40 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')]} 15:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:40 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')]} 15:50:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:40 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 0x11741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e2f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e0130>, '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 0x14e1030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e14c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', '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 15:50:40 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 15:50:40 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 15:50:40 INFO - Using env: {'DISPLAY': ':0', 15:50:40 INFO - 'HOME': '/home/cltbld', 15:50:40 INFO - 'LANG': 'en_US.UTF-8', 15:50:40 INFO - 'LANGUAGE': 'en_US:en', 15:50:40 INFO - 'LOGNAME': 'cltbld', 15:50:40 INFO - 'MAIL': '/var/mail/cltbld', 15:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:40 INFO - 'MOZ_NO_REMOTE': '1', 15:50:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:40 INFO - 'NO_EM_RESTART': '1', 15:50:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:40 INFO - 'PWD': '/builds/slave/test', 15:50:40 INFO - 'SHELL': '/bin/bash', 15:50:40 INFO - 'SHLVL': '1', 15:50:40 INFO - 'TERM': 'linux', 15:50:40 INFO - 'TMOUT': '86400', 15:50:40 INFO - 'USER': 'cltbld', 15:50:40 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:40 INFO - '_': '/tools/buildbot/bin/python'} 15:50:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:50:40 INFO - Cleaning up... 15:50:40 INFO - Return code: 0 15:50:40 INFO - Running post-action listener: _resource_record_post_action 15:50:40 INFO - Running post-action listener: _start_resource_monitoring 15:50:40 INFO - Starting resource monitoring. 15:50:40 INFO - [mozharness: 2017-08-25 22:50:40.407509Z] Finished create-virtualenv step (success) 15:50:40 INFO - [mozharness: 2017-08-25 22:50:40.408016Z] Running install step. 15:50:40 INFO - Running pre-action listener: _resource_record_pre_action 15:50:40 INFO - Running main action method: install 15:50:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:40 INFO - Reading from file tmpfile_stdout 15:50:40 INFO - Detecting whether we're running mozinstall >=1.0... 15:50:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:50:40 INFO - Reading from file tmpfile_stdout 15:50:40 INFO - Output received: 15:50:40 INFO - Usage: mozinstall [options] installer 15:50:40 INFO - Options: 15:50:40 INFO - -h, --help show this help message and exit 15:50:40 INFO - -d DEST, --destination=DEST 15:50:40 INFO - Directory to install application into. [default: 15:50:40 INFO - "/builds/slave/test"] 15:50:40 INFO - --app=APP Application being installed. [default: firefox] 15:50:40 INFO - mkdir: /builds/slave/test/build/application 15:50:40 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'] 15:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:50:58 INFO - Reading from file tmpfile_stdout 15:50:58 INFO - Output received: 15:50:58 INFO - /builds/slave/test/build/application/firefox/firefox 15:50:58 INFO - Running post-action listener: _resource_record_post_action 15:50:58 INFO - [mozharness: 2017-08-25 22:50:58.459095Z] Finished install step (success) 15:50:58 INFO - [mozharness: 2017-08-25 22:50:58.459246Z] Running setup-mitmproxy step. 15:50:58 INFO - Running pre-action listener: _resource_record_pre_action 15:50:58 INFO - Running main action method: setup_mitmproxy 15:50:58 INFO - Skipping: mitmproxy is not required 15:50:58 INFO - Running post-action listener: _resource_record_post_action 15:50:58 INFO - [mozharness: 2017-08-25 22:50:58.460773Z] Finished setup-mitmproxy step (success) 15:50:58 INFO - [mozharness: 2017-08-25 22:50:58.461086Z] Running run-tests step. 15:50:58 INFO - Running pre-action listener: _resource_record_pre_action 15:50:58 INFO - Running pre-action listener: _set_gcov_prefix 15:50:58 INFO - Running main action method: run_tests 15:50:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:50:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:50:58 INFO - Python 2.7.3 15:50:58 INFO - Return code: 0 15:50:58 INFO - grabbing minidump binary from tooltool 15:50: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')]} 15:50:58 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 0x14e0130>, '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 0x14e1030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e14c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:50:58 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 15:50:58 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 15:50:58 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 15:50:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:50:58 INFO - Return code: 0 15:50:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:50:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:50:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:50:58 INFO - ENV: RUST_BACKTRACE is now full 15:50:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:50:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:50:58 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/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 15:50:58 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/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 15:50:58 INFO - Using env: {'DISPLAY': ':0', 15:50:58 INFO - 'HOME': '/home/cltbld', 15:50:58 INFO - 'LANG': 'en_US.UTF-8', 15:50:58 INFO - 'LANGUAGE': 'en_US:en', 15:50:58 INFO - 'LOGNAME': 'cltbld', 15:50:58 INFO - 'MAIL': '/var/mail/cltbld', 15:50:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:50:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:58 INFO - 'MOZ_NO_REMOTE': '1', 15:50:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:50:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:58 INFO - 'NO_EM_RESTART': '1', 15:50:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:58 INFO - 'PWD': '/builds/slave/test', 15:50:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:50:58 INFO - 'RUST_BACKTRACE': 'full', 15:50:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:50:58 INFO - 'SHELL': '/bin/bash', 15:50:58 INFO - 'SHLVL': '1', 15:50:58 INFO - 'STYLO_FORCE_ENABLED': '1', 15:50:58 INFO - 'TERM': 'linux', 15:50:58 INFO - 'TMOUT': '86400', 15:50:58 INFO - 'USER': 'cltbld', 15:50:58 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:58 INFO - '_': '/tools/buildbot/bin/python'} 15:50:58 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/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 15:50:58 INFO - mozversion application_buildid: 20170825220049 15:50:58 INFO - mozversion application_changeset: bb813dab9832c1b780293edbc5477f920bf0c88e 15:50:58 INFO - mozversion application_display_name: Nightly 15:50:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:50:58 INFO - mozversion application_name: Firefox 15:50:58 INFO - mozversion application_remotingname: firefox 15:50:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:50:58 INFO - mozversion application_vendor: Mozilla 15:50:58 INFO - mozversion application_version: 57.0a1 15:50:58 INFO - mozversion platform_buildid: 20170825220049 15:50:58 INFO - mozversion platform_changeset: bb813dab9832c1b780293edbc5477f920bf0c88e 15:50:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:50:58 INFO - mozversion platform_version: 57.0a1 15:50:58 INFO - using testdate: 1503701458 15:50:58 INFO - actual date: 1503701458 15:50:58 INFO - starting webserver on 'localhost:50911' 15:50:58 INFO - SUITE-START | Running 2 tests 15:50:58 INFO - TEST-START | dromaeo_css 15:50:58 INFO - Initialising browser for dromaeo_css test... 15:50:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50911/getInfo.html -profile /tmp/tmp5ELw37/profile 15:50:58 INFO - TEST-INFO | started process 1007 (/builds/slave/test/build/application/firefox/firefox http://localhost:50911/getInfo.html) 15:51:05 INFO - TEST-INFO | 1007: exit 0 15:51:05 INFO - Browser initialized. 15:51:05 INFO - Running cycle 1/1 for dromaeo_css test... 15:51:05 INFO - Using env: {'DISPLAY': ':0', 15:51:05 INFO - 'HOME': '/home/cltbld', 15:51:05 INFO - 'JSGC_DISABLE_POISONING': '1', 15:51:05 INFO - 'LANG': 'en_US.UTF-8', 15:51:05 INFO - 'LANGUAGE': 'en_US:en', 15:51:05 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:51:05 INFO - 'LOGNAME': 'cltbld', 15:51:05 INFO - 'MAIL': '/var/mail/cltbld', 15:51:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:05 INFO - 'MOZ_CRASHREPORTER': '1', 15:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:05 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:51:05 INFO - 'MOZ_NO_REMOTE': '1', 15:51:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:05 INFO - 'NO_EM_RESTART': '1', 15:51:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:05 INFO - 'PWD': '/builds/slave/test', 15:51:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:05 INFO - 'RUST_BACKTRACE': 'full', 15:51:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:51:05 INFO - 'SHELL': '/bin/bash', 15:51:05 INFO - 'SHLVL': '1', 15:51:05 INFO - 'STYLO_FORCE_ENABLED': '1', 15:51:05 INFO - 'TERM': 'linux', 15:51:05 INFO - 'TMOUT': '86400', 15:51:05 INFO - 'USER': 'cltbld', 15:51:05 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 15:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:05 INFO - '_': '/tools/buildbot/bin/python'} 15:51:05 INFO - TEST-INFO | started process 1206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5ELw37/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:06 INFO - PID 1206 | 15:51:06 INFO - PID 1206 | (/builds/slave/test/build/application/firefox/firefox:1311): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:51:06 INFO - PID 1206 | 15:51:07 INFO - PID 1206 | 15:51:07 INFO - PID 1206 | (/builds/slave/test/build/application/firefox/firefox:1383): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:51:07 INFO - PID 1206 | 15:51:07 INFO - PID 1206 | __metrics Screen width/height:1600/1200 15:51:07 INFO - PID 1206 | colorDepth:24 15:51:07 INFO - PID 1206 | Browser inner width/height: 1024/768 15:51:07 INFO - PID 1206 | __metrics 15:51:16 INFO - PID 1206 | RSS: Main: 174321664 15:51:16 INFO - PID 1206 | 15:54:16 INFO - PID 1206 | Cycle 1(1): loaded http://localhost:50911/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50911/tests/dromaeo/cssquery-ext.html) 15:54:17 INFO - PID 1206 | RSS: Main: 178589696 15:54:17 INFO - PID 1206 | 15:57:19 INFO - PID 1206 | Cycle 1(1): loaded http://localhost:50911/tests/dromaeo/cssquery-ext.html (next: http://localhost:50911/tests/dromaeo/cssquery-jquery.html) 15:57:19 INFO - PID 1206 | RSS: Main: 174931968 15:57:19 INFO - PID 1206 | 16:00:21 INFO - PID 1206 | Cycle 1(1): loaded http://localhost:50911/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50911/tests/dromaeo/cssquery-mootools.html) 16:00:22 INFO - PID 1206 | RSS: Main: 177459200 16:00:22 INFO - PID 1206 | 16:03:24 INFO - PID 1206 | Cycle 1(1): loaded http://localhost:50911/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50911/tests/dromaeo/cssquery-prototype.html) 16:03:24 INFO - PID 1206 | RSS: Main: 175706112 16:03:24 INFO - PID 1206 | 16:06:28 INFO - PID 1206 | Cycle 1(1): loaded http://localhost:50911/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50911/tests/dromaeo/cssquery-yui.html) 16:06:28 INFO - PID 1206 | RSS: Main: 179331072 16:06:28 INFO - PID 1206 | 16:09:30 INFO - PID 1206 | Cycle 1(1): loaded http://localhost:50911/tests/dromaeo/cssquery-yui.html (next: http://localhost:50911/tests/dromaeo/cssquery-dojo.html) 16:09:30 INFO - PID 1206 | RSS: Main: 179044352 16:09:30 INFO - PID 1206 | 16:09:30 INFO - PID 1206 | __start_tp_report 16:09:30 INFO - PID 1206 | _x_x_mozilla_page_load 16:09:30 INFO - PID 1206 | _x_x_mozilla_page_load_details 16:09:30 INFO - PID 1206 | |i|pagename|runs| 16:09:30 INFO - PID 1206 | |0;dojo.html;3336.9666303336967;3351.4135026370377;3363.2432702641895;3385.06910599585;3425.434803257461;6185.505159587234;6294.653794041332;6413.807585772429;6420.422162005419;6443.452306553942;6347.49220062395;6375.840603984902;6469.159009328792;6474.676266186683;6507.967460162693;5690.2033715279895;5715.257016587838;5764;5844.620099693528;5863.061910094386;5796.188533605277;5799.3620701722775;5835.387284335147;5868.9413105869035;5893.44012318829;5698.287714035746;5770.971145144289;5802.680852553087;5860.501857342131;5883.735231914574;5809.302883653989;5864.941350586482;5884.499817515516;5893.675847828367;5914.319853216892;5745.6265342753;5789.855253618651;5809.3609702932645;5844.707764611795;5874.030784920483;6312.210973628296;6319.778807741753;6346.492280617539;6366.6180029198395;6441.194850643669;6757.932420675828;6799.762008329734;6802.319768023158;6825.419839313664;6831.863362732767;6804.489663275274;6890.000949862292;6895.586264824076;6911.101556797635;6913.792586222421;6088.391160883876;6153.446189842935;6288.811335659938;6330.715117819663;6396.5842220256;6924.6537673116145;6945.062416573698;6960.234374218832;6977.023216749659;6980.616066116311;6645.169353830771;6647.135872336566;6657;6657.900131498031;6660.167479065117;6700.396964273239;6733.3266673333255;6798.286179951133;6805.2173999989645;6812.352826481477;6801.863962720718;6813.7955861324235;6859.348361905611;6862.828429289308;6895.34494223058;6839.213490448662;6847.691853866588;6922.861542769117;6924.688389022506;6932.23745388007;5232.5552328052545;5277.23480095384;5282.3661160660195;5283.6037297202865;5289.021531016774;4687.906241875144;4746.786394612251;4763.904721905543;4767.570918617341;4787.664863459524;5978.163057172;6007.128966299862;6032.095185722177;6057.454829065406;6071.939280607226;6855.143107111611;6856.794296171123;6942.271061538466;6952.826179345456;6952.860942781217;5846.503047240947;5876.177335173079;5935.52515798735;6024.548158888013;6035.30593981689;5874.441928016831;5876.823695289148;5916.408359164049;5918.201042859159;5952.107183922446;6692.163479564957;6696.5647232929705;6752.763653272112;6786.830329241809;6791.558548694319;6888.104546408935;6891.310868913069;6959.721611135498;6968.616726080114;6968.721251149897;6424.967875160594;6549.115869357575;6672.966635166793;6682.46540276767;6770.661466926733;6799.89800153008;6800.455963522807;6825.863482730417;6833.41915937131;6833.45332373399;6842.144731908512;6875.140607424072;6962.965185174042;6966.721331146698;6989.790306290819;6620.304867988984;6622.337766223532;6642.4021838034805;6718.630475323904;6726.058351830846;6506.674666266573;6599.901001485077;6634.369734875179;6652.467802575686;6711.899321510279;6605.702743376656;6619.801405957829;6622.602643841385;6626.768063117768;6642.103316052271;6606.075149478981;6717.05961165447;6734.663266836541;6807.047013418224;6811.761588344384;6478.611283322827;6483.546151769331;6527.02094685801;6560;6574.76988305407 16:09:30 INFO - PID 1206 | |1;ext.html;777.4518964130289;840.3193413335199;854.4488804720955;866.2290561400353;1112.7162573543744;28921.710782892165;29465.55801662974;38659.99999999996;39368.60631393685;39506.60493395065;39250.411243831295;39509.20981580374;39711.80144099283;39768.80115599418;39803;7615.961920190405;7757.612119394036;7761.495502792329;7770.145284018781;7770.222977702213;7612.391008719289;7694.269044440769;7694.615269236544;7722.575258360788;7733.613319334011;7660.846783064336;7714.4599878008285;7725.266099720518;7760.767176984699;7772.145064042951;7622.161562228151;7711.691532338756;7712;7716.729914452995;7724.922750772476;7537.170911199763;7541.472096953215;7572.053493313336;7584.734534291273;7585.393168546559;35391;35467;35526.644733552595;35538.111547211265;35596.28807423837;3527.7177825773874;3547.875824346161;3548.840302186382;3549.0594992327055;3552.6980206682465;29310.70689293101;29401.264968375748;29420.70579294201;29430.117096487138;29515.409691806257;4841.636877234221;4842.225243960949;4843.636727245435;4847.75761211938;4881.487443818419;180167;180273.19726802694;180408;180944;181598;44368;44495.55504444914;44543.331850022274;44604.330935036;44722.55277447184;46927.296090558666;46981;46983.29525057127;47002;47017.529824702;15221.923890380478;15507.534773956799;15518.9224053879;15521.223938803014;15530.767038494432;12907.354632268349;12923.095383323078;12932.676683082998;12935.547255846188;12980.480780768852;12766.744665106831;12935.935320323339;12938;12998.285094319903;13125.67185820343;5060.620453466005;5077.543021128116;5096.770645320896;5127.282180494733;5144.408393034774;967.1537404770826;969.8545218217324;976.511744127936;991.7272749993809;1001.249063202598;20662.173513059308;20774.58450830975;20971.056302466426;21123.26068587592;21245.46886327854;1022.4223313827664;1055.9313644613076;1069.4652673663168;1073.1629329123296;1075.930064545802;11437.370944598128;11501.309921404576;11546.249493782878;11663.475143618558;11669.833016698261;220432;221162;221313;231271;234808;53151.468485314654;53383;53428.19857702137;53492;53506;1390.7079513302117;1424.5085445521486;1428.6428392901776;1428.6499807547218;1443.4875619155048;25188.74811251864;25245.873770631762;25528.489430211663;25650.743492564834;26209.73790262149;35825.64174358223;35923;36014.27971440609;36039.81980090083;36060.81969590135;192156;192480;193156;194013;194619;31752.841235793672;33229.83385083059;33720.662793372736;33739.83130084334;33934.66065339315;41755.16489670129;42866;42868.57131428771;43071.138577227655;43192.35211471893;31497.370052598362;31507.842460788466;31743.206419838763;32058;32076.51885221677;31033.37933241368;31146.221344465663;31358;31496.527552087195;32197.67802321947;35406.46890296699;35521.467177991835;35566;35580.64419355773;35603.10992225215;30853.382932341676;31065.53401698931;31244.218894527818;31569;31718.207044824063;23351;23833.523329532967;23900.28299151028;23911.641325380482;24227.75772242255 16:09:30 INFO - PID 1206 | |2;jquery.html;544.7575828756203;545.9645123066998;548.5830768615854;566.7732906837266;571.4942276085665;21865.453363665878;21977.560448791013;22632.20787272446;22755.203567875127;22809.657855132187;24583.139590114348;25008.999640014423;25121.87439062802;25208.621870671956;25319.74680253197;6397.744090236397;6547.3125321841235;6552.312007239243;6566.934330656704;6568.146141001663;5957.493613042897;6458.095866578659;6513.837154071139;6563.3764792344655;6571.934280657204;6526.608403495806;6536.313687062861;6539.182602174728;6544.181977252843;6546.541742078057;6493.123428337161;6501.967490162566;6510.967445162768;6512.93487065128;6527.151470308853;6004;6045.153678485016;6061.66660833654;6078.361772013964;6088.360722124157;26065.218043458728;27177.320566985785;27590;28518;28879;1995.3315639260866;2025.837932965374;2029.9797002029939;2030.0763152765455;2030.3807338761671;18516.722249166272;18996.24015039397;19045.61908761817;19103.2358705653;19115.90442047795;2340.742518322983;2435.7320694723567;2475.777180053804;2489.576771948773;2504.649349091129;129267.35366323202;136032.31983840116;136782.3160884189;136800.31599842035;136834.3158284212;14010;14129.690444414553;14257.21585312797;14647.633809154644;14941.178235197169;23046.8847655764;23087;23172.65241021386;23192.304230873102;23207.187748428725;9399.483028433366;9707.174890134276;9752.171065459373;9797.559109840075;9800;8886.022537520868;8886.289096872235;8910.108989101038;8944.5080520572;9038.86441703375;7553.206913274137;8775.561221938877;8799.956000219958;9040.728778136667;9130.497822619687;2592.3130370451845;2614.241869857758;2665.440717180471;2691.6769987601615;2741.7943654225614;908.123660667459;953.2802733935835;954.0983770337103;954.928380371461;958.5399008475971;17972.55068623295;18533.907330463262;18619;18669.346572869883;18736.53158671044;1000.0399616368368;1008.3042700536606;1018.1396719771781;1020.7958408318366;1021.8109649714827;10244.743881403025;10432.791344173074;10508.842367364496;10646.73383165427;10834.729131771612;487230;489601;489753;502185;518966;102872;107983.46008269908;108502.45748771206;109869.45065274782;110280;1105.8894110588876;1173.6596387047832;1177.1230433327225;1179.7345597240724;1184.413715210976;16050.919745401197;16501.422450214184;16525.33898644089;16649.50051498457;16780.58048548796;59958;60016.09975850279;60165.69917150386;62090.68954655198;62380.68809656104;69505.65247173731;73372.63313683397;75005.62497187479;75719.86420203591;75989;12420.192687475284;12982.15615984958;14343.641408964859;14389.280535973368;14903.701925961203;22822.543549129256;23109.88445057764;23544.764552354944;24051.158209462585;24116;16730.24713887902;16851.41020064292;17152.656946861243;17203.483895483154;17241.827581724523;16387.508374748777;16908.23912923897;16931.984080955182;17042.403515876886;17670.20484078245;17422.216000279765;18155.909220453814;18307.35924242645;18472.907635461735;18526.629467410847;14230.715385692436;20929.581408371443;21651.458713532287;21658.241961531272;21714.24000159987;16028;16138.273777679799;16533.421330253383;16676.833231668013;16677.833221667628 16:09:30 INFO - PID 1206 | |3;mootools.html;1192.3680449361837;1233.7779199744043;1244.1539752967983;1245.6823510004947;1250.1623911978973;12433.626991190256;14243.92878035611;14890.255487225622;14900.180490073042;14937.775933361008;12490.937545312261;12645.936770316159;14289.285535723224;14847.406103755837;14874.47939322124;2576.665033545641;2637.4065835187025;2642.1545105566224;2649.1919964410845;2650.536156172672;2526;2534.759197876199;2620.5807070868664;2697.2852194168563;2708.9051883184093;2662.254568720761;2666.1601595497455;2692.717264687209;2694.7844172466253;2695.0567301444535;2529.6458495810602;2548.706898706654;2610.2952202905203;2668.319578507468;2668.679758429001;2480.7395223501453;2521.382261345982;2522.041624182817;2535.1127105513106;2570.5887058070807;14568.49010284635;14606.634834129232;14674.046186997812;14724.55826325212;14742.484013059599;7787.649555770004;8039.55782431966;8065.112837587919;8069.677212911476;8072.677092916277;5230.555402790767;5428.050091234058;5535.806246781343;5537.335519737658;5538.861528461821;1832.8075552067107;1851.8425933795643;1886.9150888210063;1923.807619238079;1936.0900376822872;24989.125380611775;25102.12142575001;25520.489590208093;25563;25846.224613261445;6485.513586481033;6564.980109149845;6664.966675166593;6780.864382712318;6789.049533065375;16738.497845064423;16772.496825095266;16787.2445739942;16791.832081679026;16805.24376403065;7562.886556701654;7725.150233474314;7806.453548251681;7806.960965195138;7808.180141085218;7199.784006479709;7203.135623725187;7469.813254668677;7479.065116860393;7480.438967077491;4948.975255123702;5092.770825312796;5095.8726031848755;5141.511556402136;5148.76830542619;1485.8514148585054;1499.4077339450855;1505.503183949294;1505.7214415333199;1533.3713177597356;855.1106848877098;876.9912300877037;890.5458216309729;891.6700820696384;899.6401439424152;7296.852386944007;7302.0142280791415;7350.52221605594;7358.768707992926;7380.151282602462;703.2405002597285;705.8800003999332;711.9145702515731;716.4053835316779;721.2823240875258;5108.106081435734;5245.763940622604;5299.258103865462;5421.972890135603;5424.240606315119;20376.796232037785;21509;21569.24507642225;21712.674309885366;21724.23965161212;20275;20864;21211.045502952406;21601.67597486039;21653.67519487209;9016.459012459141;9241.306901982378;10505.159587233004;10931.726706832234;10945.616903408343;7242.782716518513;8968.192862642394;9022.864657030019;9078.863817042882;9088.136627020422;17205;17296.827031730027;17423.651526969137;17439.30242790274;17493.650126997134;17575;17605.383811566535;17703.203355849277;17820;17893.821061789735;11687.357195354338;12078.335691536699;12078.577249796215;12083;12085.15403921717;13419.86580134186;13473.528426505025;13586.45654173822;13645.181289122684;13693.178409295475;10830.133589312994;10933.398663073607;11038.779224415306;11133.721656958642;11166.665000050012;10793.244472886823;10836;10879.673609791718;11005;11122.44387780624;9301.953490232505;9469.763255918657;9524.095210954949;9528.047195280416;9532.28507861927;7058.647067646494;7099;7109.28907109285;7121.893171602534;7184.461165412719;7351.8162045949275;7388.926110739038;7396.3343299103335;7404.296591823768;7569.621518923922 16:09:30 INFO - PID 1206 | |4;prototype.html;483.17618460524807;499.0443301078435;502.6456348274465;506.98732531686704;517.2448225590638;6273.780417685388;6323.146375239347;6355.459785918197;6419.069234960928;6494.870102597957;6185.505159587234;6287.622742635445;6338.778142765004;6363.840903977403;6366.872662546746;5253.684778913258;5259.342582177228;5277.102892508283;5306.973465132688;5351.304330437038;4992.654921202785;4994.425641051271;5230.163173892159;5257.815976440826;5264.8157314494;4979.427365852937;4988.775505102261;5016.949830501685;5045.621578381618;5078.162103252977;4974.552290293873;4994.153332600419;5001.9749901250625;5053.166227572447;5054.837387400881;4413.955860441387;4450.554944505524;4481.6638752093395;4520.68355215135;4547.499775024745;6108.511319094462;6211.19254496917;6228.190335256433;6244.843878903019;6259.186305780219;5718.513926316268;5762.4525670061275;5774.364819869853;5803.825885223408;5811.767529298865;6535.150430444012;6586.868262634721;6625.337466253384;6663.866722665568;6691.899621505681;4922.163232250526;4925.187344088221;4935.851924442272;4943.678660887031;4955.504449555052;6674.399304062609;6767;6799;6905.309469053054;7036.261192574757;4955.851324460272;4963.60291176705;5027.622928280393;5028.547430731252;5050.621203409759;5034.672746271481;5068.023470846373;5078.796848126033;5132.204508301219;5164.638475306768;5711.428857114339;5743.425657434307;5763.789604183168;5810.94189058104;5819.359870414251;6726.730930762715;6736.292689267654;6748.561343512656;6758.594484330956;6805.931940680629;1733.50595080404;1744.1192197939959;1763.6560870630103;1781.4566557199919;1787.4995001399368;1147.9196456247982;1195.4560674892903;1202.22456515547;1209.5403746576244;1213.9453724582415;5867.413258674099;5902.5277977762535;5934.465898069195;5937.584369094122;5981.983062879321;6835.487338449537;6837.316268373223;6857.017197518995;6866.725330986705;6937.3232186941;5832.3292821326095;5854.736536855852;5893.292804863444;5908.409159084057;5926.407359264039;3622.5471816022996;3623.528941237675;3640.7633503822167;3659.8353074111196;3662.853485860536;6613.3056029117215;6626.668666566652;6676.866462670817;6686.113303297611;6729.226138994079;6951.2353641099435;7049.118860142482;7066.7173313066905;7077.929220707727;7124.287571242938;6086.330503644595;6593.406593406617;6603.76886808969;6621.602703837785;6733.528652994243;6708.26209116987;6737.854564724107;6791.185057792899;6802.319768023158;6810.693518791567;6680.63256520896;6716.597004179765;6764.289749576224;6822.317768223138;6851.623160726208;6656.7004484797335;6678.7328506859185;6698.899516507159;6724.899126513008;6778.7288508459105;4904.93992523134;5041.596672266137;5045.394552653705;5083.415407228216;5094.821681241138;6508.511861610493;6725.361090696376;6756.8648627026205;6790.080265485888;6976.441884649317;6531.934680653133;6585.670716464253;6597.934020659924;6681.231658359351;6685.431738302299;6608.7026083825385;6611.570247933869;6639.136912201383;6722.697478613572;6729.798106056826;6492.64290464029;6578.243501997332;6600.778855911672;6603.504737144637;6635.6350400728425;6721.428678562377;6741.1910570731825;6787.694553745192;6814.761483348059;6845.315468453115;6479.643619600967;6498.187726534184;6524.445422139172;6568.1789776277965;6578.572392794453 16:09:30 INFO - PID 1206 | |5;yui.html;869.0831173112366;887.2192470625853;893.6202114101505;901.0493928905003;904.7511934218085;17937.192826322815;19089.23643054278;19117.808821911778;19212.13545390457;19821.40535783929;20362.796372036275;21159;21161.15355385783;21167.153313867428;21223.257185998493;935.4761333653142;957.6313119448993;961.6586111930269;961.8364877970727;961.9855702164473;924.6116631014957;959.2661613865398;963.9855402168973;964.3249725192359;973.1241882305945;999.4203362049993;1010.3584224017728;1012.25093430861;1024.8360262358024;1025.3335332034162;1015.6597539824168;1017.5980487707315;1017.8167929772638;1018.96943091707;1019.2152042926941;748.9925100748977;749.0861149397726;749.089855825175;749.2919191364163;749.857527069855;18208.362707305314;18507.814921850742;19461.708074378897;19643.508912277164;19645.410637680892;20680.79319206804;20755.377338679864;21116.472088197763;21164.36506904795;21179.576408471745;16132.516024519284;16244.269007894673;16349;16474.670506589802;16591.668166636722;3673.2469843682247;3697.1681371691425;3712.0348709335767;3712.1833196696953;3713.57293911201;122625.38687306596;123884;124622.37688811589;127718;128106.359468203;14942.178180199975;15413.922930385277;15416.768748468781;15443.61390965235;15513.689726205414;28754.424911501654;29035.709642903723;29423.558646620317;29538.261543461154;29585.704142958726;8287.41988060842;8359.707410240613;8400.243978042006;8407.11725268838;8417.747467575982;4225.112726327444;4332.241857674894;4337.15425492026;4352.542982986741;4362.432883725159;5181.663191892515;5226.8954620908125;5238.711870847063;5271.156614941591;5313.362396512366;907.1291560102244;911.2436677557612;946.4747065378643;947.3984020147257;951.07745486879;648.9805305840832;663.7477758451854;667.9031540426611;675.6182756742425;686.5228666077128;10044.497775111215;10671.679849604525;10727.034566889017;12882.935585322013;13017.153884997635;679.4530403025611;681.5876394781185;685.8148299958983;694.8089275449191;699.0156110171938;4975.751212439363;5133.79464821403;5144.71704056273;5359.973200133974;5737.569682273846;113173.43413282881;114016.42991784988;114155;114315;115983;95287;97502.51248743852;97739;99146.55547389397;99661.50169249253;84942;86268.56865715631;87611.56194218989;88111.55944220367;88141;9759.902400976183;9884.357516761387;10026.147777438999;10062.044105809937;10129.493525323851;40235.3964690524;41184.79407602943;41331;41599.58400416078;44464.33303500515;69873.30126698871;72268.63865680849;73633.26366736478;73695;75880;17000.48998530046;17003.40488082911;17102.973821570744;17314.393996210074;17344.219510121824;19624.90187549053;20539.58920821605;21041.684374734697;21372.251971180933;21380.893095534422;17085.23116459787;18183.363582274556;18376.724349134507;18382.908085459487;18483.445496635122;18369.632607348045;18762;19069;19112.80887191166;19278.518037049187;13505.527306544223;16306.918465407596;18149.27402903869;18178.181981811114;18575.16411761501;12459.93770031144;13254.13848099909;13588.45646174142;13595.660108497366;14167.929160354131;13235.27206003679;13243.271620060988;13404.865951340362;13768.311584420939;14138.22239776781 16:09:30 INFO - PID 1206 | __end_tp_report 16:09:30 INFO - PID 1206 | __start_cc_report 16:09:30 INFO - PID 1206 | _x_x_mozilla_cycle_collect,385 16:09:30 INFO - PID 1206 | __end_cc_report 16:09:30 INFO - PID 1206 | __startTimestamp1503702570720__endTimestamp 16:09:30 INFO - PID 1206 | 16:09:30 INFO - PID 1206 | ------- Summary: start ------- 16:09:30 INFO - PID 1206 | Number of tests: 6 16:09:30 INFO - PID 1206 | 16:09:30 INFO - PID 1206 | [#0] dojo.html Cycles:165 Average:6294.26 Median:6574.77 stddev:740.82 (11.3%) stddev-sans-first:705.84 16:09:30 INFO - PID 1206 | Values: 3337.0 3351.4 3363.2 3385.1 3425.4 6185.5 6294.7 6413.8 6420.4 6443.5 6347.5 6375.8 6469.2 6474.7 6508.0 5690.2 5715.3 5764.0 5844.6 5863.1 5796.2 5799.4 5835.4 5868.9 5893.4 5698.3 5771.0 5802.7 5860.5 5883.7 5809.3 5864.9 5884.5 5893.7 5914.3 5745.6 5789.9 5809.4 5844.7 5874.0 6312.2 6319.8 6346.5 6366.6 6441.2 6757.9 6799.8 6802.3 6825.4 6831.9 6804.5 6890.0 6895.6 6911.1 6913.8 6088.4 6153.4 6288.8 6330.7 6396.6 6924.7 6945.1 6960.2 6977.0 6980.6 6645.2 6647.1 6657.0 6657.9 6660.2 6700.4 6733.3 6798.3 6805.2 6812.4 6801.9 6813.8 6859.3 6862.8 6895.3 6839.2 6847.7 6922.9 6924.7 6932.2 5232.6 5277.2 5282.4 5283.6 5289.0 4687.9 4746.8 4763.9 4767.6 4787.7 5978.2 6007.1 6032.1 6057.5 6071.9 6855.1 6856.8 6942.3 6952.8 6952.9 5846.5 5876.2 5935.5 6024.5 6035.3 5874.4 5876.8 5916.4 5918.2 5952.1 6692.2 6696.6 6752.8 6786.8 6791.6 6888.1 6891.3 6959.7 6968.6 6968.7 6425.0 6549.1 6673.0 6682.5 6770.7 6799.9 6800.5 6825.9 6833.4 6833.5 6842.1 6875.1 6963.0 6966.7 6989.8 6620.3 6622.3 6642.4 6718.6 6726.1 6506.7 6599.9 6634.4 6652.5 6711.9 6605.7 6619.8 6622.6 6626.8 6642.1 6606.1 6717.1 6734.7 6807.0 6811.8 6478.6 6483.5 6527.0 6560.0 6574.8 16:09:30 INFO - PID 1206 | 16:09:30 INFO - PID 1206 | [#1] ext.html Cycles:180 Average:36277.38 Median:35398.73 stddev:52012.32 (146.9%) stddev-sans-first:52089.92 16:09:30 INFO - PID 1206 | Values: 777.5 840.3 854.4 866.2 1112.7 28921.7 29465.6 38660.0 39368.6 39506.6 39250.4 39509.2 39711.8 39768.8 39803.0 7616.0 7757.6 7761.5 7770.1 7770.2 7612.4 7694.3 7694.6 7722.6 7733.6 7660.8 7714.5 7725.3 7760.8 7772.1 7622.2 7711.7 7712.0 7716.7 7724.9 7537.2 7541.5 7572.1 7584.7 7585.4 35391.0 35467.0 35526.6 35538.1 35596.3 3527.7 3547.9 3548.8 3549.1 3552.7 29310.7 29401.3 29420.7 29430.1 29515.4 4841.6 4842.2 4843.6 4847.8 4881.5 180167.0 180273.2 180408.0 180944.0 181598.0 44368.0 44495.6 44543.3 44604.3 44722.6 46927.3 46981.0 46983.3 47002.0 47017.5 15221.9 15507.5 15518.9 15521.2 15530.8 12907.4 12923.1 12932.7 12935.5 12980.5 12766.7 12935.9 12938.0 12998.3 13125.7 5060.6 5077.5 5096.8 5127.3 5144.4 967.2 969.9 976.5 991.7 1001.2 20662.2 20774.6 20971.1 21123.3 21245.5 1022.4 1055.9 1069.5 1073.2 1075.9 11437.4 11501.3 11546.2 11663.5 11669.8 220432.0 221162.0 221313.0 231271.0 234808.0 53151.5 53383.0 53428.2 53492.0 53506.0 1390.7 1424.5 1428.6 1428.6 1443.5 25188.7 25245.9 25528.5 25650.7 26209.7 35825.6 35923.0 36014.3 36039.8 36060.8 192156.0 192480.0 193156.0 194013.0 194619.0 31752.8 33229.8 33720.7 33739.8 33934.7 41755.2 42866.0 42868.6 43071.1 43192.4 31497.4 31507.8 31743.2 32058.0 32076.5 31033.4 31146.2 31358.0 31496.5 32197.7 35406.5 35521.5 35566.0 35580.6 35603.1 30853.4 31065.5 31244.2 31569.0 31718.2 23351.0 23833.5 23900.3 23911.6 24227.8 16:09:30 INFO - PID 1206 | 16:09:30 INFO - PID 1206 | [#2] jquery.html Cycles:180 Average:34685.97 Median:23199.75 stddev:83569.22 (360.2%) stddev-sans-first:83764.33 16:09:30 INFO - PID 1206 | Values: 544.8 546.0 548.6 566.8 571.5 21865.5 21977.6 22632.2 22755.2 22809.7 24583.1 25009.0 25121.9 25208.6 25319.7 6397.7 6547.3 6552.3 6566.9 6568.1 5957.5 6458.1 6513.8 6563.4 6571.9 6526.6 6536.3 6539.2 6544.2 6546.5 6493.1 6502.0 6511.0 6512.9 6527.2 6004.0 6045.2 6061.7 6078.4 6088.4 26065.2 27177.3 27590.0 28518.0 28879.0 1995.3 2025.8 2030.0 2030.1 2030.4 18516.7 18996.2 19045.6 19103.2 19115.9 2340.7 2435.7 2475.8 2489.6 2504.6 129267.4 136032.3 136782.3 136800.3 136834.3 14010.0 14129.7 14257.2 14647.6 14941.2 23046.9 23087.0 23172.7 23192.3 23207.2 9399.5 9707.2 9752.2 9797.6 9800.0 8886.0 8886.3 8910.1 8944.5 9038.9 7553.2 8775.6 8800.0 9040.7 9130.5 2592.3 2614.2 2665.4 2691.7 2741.8 908.1 953.3 954.1 954.9 958.5 17972.6 18533.9 18619.0 18669.3 18736.5 1000.0 1008.3 1018.1 1020.8 1021.8 10244.7 10432.8 10508.8 10646.7 10834.7 487230.0 489601.0 489753.0 502185.0 518966.0 102872.0 107983.5 108502.5 109869.5 110280.0 1105.9 1173.7 1177.1 1179.7 1184.4 16050.9 16501.4 16525.3 16649.5 16780.6 59958.0 60016.1 60165.7 62090.7 62380.7 69505.7 73372.6 75005.6 75719.9 75989.0 12420.2 12982.2 14343.6 14389.3 14903.7 22822.5 23109.9 23544.8 24051.2 24116.0 16730.2 16851.4 17152.7 17203.5 17241.8 16387.5 16908.2 16932.0 17042.4 17670.2 17422.2 18155.9 18307.4 18472.9 18526.6 14230.7 20929.6 21651.5 21658.2 21714.2 16028.0 16138.3 16533.4 16676.8 16677.8 16:09:30 INFO - PID 1206 | 16:09:30 INFO - PID 1206 | [#3] mootools.html Cycles:180 Average:9030.83 Median:2573.63 stddev:6406.28 (248.9%) stddev-sans-first:6397.17 16:09:30 INFO - PID 1206 | Values: 1192.4 1233.8 1244.2 1245.7 1250.2 12433.6 14243.9 14890.3 14900.2 14937.8 12490.9 12645.9 14289.3 14847.4 14874.5 2576.7 2637.4 2642.2 2649.2 2650.5 2526.0 2534.8 2620.6 2697.3 2708.9 2662.3 2666.2 2692.7 2694.8 2695.1 2529.6 2548.7 2610.3 2668.3 2668.7 2480.7 2521.4 2522.0 2535.1 2570.6 14568.5 14606.6 14674.0 14724.6 14742.5 7787.6 8039.6 8065.1 8069.7 8072.7 5230.6 5428.1 5535.8 5537.3 5538.9 1832.8 1851.8 1886.9 1923.8 1936.1 24989.1 25102.1 25520.5 25563.0 25846.2 6485.5 6565.0 6665.0 6780.9 6789.0 16738.5 16772.5 16787.2 16791.8 16805.2 7562.9 7725.2 7806.5 7807.0 7808.2 7199.8 7203.1 7469.8 7479.1 7480.4 4949.0 5092.8 5095.9 5141.5 5148.8 1485.9 1499.4 1505.5 1505.7 1533.4 855.1 877.0 890.5 891.7 899.6 7296.9 7302.0 7350.5 7358.8 7380.2 703.2 705.9 711.9 716.4 721.3 5108.1 5245.8 5299.3 5422.0 5424.2 20376.8 21509.0 21569.2 21712.7 21724.2 20275.0 20864.0 21211.0 21601.7 21653.7 9016.5 9241.3 10505.2 10931.7 10945.6 7242.8 8968.2 9022.9 9078.9 9088.1 17205.0 17296.8 17423.7 17439.3 17493.7 17575.0 17605.4 17703.2 17820.0 17893.8 11687.4 12078.3 12078.6 12083.0 12085.2 13419.9 13473.5 13586.5 13645.2 13693.2 10830.1 10933.4 11038.8 11133.7 11166.7 10793.2 10836.0 10879.7 11005.0 11122.4 9302.0 9469.8 9524.1 9528.0 9532.3 7058.6 7099.0 7109.3 7121.9 7184.5 7351.8 7388.9 7396.3 7404.3 7569.6 16:09:30 INFO - PID 1206 | 16:09:30 INFO - PID 1206 | [#4] prototype.html Cycles:180 Average:5607.48 Median:6252.02 stddev:1583.27 (25.3%) stddev-sans-first:1540.28 16:09:30 INFO - PID 1206 | Values: 483.2 499.0 502.6 507.0 517.2 6273.8 6323.1 6355.5 6419.1 6494.9 6185.5 6287.6 6338.8 6363.8 6366.9 5253.7 5259.3 5277.1 5307.0 5351.3 4992.7 4994.4 5230.2 5257.8 5264.8 4979.4 4988.8 5016.9 5045.6 5078.2 4974.6 4994.2 5002.0 5053.2 5054.8 4414.0 4450.6 4481.7 4520.7 4547.5 6108.5 6211.2 6228.2 6244.8 6259.2 5718.5 5762.5 5774.4 5803.8 5811.8 6535.2 6586.9 6625.3 6663.9 6691.9 4922.2 4925.2 4935.9 4943.7 4955.5 6674.4 6767.0 6799.0 6905.3 7036.3 4955.9 4963.6 5027.6 5028.5 5050.6 5034.7 5068.0 5078.8 5132.2 5164.6 5711.4 5743.4 5763.8 5810.9 5819.4 6726.7 6736.3 6748.6 6758.6 6805.9 1733.5 1744.1 1763.7 1781.5 1787.5 1147.9 1195.5 1202.2 1209.5 1213.9 5867.4 5902.5 5934.5 5937.6 5982.0 6835.5 6837.3 6857.0 6866.7 6937.3 5832.3 5854.7 5893.3 5908.4 5926.4 3622.5 3623.5 3640.8 3659.8 3662.9 6613.3 6626.7 6676.9 6686.1 6729.2 6951.2 7049.1 7066.7 7077.9 7124.3 6086.3 6593.4 6603.8 6621.6 6733.5 6708.3 6737.9 6791.2 6802.3 6810.7 6680.6 6716.6 6764.3 6822.3 6851.6 6656.7 6678.7 6698.9 6724.9 6778.7 4904.9 5041.6 5045.4 5083.4 5094.8 6508.5 6725.4 6756.9 6790.1 6976.4 6531.9 6585.7 6597.9 6681.2 6685.4 6608.7 6611.6 6639.1 6722.7 6729.8 6492.6 6578.2 6600.8 6603.5 6635.6 6721.4 6741.2 6787.7 6814.8 6845.3 6479.6 6498.2 6524.4 6568.2 6578.6 16:09:30 INFO - PID 1206 | 16:09:30 INFO - PID 1206 | [#5] yui.html Cycles:180 Average:23767.79 Median:21376.57 stddev:32639.99 (152.7%) stddev-sans-first:32686.27 16:09:30 INFO - PID 1206 | Values: 869.1 887.2 893.6 901.0 904.8 17937.2 19089.2 19117.8 19212.1 19821.4 20362.8 21159.0 21161.2 21167.2 21223.3 935.5 957.6 961.7 961.8 962.0 924.6 959.3 964.0 964.3 973.1 999.4 1010.4 1012.3 1024.8 1025.3 1015.7 1017.6 1017.8 1019.0 1019.2 749.0 749.1 749.1 749.3 749.9 18208.4 18507.8 19461.7 19643.5 19645.4 20680.8 20755.4 21116.5 21164.4 21179.6 16132.5 16244.3 16349.0 16474.7 16591.7 3673.2 3697.2 3712.0 3712.2 3713.6 122625.4 123884.0 124622.4 127718.0 128106.4 14942.2 15413.9 15416.8 15443.6 15513.7 28754.4 29035.7 29423.6 29538.3 29585.7 8287.4 8359.7 8400.2 8407.1 8417.7 4225.1 4332.2 4337.2 4352.5 4362.4 5181.7 5226.9 5238.7 5271.2 5313.4 907.1 911.2 946.5 947.4 951.1 649.0 663.7 667.9 675.6 686.5 10044.5 10671.7 10727.0 12882.9 13017.2 679.5 681.6 685.8 694.8 699.0 4975.8 5133.8 5144.7 5360.0 5737.6 113173.4 114016.4 114155.0 114315.0 115983.0 95287.0 97502.5 97739.0 99146.6 99661.5 84942.0 86268.6 87611.6 88111.6 88141.0 9759.9 9884.4 10026.1 10062.0 10129.5 40235.4 41184.8 41331.0 41599.6 44464.3 69873.3 72268.6 73633.3 73695.0 75880.0 17000.5 17003.4 17103.0 17314.4 17344.2 19624.9 20539.6 21041.7 21372.3 21380.9 17085.2 18183.4 18376.7 18382.9 18483.4 18369.6 18762.0 19069.0 19112.8 19278.5 13505.5 16306.9 18149.3 18178.2 18575.2 12459.9 13254.1 13588.5 13595.7 14167.9 13235.3 13243.3 13404.9 13768.3 14138.2 16:09:30 INFO - PID 1206 | -------- Summary: end -------- 16:09:30 INFO - PID 1206 | 16:09:31 INFO - TEST-INFO | 1206: exit 0 16:09:31 INFO - TEST-OK | dromaeo_css | took 1112623ms 16:09:31 INFO - TEST-START | kraken 16:09:31 INFO - Initialising browser for kraken test... 16:09:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50911/getInfo.html -profile /tmp/tmpAhMjOf/profile 16:09:31 INFO - TEST-INFO | started process 1697 (/builds/slave/test/build/application/firefox/firefox http://localhost:50911/getInfo.html) 16:09:37 INFO - TEST-INFO | 1697: exit 0 16:09:38 INFO - Browser initialized. 16:09:38 INFO - Running cycle 1/1 for kraken test... 16:09:38 INFO - Using env: {'DISPLAY': ':0', 16:09:38 INFO - 'HOME': '/home/cltbld', 16:09:38 INFO - 'JSGC_DISABLE_POISONING': '1', 16:09:38 INFO - 'LANG': 'en_US.UTF-8', 16:09:38 INFO - 'LANGUAGE': 'en_US:en', 16:09:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:09:38 INFO - 'LOGNAME': 'cltbld', 16:09:38 INFO - 'MAIL': '/var/mail/cltbld', 16:09:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:09:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:09:38 INFO - 'MOZ_CRASHREPORTER': '1', 16:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:09:38 INFO - 'MOZ_NO_REMOTE': '1', 16:09:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:38 INFO - 'NO_EM_RESTART': '1', 16:09:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:38 INFO - 'PWD': '/builds/slave/test', 16:09:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:09:38 INFO - 'RUST_BACKTRACE': 'full', 16:09:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:09:38 INFO - 'SHELL': '/bin/bash', 16:09:38 INFO - 'SHLVL': '1', 16:09:38 INFO - 'STYLO_FORCE_ENABLED': '1', 16:09:38 INFO - 'TERM': 'linux', 16:09:38 INFO - 'TMOUT': '86400', 16:09:38 INFO - 'USER': 'cltbld', 16:09:38 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008', 16:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:38 INFO - '_': '/tools/buildbot/bin/python'} 16:09:38 INFO - TEST-INFO | started process 1889 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAhMjOf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:39 INFO - PID 1889 | 16:09:39 INFO - PID 1889 | (/builds/slave/test/build/application/firefox/firefox:1938): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:09:39 INFO - PID 1889 | 16:09:39 INFO - PID 1889 | 16:09:39 INFO - PID 1889 | (/builds/slave/test/build/application/firefox/firefox:1997): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:09:39 INFO - PID 1889 | 16:09:39 INFO - PID 1889 | __metrics Screen width/height:1600/1200 16:09:39 INFO - PID 1889 | colorDepth:24 16:09:39 INFO - PID 1889 | Browser inner width/height: 1024/768 16:09:39 INFO - PID 1889 | __metrics 16:09:49 INFO - PID 1889 | RSS: Main: 174874624 16:09:49 INFO - PID 1889 | 16:09:52 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50911/tests/kraken/driver.html?testName=audio-beat-detection) 16:09:53 INFO - PID 1889 | RSS: Main: 179118080 16:09:53 INFO - PID 1889 | 16:09:55 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50911/tests/kraken/driver.html?testName=audio-dft) 16:09:56 INFO - PID 1889 | RSS: Main: 181014528 16:09:56 INFO - PID 1889 | 16:09:59 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50911/tests/kraken/driver.html?testName=audio-fft) 16:10:00 INFO - PID 1889 | RSS: Main: 174407680 16:10:00 INFO - PID 1889 | 16:10:02 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50911/tests/kraken/driver.html?testName=audio-oscillator) 16:10:02 INFO - PID 1889 | RSS: Main: 175099904 16:10:02 INFO - PID 1889 | 16:10:05 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50911/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:10:05 INFO - PID 1889 | RSS: Main: 175407104 16:10:05 INFO - PID 1889 | 16:10:08 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50911/tests/kraken/driver.html?testName=imaging-darkroom) 16:10:09 INFO - PID 1889 | RSS: Main: 176623616 16:10:09 INFO - PID 1889 | 16:10:13 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50911/tests/kraken/driver.html?testName=imaging-desaturate) 16:10:13 INFO - PID 1889 | RSS: Main: 176201728 16:10:13 INFO - PID 1889 | 16:10:16 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50911/tests/kraken/driver.html?testName=json-parse-financial) 16:10:17 INFO - PID 1889 | RSS: Main: 176984064 16:10:17 INFO - PID 1889 | 16:10:18 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50911/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:10:19 INFO - PID 1889 | RSS: Main: 177086464 16:10:19 INFO - PID 1889 | 16:10:21 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50911/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:10:22 INFO - PID 1889 | RSS: Main: 177209344 16:10:22 INFO - PID 1889 | 16:10:24 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50911/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:10:24 INFO - PID 1889 | RSS: Main: 177057792 16:10:24 INFO - PID 1889 | 16:10:27 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:10:28 INFO - PID 1889 | RSS: Main: 177135616 16:10:28 INFO - PID 1889 | 16:10:31 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:10:31 INFO - PID 1889 | RSS: Main: 177082368 16:10:31 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | Cycle 1(1): loaded http://localhost:50911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50911/tests/kraken/driver.html?testName=ai-astar) 16:10:33 INFO - PID 1889 | RSS: Main: 178368512 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | __start_tp_report 16:10:33 INFO - PID 1889 | _x_x_mozilla_page_load 16:10:33 INFO - PID 1889 | _x_x_mozilla_page_load_details 16:10:33 INFO - PID 1889 | |i|pagename|runs| 16:10:33 INFO - PID 1889 | |0;ai-astar;98;109;106;109;108;111;114;98;106;108 16:10:33 INFO - PID 1889 | |1;audio-beat-detection;153;120;140;172;125;147;146;124;176;120 16:10:33 INFO - PID 1889 | |2;audio-dft;169;162;163;163;164;165;164;171;163;163 16:10:33 INFO - PID 1889 | |3;audio-fft;73;74;76;177;77;76;73;73;150;81 16:10:33 INFO - PID 1889 | |4;audio-oscillator;79;78;132;85;78;79;130;80;79;126 16:10:33 INFO - PID 1889 | |5;imaging-gaussian-blur;114;114;117;115;117;115;114;114;118;114 16:10:33 INFO - PID 1889 | |6;imaging-darkroom;150;154;149;155;149;150;147;150;150;150 16:10:33 INFO - PID 1889 | |7;imaging-desaturate;99;94;93;94;94;93;93;99;98;94 16:10:33 INFO - PID 1889 | |8;json-parse-financial;53;51;51;52;57;52;53;59;51;55 16:10:33 INFO - PID 1889 | |9;json-stringify-tinderbox;58;60;55;55;56;64;56;56;57;63 16:10:33 INFO - PID 1889 | |10;stanford-crypto-aes;83;73;74;75;81;74;75;73;76;74 16:10:33 INFO - PID 1889 | |11;stanford-crypto-ccm;123;124;121;119;132;123;120;118;127;163 16:10:33 INFO - PID 1889 | |12;stanford-crypto-pbkdf2;177;176;172;166;168;170;170;180;169;169 16:10:33 INFO - PID 1889 | |13;stanford-crypto-sha256-iterative;65;63;63;63;62;66;63;68;63;66 16:10:33 INFO - PID 1889 | __end_tp_report 16:10:33 INFO - PID 1889 | __start_cc_report 16:10:33 INFO - PID 1889 | _x_x_mozilla_cycle_collect,976 16:10:33 INFO - PID 1889 | __end_cc_report 16:10:33 INFO - PID 1889 | __startTimestamp1503702633953__endTimestamp 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | ------- Summary: start ------- 16:10:33 INFO - PID 1889 | Number of tests: 14 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#0] ai-astar Cycles:10 Average:106.70 Median:110.00 stddev:5.14 (4.7%) stddev-sans-first:4.39 16:10:33 INFO - PID 1889 | Values: 98.0 109.0 106.0 109.0 108.0 111.0 114.0 98.0 106.0 108.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:146.50 stddev:20.55 (14.0%) stddev-sans-first:21.43 16:10:33 INFO - PID 1889 | Values: 153.0 120.0 140.0 172.0 125.0 147.0 146.0 124.0 176.0 120.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#2] audio-dft Cycles:10 Average:164.70 Median:164.00 stddev:2.95 (1.8%) stddev-sans-first:2.68 16:10:33 INFO - PID 1889 | Values: 169.0 162.0 163.0 163.0 164.0 165.0 164.0 171.0 163.0 163.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#3] audio-fft Cycles:10 Average:93.00 Median:75.00 stddev:37.78 (50.4%) stddev-sans-first:39.37 16:10:33 INFO - PID 1889 | Values: 73.0 74.0 76.0 177.0 77.0 76.0 73.0 73.0 150.0 81.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:79.00 stddev:24.09 (30.5%) stddev-sans-first:24.88 16:10:33 INFO - PID 1889 | Values: 79.0 78.0 132.0 85.0 78.0 79.0 130.0 80.0 79.0 126.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:115.00 stddev:1.55 (1.3%) stddev-sans-first:1.58 16:10:33 INFO - PID 1889 | Values: 114.0 114.0 117.0 115.0 117.0 115.0 114.0 114.0 118.0 114.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:150.00 stddev:2.37 (1.6%) stddev-sans-first:2.51 16:10:33 INFO - PID 1889 | Values: 150.0 154.0 149.0 155.0 149.0 150.0 147.0 150.0 150.0 150.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:94.00 stddev:2.51 (2.7%) stddev-sans-first:2.24 16:10:33 INFO - PID 1889 | Values: 99.0 94.0 93.0 94.0 94.0 93.0 93.0 99.0 98.0 94.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.00 stddev:2.76 (5.2%) stddev-sans-first:2.92 16:10:33 INFO - PID 1889 | Values: 53.0 51.0 51.0 52.0 57.0 52.0 53.0 59.0 51.0 55.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#9] json-stringify-tinderbox Cycles:10 Average:58.00 Median:57.50 stddev:3.27 (5.7%) stddev-sans-first:3.46 16:10:33 INFO - PID 1889 | Values: 58.0 60.0 55.0 55.0 56.0 64.0 56.0 56.0 57.0 63.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:75.00 stddev:3.43 (4.6%) stddev-sans-first:2.45 16:10:33 INFO - PID 1889 | Values: 83.0 73.0 74.0 75.0 81.0 74.0 75.0 73.0 76.0 74.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:123.50 stddev:13.30 (10.8%) stddev-sans-first:14.03 16:10:33 INFO - PID 1889 | Values: 123.0 124.0 121.0 119.0 132.0 123.0 120.0 118.0 127.0 163.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:171.00 stddev:4.50 (2.6%) stddev-sans-first:4.34 16:10:33 INFO - PID 1889 | Values: 177.0 176.0 172.0 166.0 168.0 170.0 170.0 180.0 169.0 169.0 16:10:33 INFO - PID 1889 | 16:10:33 INFO - PID 1889 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:1.93 (3.0%) stddev-sans-first:2.03 16:10:33 INFO - PID 1889 | Values: 65.0 63.0 63.0 63.0 62.0 66.0 63.0 68.0 63.0 66.0 16:10:33 INFO - PID 1889 | -------- Summary: end -------- 16:10:33 INFO - PID 1889 | 16:10:34 INFO - PID 1889 | 16:10:34 INFO - PID 1889 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 16:10:34 INFO - PID 1889 | 16:10:34 INFO - PID 1889 | 16:10:34 INFO - PID 1889 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 16:10:34 INFO - PID 1889 | 16:10:34 INFO - PID 1889 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:10:34 INFO - TEST-INFO | 1889: exit 0 16:10:34 INFO - TEST-OK | kraken | took 63335ms 16:10:34 INFO - SUITE-END | took 1175s 16:10:35 INFO - Completed test suite (00:19:36) 16:10:35 INFO - javascript benchmark 16:10:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8442.649322532538, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3336.9666303336967, 3351.4135026370377, 3363.2432702641895, 3385.06910599585, 3425.434803257461, 6185.505159587234, 6294.653794041332, 6413.807585772429, 6420.422162005419, 6443.452306553942, 6347.49220062395, 6375.840603984902, 6469.159009328792, 6474.676266186683, 6507.967460162693, 5690.2033715279895, 5715.257016587838, 5764.0, 5844.620099693528, 5863.061910094386, 5796.188533605277, 5799.3620701722775, 5835.387284335147, 5868.9413105869035, 5893.44012318829, 5698.287714035746, 5770.971145144289, 5802.680852553087, 5860.501857342131, 5883.735231914574, 5809.302883653989, 5864.941350586482, 5884.499817515516, 5893.675847828367, 5914.319853216892, 5745.6265342753, 5789.855253618651, 5809.3609702932645, 5844.707764611795, 5874.030784920483, 6312.210973628296, 6319.778807741753, 6346.492280617539, 6366.6180029198395, 6441.194850643669, 6757.932420675828, 6799.762008329734, 6802.319768023158, 6825.419839313664, 6831.863362732767, 6804.489663275274, 6890.000949862292, 6895.586264824076, 6911.101556797635, 6913.792586222421, 6088.391160883876, 6153.446189842935, 6288.811335659938, 6330.715117819663, 6396.5842220256, 6924.6537673116145, 6945.062416573698, 6960.234374218832, 6977.023216749659, 6980.616066116311, 6645.169353830771, 6647.135872336566, 6657.0, 6657.900131498031, 6660.167479065117, 6700.396964273239, 6733.3266673333255, 6798.286179951133, 6805.2173999989645, 6812.352826481477, 6801.863962720718, 6813.7955861324235, 6859.348361905611, 6862.828429289308, 6895.34494223058, 6839.213490448662, 6847.691853866588, 6922.861542769117, 6924.688389022506, 6932.23745388007, 5232.5552328052545, 5277.23480095384, 5282.3661160660195, 5283.6037297202865, 5289.021531016774, 4687.906241875144, 4746.786394612251, 4763.904721905543, 4767.570918617341, 4787.664863459524, 5978.163057172, 6007.128966299862, 6032.095185722177, 6057.454829065406, 6071.939280607226, 6855.143107111611, 6856.794296171123, 6942.271061538466, 6952.826179345456, 6952.860942781217, 5846.503047240947, 5876.177335173079, 5935.52515798735, 6024.548158888013, 6035.30593981689, 5874.441928016831, 5876.823695289148, 5916.408359164049, 5918.201042859159, 5952.107183922446, 6692.163479564957, 6696.5647232929705, 6752.763653272112, 6786.830329241809, 6791.558548694319, 6888.104546408935, 6891.310868913069, 6959.721611135498, 6968.616726080114, 6968.721251149897, 6424.967875160594, 6549.115869357575, 6672.966635166793, 6682.46540276767, 6770.661466926733, 6799.89800153008, 6800.455963522807, 6825.863482730417, 6833.41915937131, 6833.45332373399, 6842.144731908512, 6875.140607424072, 6962.965185174042, 6966.721331146698, 6989.790306290819, 6620.304867988984, 6622.337766223532, 6642.4021838034805, 6718.630475323904, 6726.058351830846, 6506.674666266573, 6599.901001485077, 6634.369734875179, 6652.467802575686, 6711.899321510279, 6605.702743376656, 6619.801405957829, 6622.602643841385, 6626.768063117768, 6642.103316052271, 6606.075149478981, 6717.05961165447, 6734.663266836541, 6807.047013418224, 6811.761588344384, 6478.611283322827, 6483.546151769331, 6527.02094685801, 6560.0, 6574.76988305407], "value": 6240.415248613396, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.4518964130289, 840.3193413335199, 854.4488804720955, 866.2290561400353, 1112.7162573543744, 28921.710782892165, 29465.55801662974, 38659.99999999996, 39368.60631393685, 39506.60493395065, 39250.411243831295, 39509.20981580374, 39711.80144099283, 39768.80115599418, 39803.0, 7615.961920190405, 7757.612119394036, 7761.495502792329, 7770.145284018781, 7770.222977702213, 7612.391008719289, 7694.269044440769, 7694.615269236544, 7722.575258360788, 7733.613319334011, 7660.846783064336, 7714.4599878008285, 7725.266099720518, 7760.767176984699, 7772.145064042951, 7622.161562228151, 7711.691532338756, 7712.0, 7716.729914452995, 7724.922750772476, 7537.170911199763, 7541.472096953215, 7572.053493313336, 7584.734534291273, 7585.393168546559, 35391.0, 35467.0, 35526.644733552595, 35538.111547211265, 35596.28807423837, 3527.7177825773874, 3547.875824346161, 3548.840302186382, 3549.0594992327055, 3552.6980206682465, 29310.70689293101, 29401.264968375748, 29420.70579294201, 29430.117096487138, 29515.409691806257, 4841.636877234221, 4842.225243960949, 4843.636727245435, 4847.75761211938, 4881.487443818419, 180167.0, 180273.19726802694, 180408.0, 180944.0, 181598.0, 44368.0, 44495.55504444914, 44543.331850022274, 44604.330935036, 44722.55277447184, 46927.296090558666, 46981.0, 46983.29525057127, 47002.0, 47017.529824702, 15221.923890380478, 15507.534773956799, 15518.9224053879, 15521.223938803014, 15530.767038494432, 12907.354632268349, 12923.095383323078, 12932.676683082998, 12935.547255846188, 12980.480780768852, 12766.744665106831, 12935.935320323339, 12938.0, 12998.285094319903, 13125.67185820343, 5060.620453466005, 5077.543021128116, 5096.770645320896, 5127.282180494733, 5144.408393034774, 967.1537404770826, 969.8545218217324, 976.511744127936, 991.7272749993809, 1001.249063202598, 20662.173513059308, 20774.58450830975, 20971.056302466426, 21123.26068587592, 21245.46886327854, 1022.4223313827664, 1055.9313644613076, 1069.4652673663168, 1073.1629329123296, 1075.930064545802, 11437.370944598128, 11501.309921404576, 11546.249493782878, 11663.475143618558, 11669.833016698261, 220432.0, 221162.0, 221313.0, 231271.0, 234808.0, 53151.468485314654, 53383.0, 53428.19857702137, 53492.0, 53506.0, 1390.7079513302117, 1424.5085445521486, 1428.6428392901776, 1428.6499807547218, 1443.4875619155048, 25188.74811251864, 25245.873770631762, 25528.489430211663, 25650.743492564834, 26209.73790262149, 35825.64174358223, 35923.0, 36014.27971440609, 36039.81980090083, 36060.81969590135, 192156.0, 192480.0, 193156.0, 194013.0, 194619.0, 31752.841235793672, 33229.83385083059, 33720.662793372736, 33739.83130084334, 33934.66065339315, 41755.16489670129, 42866.0, 42868.57131428771, 43071.138577227655, 43192.35211471893, 31497.370052598362, 31507.842460788466, 31743.206419838763, 32058.0, 32076.51885221677, 31033.37933241368, 31146.221344465663, 31358.0, 31496.527552087195, 32197.67802321947, 35406.46890296699, 35521.467177991835, 35566.0, 35580.64419355773, 35603.10992225215, 30853.382932341676, 31065.53401698931, 31244.218894527818, 31569.0, 31718.207044824063, 23351.0, 23833.523329532967, 23900.28299151028, 23911.641325380482, 24227.75772242255], "value": 16614.635918073516, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.7575828756203, 545.9645123066998, 548.5830768615854, 566.7732906837266, 571.4942276085665, 21865.453363665878, 21977.560448791013, 22632.20787272446, 22755.203567875127, 22809.657855132187, 24583.139590114348, 25008.999640014423, 25121.87439062802, 25208.621870671956, 25319.74680253197, 6397.744090236397, 6547.3125321841235, 6552.312007239243, 6566.934330656704, 6568.146141001663, 5957.493613042897, 6458.095866578659, 6513.837154071139, 6563.3764792344655, 6571.934280657204, 6526.608403495806, 6536.313687062861, 6539.182602174728, 6544.181977252843, 6546.541742078057, 6493.123428337161, 6501.967490162566, 6510.967445162768, 6512.93487065128, 6527.151470308853, 6004.0, 6045.153678485016, 6061.66660833654, 6078.361772013964, 6088.360722124157, 26065.218043458728, 27177.320566985785, 27590.0, 28518.0, 28879.0, 1995.3315639260866, 2025.837932965374, 2029.9797002029939, 2030.0763152765455, 2030.3807338761671, 18516.722249166272, 18996.24015039397, 19045.61908761817, 19103.2358705653, 19115.90442047795, 2340.742518322983, 2435.7320694723567, 2475.777180053804, 2489.576771948773, 2504.649349091129, 129267.35366323202, 136032.31983840116, 136782.3160884189, 136800.31599842035, 136834.3158284212, 14010.0, 14129.690444414553, 14257.21585312797, 14647.633809154644, 14941.178235197169, 23046.8847655764, 23087.0, 23172.65241021386, 23192.304230873102, 23207.187748428725, 9399.483028433366, 9707.174890134276, 9752.171065459373, 9797.559109840075, 9800.0, 8886.022537520868, 8886.289096872235, 8910.108989101038, 8944.5080520572, 9038.86441703375, 7553.206913274137, 8775.561221938877, 8799.956000219958, 9040.728778136667, 9130.497822619687, 2592.3130370451845, 2614.241869857758, 2665.440717180471, 2691.6769987601615, 2741.7943654225614, 908.123660667459, 953.2802733935835, 954.0983770337103, 954.928380371461, 958.5399008475971, 17972.55068623295, 18533.907330463262, 18619.0, 18669.346572869883, 18736.53158671044, 1000.0399616368368, 1008.3042700536606, 1018.1396719771781, 1020.7958408318366, 1021.8109649714827, 10244.743881403025, 10432.791344173074, 10508.842367364496, 10646.73383165427, 10834.729131771612, 487230.0, 489601.0, 489753.0, 502185.0, 518966.0, 102872.0, 107983.46008269908, 108502.45748771206, 109869.45065274782, 110280.0, 1105.8894110588876, 1173.6596387047832, 1177.1230433327225, 1179.7345597240724, 1184.413715210976, 16050.919745401197, 16501.422450214184, 16525.33898644089, 16649.50051498457, 16780.58048548796, 59958.0, 60016.09975850279, 60165.69917150386, 62090.68954655198, 62380.68809656104, 69505.65247173731, 73372.63313683397, 75005.62497187479, 75719.86420203591, 75989.0, 12420.192687475284, 12982.15615984958, 14343.641408964859, 14389.280535973368, 14903.701925961203, 22822.543549129256, 23109.88445057764, 23544.764552354944, 24051.158209462585, 24116.0, 16730.24713887902, 16851.41020064292, 17152.656946861243, 17203.483895483154, 17241.827581724523, 16387.508374748777, 16908.23912923897, 16931.984080955182, 17042.403515876886, 17670.20484078245, 17422.216000279765, 18155.909220453814, 18307.35924242645, 18472.907635461735, 18526.629467410847, 14230.715385692436, 20929.581408371443, 21651.458713532287, 21658.241961531272, 21714.24000159987, 16028.0, 16138.273777679799, 16533.421330253383, 16676.833231668013, 16677.833221667628], "value": 11797.86374694594, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1192.3680449361837, 1233.7779199744043, 1244.1539752967983, 1245.6823510004947, 1250.1623911978973, 12433.626991190256, 14243.92878035611, 14890.255487225622, 14900.180490073042, 14937.775933361008, 12490.937545312261, 12645.936770316159, 14289.285535723224, 14847.406103755837, 14874.47939322124, 2576.665033545641, 2637.4065835187025, 2642.1545105566224, 2649.1919964410845, 2650.536156172672, 2526.0, 2534.759197876199, 2620.5807070868664, 2697.2852194168563, 2708.9051883184093, 2662.254568720761, 2666.1601595497455, 2692.717264687209, 2694.7844172466253, 2695.0567301444535, 2529.6458495810602, 2548.706898706654, 2610.2952202905203, 2668.319578507468, 2668.679758429001, 2480.7395223501453, 2521.382261345982, 2522.041624182817, 2535.1127105513106, 2570.5887058070807, 14568.49010284635, 14606.634834129232, 14674.046186997812, 14724.55826325212, 14742.484013059599, 7787.649555770004, 8039.55782431966, 8065.112837587919, 8069.677212911476, 8072.677092916277, 5230.555402790767, 5428.050091234058, 5535.806246781343, 5537.335519737658, 5538.861528461821, 1832.8075552067107, 1851.8425933795643, 1886.9150888210063, 1923.807619238079, 1936.0900376822872, 24989.125380611775, 25102.12142575001, 25520.489590208093, 25563.0, 25846.224613261445, 6485.513586481033, 6564.980109149845, 6664.966675166593, 6780.864382712318, 6789.049533065375, 16738.497845064423, 16772.496825095266, 16787.2445739942, 16791.832081679026, 16805.24376403065, 7562.886556701654, 7725.150233474314, 7806.453548251681, 7806.960965195138, 7808.180141085218, 7199.784006479709, 7203.135623725187, 7469.813254668677, 7479.065116860393, 7480.438967077491, 4948.975255123702, 5092.770825312796, 5095.8726031848755, 5141.511556402136, 5148.76830542619, 1485.8514148585054, 1499.4077339450855, 1505.503183949294, 1505.7214415333199, 1533.3713177597356, 855.1106848877098, 876.9912300877037, 890.5458216309729, 891.6700820696384, 899.6401439424152, 7296.852386944007, 7302.0142280791415, 7350.52221605594, 7358.768707992926, 7380.151282602462, 703.2405002597285, 705.8800003999332, 711.9145702515731, 716.4053835316779, 721.2823240875258, 5108.106081435734, 5245.763940622604, 5299.258103865462, 5421.972890135603, 5424.240606315119, 20376.796232037785, 21509.0, 21569.24507642225, 21712.674309885366, 21724.23965161212, 20275.0, 20864.0, 21211.045502952406, 21601.67597486039, 21653.67519487209, 9016.459012459141, 9241.306901982378, 10505.159587233004, 10931.726706832234, 10945.616903408343, 7242.782716518513, 8968.192862642394, 9022.864657030019, 9078.863817042882, 9088.136627020422, 17205.0, 17296.827031730027, 17423.651526969137, 17439.30242790274, 17493.650126997134, 17575.0, 17605.383811566535, 17703.203355849277, 17820.0, 17893.821061789735, 11687.357195354338, 12078.335691536699, 12078.577249796215, 12083.0, 12085.15403921717, 13419.86580134186, 13473.528426505025, 13586.45654173822, 13645.181289122684, 13693.178409295475, 10830.133589312994, 10933.398663073607, 11038.779224415306, 11133.721656958642, 11166.665000050012, 10793.244472886823, 10836.0, 10879.673609791718, 11005.0, 11122.44387780624, 9301.953490232505, 9469.763255918657, 9524.095210954949, 9528.047195280416, 9532.28507861927, 7058.647067646494, 7099.0, 7109.28907109285, 7121.893171602534, 7184.461165412719, 7351.8162045949275, 7388.926110739038, 7396.3343299103335, 7404.296591823768, 7569.621518923922], "value": 6437.862318879748, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.17618460524807, 499.0443301078435, 502.6456348274465, 506.98732531686704, 517.2448225590638, 6273.780417685388, 6323.146375239347, 6355.459785918197, 6419.069234960928, 6494.870102597957, 6185.505159587234, 6287.622742635445, 6338.778142765004, 6363.840903977403, 6366.872662546746, 5253.684778913258, 5259.342582177228, 5277.102892508283, 5306.973465132688, 5351.304330437038, 4992.654921202785, 4994.425641051271, 5230.163173892159, 5257.815976440826, 5264.8157314494, 4979.427365852937, 4988.775505102261, 5016.949830501685, 5045.621578381618, 5078.162103252977, 4974.552290293873, 4994.153332600419, 5001.9749901250625, 5053.166227572447, 5054.837387400881, 4413.955860441387, 4450.554944505524, 4481.6638752093395, 4520.68355215135, 4547.499775024745, 6108.511319094462, 6211.19254496917, 6228.190335256433, 6244.843878903019, 6259.186305780219, 5718.513926316268, 5762.4525670061275, 5774.364819869853, 5803.825885223408, 5811.767529298865, 6535.150430444012, 6586.868262634721, 6625.337466253384, 6663.866722665568, 6691.899621505681, 4922.163232250526, 4925.187344088221, 4935.851924442272, 4943.678660887031, 4955.504449555052, 6674.399304062609, 6767.0, 6799.0, 6905.309469053054, 7036.261192574757, 4955.851324460272, 4963.60291176705, 5027.622928280393, 5028.547430731252, 5050.621203409759, 5034.672746271481, 5068.023470846373, 5078.796848126033, 5132.204508301219, 5164.638475306768, 5711.428857114339, 5743.425657434307, 5763.789604183168, 5810.94189058104, 5819.359870414251, 6726.730930762715, 6736.292689267654, 6748.561343512656, 6758.594484330956, 6805.931940680629, 1733.50595080404, 1744.1192197939959, 1763.6560870630103, 1781.4566557199919, 1787.4995001399368, 1147.9196456247982, 1195.4560674892903, 1202.22456515547, 1209.5403746576244, 1213.9453724582415, 5867.413258674099, 5902.5277977762535, 5934.465898069195, 5937.584369094122, 5981.983062879321, 6835.487338449537, 6837.316268373223, 6857.017197518995, 6866.725330986705, 6937.3232186941, 5832.3292821326095, 5854.736536855852, 5893.292804863444, 5908.409159084057, 5926.407359264039, 3622.5471816022996, 3623.528941237675, 3640.7633503822167, 3659.8353074111196, 3662.853485860536, 6613.3056029117215, 6626.668666566652, 6676.866462670817, 6686.113303297611, 6729.226138994079, 6951.2353641099435, 7049.118860142482, 7066.7173313066905, 7077.929220707727, 7124.287571242938, 6086.330503644595, 6593.406593406617, 6603.76886808969, 6621.602703837785, 6733.528652994243, 6708.26209116987, 6737.854564724107, 6791.185057792899, 6802.319768023158, 6810.693518791567, 6680.63256520896, 6716.597004179765, 6764.289749576224, 6822.317768223138, 6851.623160726208, 6656.7004484797335, 6678.7328506859185, 6698.899516507159, 6724.899126513008, 6778.7288508459105, 4904.93992523134, 5041.596672266137, 5045.394552653705, 5083.415407228216, 5094.821681241138, 6508.511861610493, 6725.361090696376, 6756.8648627026205, 6790.080265485888, 6976.441884649317, 6531.934680653133, 6585.670716464253, 6597.934020659924, 6681.231658359351, 6685.431738302299, 6608.7026083825385, 6611.570247933869, 6639.136912201383, 6722.697478613572, 6729.798106056826, 6492.64290464029, 6578.243501997332, 6600.778855911672, 6603.504737144637, 6635.6350400728425, 6721.428678562377, 6741.1910570731825, 6787.694553745192, 6814.761483348059, 6845.315468453115, 6479.643619600967, 6498.187726534184, 6524.445422139172, 6568.1789776277965, 6578.572392794453], "value": 5132.832551102633, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [869.0831173112366, 887.2192470625853, 893.6202114101505, 901.0493928905003, 904.7511934218085, 17937.192826322815, 19089.23643054278, 19117.808821911778, 19212.13545390457, 19821.40535783929, 20362.796372036275, 21159.0, 21161.15355385783, 21167.153313867428, 21223.257185998493, 935.4761333653142, 957.6313119448993, 961.6586111930269, 961.8364877970727, 961.9855702164473, 924.6116631014957, 959.2661613865398, 963.9855402168973, 964.3249725192359, 973.1241882305945, 999.4203362049993, 1010.3584224017728, 1012.25093430861, 1024.8360262358024, 1025.3335332034162, 1015.6597539824168, 1017.5980487707315, 1017.8167929772638, 1018.96943091707, 1019.2152042926941, 748.9925100748977, 749.0861149397726, 749.089855825175, 749.2919191364163, 749.857527069855, 18208.362707305314, 18507.814921850742, 19461.708074378897, 19643.508912277164, 19645.410637680892, 20680.79319206804, 20755.377338679864, 21116.472088197763, 21164.36506904795, 21179.576408471745, 16132.516024519284, 16244.269007894673, 16349.0, 16474.670506589802, 16591.668166636722, 3673.2469843682247, 3697.1681371691425, 3712.0348709335767, 3712.1833196696953, 3713.57293911201, 122625.38687306596, 123884.0, 124622.37688811589, 127718.0, 128106.359468203, 14942.178180199975, 15413.922930385277, 15416.768748468781, 15443.61390965235, 15513.689726205414, 28754.424911501654, 29035.709642903723, 29423.558646620317, 29538.261543461154, 29585.704142958726, 8287.41988060842, 8359.707410240613, 8400.243978042006, 8407.11725268838, 8417.747467575982, 4225.112726327444, 4332.241857674894, 4337.15425492026, 4352.542982986741, 4362.432883725159, 5181.663191892515, 5226.8954620908125, 5238.711870847063, 5271.156614941591, 5313.362396512366, 907.1291560102244, 911.2436677557612, 946.4747065378643, 947.3984020147257, 951.07745486879, 648.9805305840832, 663.7477758451854, 667.9031540426611, 675.6182756742425, 686.5228666077128, 10044.497775111215, 10671.679849604525, 10727.034566889017, 12882.935585322013, 13017.153884997635, 679.4530403025611, 681.5876394781185, 685.8148299958983, 694.8089275449191, 699.0156110171938, 4975.751212439363, 5133.79464821403, 5144.71704056273, 5359.973200133974, 5737.569682273846, 113173.43413282881, 114016.42991784988, 114155.0, 114315.0, 115983.0, 95287.0, 97502.51248743852, 97739.0, 99146.55547389397, 99661.50169249253, 84942.0, 86268.56865715631, 87611.56194218989, 88111.55944220367, 88141.0, 9759.902400976183, 9884.357516761387, 10026.147777438999, 10062.044105809937, 10129.493525323851, 40235.3964690524, 41184.79407602943, 41331.0, 41599.58400416078, 44464.33303500515, 69873.30126698871, 72268.63865680849, 73633.26366736478, 73695.0, 75880.0, 17000.48998530046, 17003.40488082911, 17102.973821570744, 17314.393996210074, 17344.219510121824, 19624.90187549053, 20539.58920821605, 21041.684374734697, 21372.251971180933, 21380.893095534422, 17085.23116459787, 18183.363582274556, 18376.724349134507, 18382.908085459487, 18483.445496635122, 18369.632607348045, 18762.0, 19069.0, 19112.80887191166, 19278.518037049187, 13505.527306544223, 16306.918465407596, 18149.27402903869, 18178.181981811114, 18575.16411761501, 12459.93770031144, 13254.13848099909, 13588.45646174142, 13595.660108497366, 14167.929160354131, 13235.27206003679, 13243.271620060988, 13404.865951340362, 13768.311584420939, 14138.22239776781], "value": 8958.629677808116, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1512.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 106.0, 109.0, 108.0, 111.0, 114.0, 98.0, 106.0, 108.0], "value": 106.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 140.0, 172.0, 125.0, 147.0, 146.0, 124.0, 176.0, 120.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 162.0, 163.0, 163.0, 164.0, 165.0, 164.0, 171.0, 163.0, 163.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 76.0, 177.0, 77.0, 76.0, 73.0, 73.0, 150.0, 81.0], "value": 93.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 132.0, 85.0, 78.0, 79.0, 130.0, 80.0, 79.0, 126.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 117.0, 115.0, 117.0, 115.0, 114.0, 114.0, 118.0, 114.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 154.0, 149.0, 155.0, 149.0, 150.0, 147.0, 150.0, 150.0, 150.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 93.0, 94.0, 94.0, 93.0, 93.0, 99.0, 98.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 51.0, 52.0, 57.0, 52.0, 53.0, 59.0, 51.0, 55.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 55.0, 55.0, 56.0, 64.0, 56.0, 56.0, 57.0, 63.0], "value": 58.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 73.0, 74.0, 75.0, 81.0, 74.0, 75.0, 73.0, 76.0, 74.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 124.0, 121.0, 119.0, 132.0, 123.0, 120.0, 118.0, 127.0, 163.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 176.0, 172.0, 166.0, 168.0, 170.0, 170.0, 180.0, 169.0, 169.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 63.0, 63.0, 62.0, 66.0, 63.0, 68.0, 63.0, 66.0], "value": 64.2, "unit": "score"}]}]} 16:10:35 INFO - Return code: 0 16:10:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:10:35 INFO - # TBPL SUCCESS # 16:10:35 INFO - Running post-action listener: _package_coverage_data 16:10:35 INFO - Running post-action listener: _resource_record_post_action 16:10:35 INFO - [mozharness: 2017-08-25 23:10:35.247402Z] Finished run-tests step (success) 16:10:35 INFO - Running post-run listener: _resource_record_post_run 16:10:35 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 441073664; Read time: 16; Write time: 388692 16:10:35 INFO - TinderboxPrint: CPU usage
13.1% 16:10:35 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:10:35 INFO - TinderboxPrint: I/O write bytes / time
441,073,664 / 388,692 16:10:35 INFO - TinderboxPrint: CPU idle
8,266.9 (86.8%) 16:10:35 INFO - TinderboxPrint: CPU user
1,199.4 (12.6%) 16:10:35 INFO - TinderboxPrint: Swap in / out
0 / 0 16:10:35 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 120860672; Read time: 0; Write time: 132064 16:10:35 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:10:35 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 319090688; Read time: 0; Write time: 256608 16:10:35 INFO - Running post-run listener: _upload_blobber_files 16:10:35 INFO - Blob upload gear active. 16:10:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:10:35 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 16:10:35 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'] 16:10:35 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 16:10:36 INFO - (blobuploader) - INFO - Open directory for files ... 16:10:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:10:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:10:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:42 INFO - (blobuploader) - INFO - Done attempting. 16:10:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:10:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:10:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:43 INFO - (blobuploader) - INFO - Done attempting. 16:10:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:10:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:10:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:44 INFO - (blobuploader) - INFO - Done attempting. 16:10:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:10:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:10:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:45 INFO - (blobuploader) - INFO - Done attempting. 16:10:45 INFO - (blobuploader) - INFO - Iteration through files over. 16:10:45 INFO - Return code: 0 16:10:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:10:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:10:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e33976d4bc24e478492c1580e856b48430e3ac63cd5d6c4fa50db87704e42f62a7009fe71f45772da81325785a304b5e0ea7172fd5b28e3931d6d524cdcf3235", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b497e15707c317996c4516840ea0f8deb2c581c20d7510303d511866aee90d887a30e439c31d5ecd4d8cd0e2c1539b333e9acc9aff6410a4dddadc34d6c8d137", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c4936c4a501e8221ea2ca0b3e29dc959df18c796bc29456878b792d1d6c7fb7c504e8258f579793e8ab0a06d51b7604c7cd74694c55938a0fe8ff96fc1698f5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75964dfb3e9b904fb8a4852c745546dcbe9d5409b0402bba68665a6f2008641477cd3cbc2308507ef34e0ed97c4b15cd9c5e64c6d0157462a905e3c8ca9dbbbf"} 16:10:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:10:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:10:45 INFO - Contents: 16:10:45 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e33976d4bc24e478492c1580e856b48430e3ac63cd5d6c4fa50db87704e42f62a7009fe71f45772da81325785a304b5e0ea7172fd5b28e3931d6d524cdcf3235", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b497e15707c317996c4516840ea0f8deb2c581c20d7510303d511866aee90d887a30e439c31d5ecd4d8cd0e2c1539b333e9acc9aff6410a4dddadc34d6c8d137", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c4936c4a501e8221ea2ca0b3e29dc959df18c796bc29456878b792d1d6c7fb7c504e8258f579793e8ab0a06d51b7604c7cd74694c55938a0fe8ff96fc1698f5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75964dfb3e9b904fb8a4852c745546dcbe9d5409b0402bba68665a6f2008641477cd3cbc2308507ef34e0ed97c4b15cd9c5e64c6d0157462a905e3c8ca9dbbbf"} 16:10:45 INFO - Running post-run listener: copy_logs_to_upload_dir 16:10:45 INFO - Copying logs to upload dir... 16:10:45 INFO - mkdir: /builds/slave/test/build/upload/logs 16:10:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.169966 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-25 16:10:45.879284) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 16:10:45.887413) ========= 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=eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008 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/e33976d4bc24e478492c1580e856b48430e3ac63cd5d6c4fa50db87704e42f62a7009fe71f45772da81325785a304b5e0ea7172fd5b28e3931d6d524cdcf3235", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b497e15707c317996c4516840ea0f8deb2c581c20d7510303d511866aee90d887a30e439c31d5ecd4d8cd0e2c1539b333e9acc9aff6410a4dddadc34d6c8d137", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c4936c4a501e8221ea2ca0b3e29dc959df18c796bc29456878b792d1d6c7fb7c504e8258f579793e8ab0a06d51b7604c7cd74694c55938a0fe8ff96fc1698f5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75964dfb3e9b904fb8a4852c745546dcbe9d5409b0402bba68665a6f2008641477cd3cbc2308507ef34e0ed97c4b15cd9c5e64c6d0157462a905e3c8ca9dbbbf"} build_url:https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011367 build_url: 'https://queue.taskcluster.net/v1/task/dZXUCCYSRt2PJV-HC3Hz2w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e33976d4bc24e478492c1580e856b48430e3ac63cd5d6c4fa50db87704e42f62a7009fe71f45772da81325785a304b5e0ea7172fd5b28e3931d6d524cdcf3235", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b497e15707c317996c4516840ea0f8deb2c581c20d7510303d511866aee90d887a30e439c31d5ecd4d8cd0e2c1539b333e9acc9aff6410a4dddadc34d6c8d137", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c4936c4a501e8221ea2ca0b3e29dc959df18c796bc29456878b792d1d6c7fb7c504e8258f579793e8ab0a06d51b7604c7cd74694c55938a0fe8ff96fc1698f5", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75964dfb3e9b904fb8a4852c745546dcbe9d5409b0402bba68665a6f2008641477cd3cbc2308507ef34e0ed97c4b15cd9c5e64c6d0157462a905e3c8ca9dbbbf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 16:10:45.917457) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 16:10:45.917809) ========= 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=eca2ab57546de7b41f256b19000001ef-1503701387.400538-367961008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 16:10:46.003137) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 16:10:46.003506) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 16:10:46.003849) ========= ========= Total master_lag: 0.22 =========