builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-070 starttime: 1506798057.82 results: success (0) revision: 8873432294c4ae1431357410497676fbd3e1f855 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 12:00:57.815696) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 12:00:57.816048) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 12:00:57.816476) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 12:00:57.891467) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 12:00:57.891766) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-30 12:00:58-- 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% 226M=0s 2017-09-30 12:00:58 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.260752 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 12:00:58.267436) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 12:00:58.271167) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019708 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 12:00:58.368640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2017-09-30 12:00:58.368962) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8873432294c4ae1431357410497676fbd3e1f855 --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 8873432294c4ae1431357410497676fbd3e1f855 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-30 12:00:58,476 truncating revision to first 12 chars 2017-09-30 12:00:58,476 Setting DEBUG logging. 2017-09-30 12:00:58,476 attempt 1/10 2017-09-30 12:00:58,476 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8873432294c4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-30 12:00:59,125 sleeping for 9.00s (attempt 1/10) 2017-09-30 12:01:08,134 attempt 2/10 2017-09-30 12:01:08,134 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8873432294c4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-30 12:01:08,493 unpacking tar archive at: mozilla-central-8873432294c4/testing/mozharness/ program finished with exit code 0 elapsedTime=10.285936 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2017-09-30 12:01:08.675312) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 12:01:08.675622) ========= script_repo_revision: 8873432294c4ae1431357410497676fbd3e1f855 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 12:01:08.675989) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-30 12:01:08.676246) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-30 12:01:08.695106) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-30 12:01:08.695489) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:01:09 INFO - MultiFileLogger online at 20170930 12:01:09 in /builds/slave/test 12:01:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:01:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:01:09 INFO - {'append_to_log': False, 12:01:09 INFO - 'base_work_dir': '/builds/slave/test', 12:01:09 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:01:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:01:09 INFO - 'branch': 'Firefox-Non-PGO', 12:01:09 INFO - 'buildbot_json_path': 'buildprops.json', 12:01:09 INFO - 'code_coverage': False, 12:01:09 INFO - 'config_files': ('talos/linux_config.py',), 12:01:09 INFO - 'default_actions': ('clobber', 12:01:09 INFO - 'read-buildbot-config', 12:01:09 INFO - 'download-and-extract', 12:01:09 INFO - 'populate-webroot', 12:01:09 INFO - 'create-virtualenv', 12:01:09 INFO - 'install', 12:01:09 INFO - 'setup-mitmproxy', 12:01:09 INFO - 'run-tests'), 12:01:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:01:09 INFO - 'disable_ccov_upload': False, 12:01:09 INFO - 'disable_stylo': False, 12:01:09 INFO - 'download_minidump_stackwalk': True, 12:01:09 INFO - 'download_symbols': 'ondemand', 12:01:09 INFO - 'enable_stylo': False, 12:01:09 INFO - 'enable_webrender': False, 12:01:09 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:01:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:01:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:01:09 INFO - 'gecko_profile': False, 12:01:09 INFO - 'gecko_profile_interval': 0, 12:01:09 INFO - 'installer_path': 'installer.exe', 12:01:09 INFO - 'log_level': 'info', 12:01:09 INFO - 'log_name': 'talos', 12:01:09 INFO - 'log_to_console': True, 12:01:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:01:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:01:09 INFO - 'opt_config_files': (), 12:01:09 INFO - 'pip_index': False, 12:01:09 INFO - 'suite': 'dromaeojs-e10s', 12:01:09 INFO - 'system_bits': '32', 12:01:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:01:09 INFO - 'title': 'talos-linux64-ix-070', 12:01:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:01:09 INFO - 'use_talos_json': True, 12:01:09 INFO - 'verify': 'False', 12:01:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:01:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:01:09 INFO - 'work_dir': 'build'} 12:01:09 INFO - [mozharness: 2017-09-30 19:01:09.039020Z] Running clobber step. 12:01:09 INFO - Running pre-action listener: _resource_record_pre_action 12:01:09 INFO - Running main action method: clobber 12:01:09 INFO - rmtree: /builds/slave/test/build 12:01:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:01:09 INFO - Running post-action listener: _resource_record_post_action 12:01:09 INFO - [mozharness: 2017-09-30 19:01:09.989473Z] Finished clobber step (success) 12:01:09 INFO - [mozharness: 2017-09-30 19:01:09.989570Z] Running read-buildbot-config step. 12:01:09 INFO - Running pre-action listener: _resource_record_pre_action 12:01:09 INFO - Running main action method: read_buildbot_config 12:01:09 INFO - Using buildbot properties: 12:01:09 INFO - { 12:01:09 INFO - "project": "", 12:01:09 INFO - "product": "firefox", 12:01:09 INFO - "who": "ffxbld@noreply.mozilla.org", 12:01:09 INFO - "installer_path": "public/build/target.tar.bz2", 12:01:09 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:01:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:01:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 12:01:09 INFO - "stage_platform": "linux64", 12:01:09 INFO - "basedir": "/builds/slave/test", 12:01:09 INFO - "buildnumber": 885, 12:01:09 INFO - "platform": "ubuntu64_hw", 12:01:09 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:01:09 INFO - "slavebuilddir": "test", 12:01:09 INFO - "taskId": "GYB7czyuT8er4MWwiXfXGg", 12:01:09 INFO - "branch": "mozilla-central", 12:01:09 INFO - "script_repo_revision": "production", 12:01:09 INFO - "revision": "8873432294c4ae1431357410497676fbd3e1f855", 12:01:09 INFO - "slavename": "talos-linux64-ix-070", 12:01:09 INFO - "repo_path": "mozilla-central" 12:01:09 INFO - } 12:01:09 INFO - Finding installer, test and symbols from parent task. 12:01:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GYB7czyuT8er4MWwiXfXGg'}, attempt #1 12:01:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GYB7czyuT8er4MWwiXfXGg'}, attempt #1 12:01:11 INFO - Task dependencies: K5-cUQKoRSWuOasNB_ERwQ 12:01:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ'}, attempt #1 12:01:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.tar.bz2 12:01:11 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.test_packages.json 12:01:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.crashreporter-symbols.zip 12:01:11 INFO - Running post-action listener: _resource_record_post_action 12:01:11 INFO - [mozharness: 2017-09-30 19:01:11.563655Z] Finished read-buildbot-config step (success) 12:01:11 INFO - [mozharness: 2017-09-30 19:01:11.563874Z] Running download-and-extract step. 12:01:11 INFO - Running pre-action listener: _resource_record_pre_action 12:01:11 INFO - Running main action method: download_and_extract 12:01:11 INFO - mkdir: /builds/slave/test/build/tests 12:01:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:11 INFO - trying https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.test_packages.json 12:01:11 INFO - Downloading https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:01:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:01:12 INFO - Downloaded 989 bytes. 12:01:12 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:01:12 INFO - Using the following test package requirements: 12:01:12 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:01:12 INFO - u'common': [u'target.common.tests.zip'], 12:01:12 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:01:12 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:01:12 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:01:12 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:01:12 INFO - u'mozbase': [u'target.common.tests.zip'], 12:01:12 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:01:12 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:01:12 INFO - u'web-platform': [u'target.common.tests.zip', 12:01:12 INFO - u'target.web-platform.tests.tar.gz'], 12:01:12 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:01:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:01:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.common.tests.zip 12:01:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:01:12 INFO - Fetch https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.common.tests.zip into memory 12:01:14 INFO - Content-Length response header: 55366763 12:01:14 INFO - Bytes received: 55366763 12:01:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.talos.tests.zip 12:01:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:01:25 INFO - Fetch https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.talos.tests.zip into memory 12:01:27 INFO - Content-Length response header: 14522385 12:01:27 INFO - Bytes received: 14522385 12:01: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')]} 12:01:27 INFO - trying https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.tar.bz2 12:01:27 INFO - Downloading https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:01:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:01:30 INFO - Downloaded 61768290 bytes. 12:01:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.tar.bz2 12:01:30 INFO - mkdir: /builds/slave/test/properties 12:01:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:01:30 INFO - Writing to file /builds/slave/test/properties/build_url 12:01:30 INFO - Contents: 12:01:30 INFO - build_url:https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.tar.bz2 12:01:30 INFO - Running post-action listener: _resource_record_post_action 12:01:30 INFO - Running post-action listener: find_tests_for_verification 12:01:30 INFO - Running post-action listener: set_extra_try_arguments 12:01:30 INFO - [mozharness: 2017-09-30 19:01:30.602214Z] Finished download-and-extract step (success) 12:01:30 INFO - [mozharness: 2017-09-30 19:01:30.602443Z] Running populate-webroot step. 12:01:30 INFO - Running pre-action listener: _resource_record_pre_action 12:01:30 INFO - Running main action method: populate_webroot 12:01:30 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:01:30 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['tresize', 12:01:30 INFO - 'tcanvasmark']}, 12:01:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:01:30 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['dromaeo_css', 12:01:30 INFO - 'kraken']}, 12:01:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:01:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:30 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:01:30 INFO - 'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:01:30 INFO - 'tests': ['damp', 'tps']}, 12:01:30 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:01:30 INFO - 'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['damp', 'tps']}, 12:01:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:01:30 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['dromaeo_dom']}, 12:01:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:01:30 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['basic_compositor_video', 12:01:30 INFO - 'glvideo']}, 12:01:30 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:01:30 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:01:30 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:01:30 INFO - 'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['ts_paint_webext', 12:01:30 INFO - 'tp5o_webext']}, 12:01:30 INFO - 'other-e10s': {'tests': ['a11yr', 12:01:30 INFO - 'ts_paint', 12:01:30 INFO - 'tpaint', 12:01:30 INFO - 'sessionrestore', 12:01:30 INFO - 'sessionrestore_many_windows', 12:01:30 INFO - 'sessionrestore_no_auto_restore', 12:01:30 INFO - 'tabpaint', 12:01:30 INFO - 'cpstartup']}, 12:01:30 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['a11yr', 12:01:30 INFO - 'ts_paint', 12:01:30 INFO - 'tpaint', 12:01:30 INFO - 'sessionrestore', 12:01:30 INFO - 'sessionrestore_many_windows', 12:01:30 INFO - 'sessionrestore_no_auto_restore', 12:01:30 INFO - 'tabpaint', 12:01:30 INFO - 'cpstartup']}, 12:01:30 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 12:01:30 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:01:30 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['perf_reftest_singletons']}, 12:01:30 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['perf_reftest']}, 12:01:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:01:30 INFO - 'tsvgr_opacity', 12:01:30 INFO - 'tart', 12:01:30 INFO - 'tscrollx', 12:01:30 INFO - 'tsvg_static']}, 12:01:30 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['tsvgx', 12:01:30 INFO - 'tsvgr_opacity', 12:01:30 INFO - 'tart', 12:01:30 INFO - 'tscrollx', 12:01:30 INFO - 'tsvg_static']}, 12:01:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:01:30 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:01:30 INFO - 'talos_options': ['--disable-stylo'], 12:01:30 INFO - 'tests': ['tp5o']}, 12:01:30 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:01:30 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:01:30 INFO - 'talos_options': ['--mitmproxy', 12:01:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:01:30 INFO - '--firstNonBlankPaint'], 12:01:30 INFO - 'tests': ['tp6_google', 12:01:30 INFO - 'tp6_youtube', 12:01:30 INFO - 'tp6_amazon', 12:01:30 INFO - 'tp6_facebook']}, 12:01:30 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:01:30 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:01:30 INFO - 'talos_options': ['--disable-stylo', 12:01:30 INFO - '--mitmproxy', 12:01:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:01:30 INFO - '--firstNonBlankPaint'], 12:01:30 INFO - 'tests': ['tp6_google', 12:01:30 INFO - 'tp6_youtube', 12:01:30 INFO - 'tp6_amazon', 12:01:30 INFO - 'tp6_facebook']}, 12:01:30 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:01:30 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:01:30 INFO - 'talos_options': ['--stylo-threads=1', 12:01:30 INFO - '--mitmproxy', 12:01:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:01:30 INFO - '--firstNonBlankPaint'], 12:01:30 INFO - 'tests': ['tp6_google', 12:01:30 INFO - 'tp6_youtube', 12:01:30 INFO - 'tp6_amazon', 12:01:30 INFO - 'tp6_facebook']}, 12:01:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:01:30 INFO - 'talos_options': ['--xperf_path', 12:01:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:30 INFO - 'tests': ['tp5n']}, 12:01:30 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:01:30 INFO - 'talos_options': ['--disable-stylo', 12:01:30 INFO - '--xperf_path', 12:01:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:30 INFO - 'tests': ['tp5n']}}} 12:01:30 INFO - Running post-action listener: _resource_record_post_action 12:01:30 INFO - [mozharness: 2017-09-30 19:01:30.619602Z] Finished populate-webroot step (success) 12:01:30 INFO - [mozharness: 2017-09-30 19:01:30.619692Z] Running create-virtualenv step. 12:01:30 INFO - Running pre-action listener: _resource_record_pre_action 12:01:30 INFO - Running main action method: create_virtualenv 12:01:30 INFO - Creating virtualenv /builds/slave/test/build/venv 12:01:30 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:01:30 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:01:30 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:01:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:01:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:01:31 INFO - Installing distribute.............................................................................................................................................................................................done. 12:01:31 INFO - Installing pip...............done. 12:01:31 INFO - Return code: 0 12:01:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:01:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:01:31 INFO - Reading from file tmpfile_stdout 12:01:31 INFO - Output received: 12:01:31 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:01:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01: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')]} 12:01:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:31 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 0x26241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ecb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29919c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ed60>, '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 0x298fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298fef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:01:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:01:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:01:31 INFO - Using env: {'DISPLAY': ':0', 12:01:31 INFO - 'HOME': '/home/cltbld', 12:01:31 INFO - 'LANG': 'en_US.UTF-8', 12:01:31 INFO - 'LANGUAGE': 'en_US:en', 12:01:31 INFO - 'LOGNAME': 'cltbld', 12:01:31 INFO - 'MAIL': '/var/mail/cltbld', 12:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:31 INFO - 'MOZ_NO_REMOTE': '1', 12:01:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:31 INFO - 'NO_EM_RESTART': '1', 12:01:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:31 INFO - 'PWD': '/builds/slave/test', 12:01:31 INFO - 'SHELL': '/bin/bash', 12:01:31 INFO - 'SHLVL': '1', 12:01:31 INFO - 'TERM': 'linux', 12:01:31 INFO - 'TMOUT': '86400', 12:01:31 INFO - 'USER': 'cltbld', 12:01:31 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:31 INFO - '_': '/tools/buildbot/bin/python'} 12:01:33 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:01:33 INFO - Downloading/unpacking pip>=1.5 12:01:33 INFO - Running setup.py egg_info for package pip 12:01:33 INFO - warning: no files found matching 'pip/cacert.pem' 12:01:33 INFO - warning: no files found matching '*.html' under directory 'docs' 12:01:33 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:01:33 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:01:33 INFO - Installing collected packages: pip 12:01:33 INFO - Found existing installation: pip 1.1 12:01:33 INFO - Uninstalling pip: 12:01:33 INFO - Successfully uninstalled pip 12:01:33 INFO - Running setup.py install for pip 12:01:33 INFO - warning: no files found matching 'pip/cacert.pem' 12:01:33 INFO - warning: no files found matching '*.html' under directory 'docs' 12:01:33 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:01:33 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:01:33 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:01:33 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:01:33 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:01:33 INFO - Successfully installed pip 12:01:33 INFO - Cleaning up... 12:01:33 INFO - Return code: 0 12:01:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:33 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 0x26241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ecb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29919c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ed60>, '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 0x298fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298fef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:01:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:01:33 INFO - Using env: {'DISPLAY': ':0', 12:01:33 INFO - 'HOME': '/home/cltbld', 12:01:33 INFO - 'LANG': 'en_US.UTF-8', 12:01:33 INFO - 'LANGUAGE': 'en_US:en', 12:01:33 INFO - 'LOGNAME': 'cltbld', 12:01:33 INFO - 'MAIL': '/var/mail/cltbld', 12:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:33 INFO - 'MOZ_NO_REMOTE': '1', 12:01:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:33 INFO - 'NO_EM_RESTART': '1', 12:01:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:33 INFO - 'PWD': '/builds/slave/test', 12:01:33 INFO - 'SHELL': '/bin/bash', 12:01:33 INFO - 'SHLVL': '1', 12:01:33 INFO - 'TERM': 'linux', 12:01:33 INFO - 'TMOUT': '86400', 12:01:33 INFO - 'USER': 'cltbld', 12:01:33 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:33 INFO - '_': '/tools/buildbot/bin/python'} 12:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:33 INFO - Downloading/unpacking psutil>=3.1.1 12:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:01:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:35 INFO - Installing collected packages: psutil 12:01:35 INFO - Running setup.py install for psutil 12:01:35 INFO - building 'psutil._psutil_linux' extension 12:01:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:01:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:01:36 INFO - building 'psutil._psutil_posix' extension 12:01:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:01:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:01:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:37 INFO - Successfully installed psutil 12:01:37 INFO - Cleaning up... 12:01:37 INFO - Return code: 0 12:01:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01: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')]} 12:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:37 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 0x26241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ecb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29919c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ed60>, '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 0x298fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298fef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:01:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:01:37 INFO - Using env: {'DISPLAY': ':0', 12:01:37 INFO - 'HOME': '/home/cltbld', 12:01:37 INFO - 'LANG': 'en_US.UTF-8', 12:01:37 INFO - 'LANGUAGE': 'en_US:en', 12:01:37 INFO - 'LOGNAME': 'cltbld', 12:01:37 INFO - 'MAIL': '/var/mail/cltbld', 12:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:37 INFO - 'MOZ_NO_REMOTE': '1', 12:01:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:37 INFO - 'NO_EM_RESTART': '1', 12:01:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:37 INFO - 'PWD': '/builds/slave/test', 12:01:37 INFO - 'SHELL': '/bin/bash', 12:01:37 INFO - 'SHLVL': '1', 12:01:37 INFO - 'TERM': 'linux', 12:01:37 INFO - 'TMOUT': '86400', 12:01:37 INFO - 'USER': 'cltbld', 12:01:37 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:37 INFO - '_': '/tools/buildbot/bin/python'} 12:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:01: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 12:01: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 12:01: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 12:01: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 12:01:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:01:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:01:38 INFO - Installing collected packages: mozsystemmonitor 12:01:38 INFO - Running setup.py install for mozsystemmonitor 12:01:38 INFO - Successfully installed mozsystemmonitor 12:01:38 INFO - Cleaning up... 12:01:38 INFO - Return code: 0 12:01:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:38 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 0x26241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ecb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29919c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ed60>, '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 0x298fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298fef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:01:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:01:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:01:38 INFO - Using env: {'DISPLAY': ':0', 12:01:38 INFO - 'HOME': '/home/cltbld', 12:01:38 INFO - 'LANG': 'en_US.UTF-8', 12:01:38 INFO - 'LANGUAGE': 'en_US:en', 12:01:38 INFO - 'LOGNAME': 'cltbld', 12:01:38 INFO - 'MAIL': '/var/mail/cltbld', 12:01:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:38 INFO - 'MOZ_NO_REMOTE': '1', 12:01:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:38 INFO - 'NO_EM_RESTART': '1', 12:01:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:38 INFO - 'PWD': '/builds/slave/test', 12:01:38 INFO - 'SHELL': '/bin/bash', 12:01:38 INFO - 'SHLVL': '1', 12:01:38 INFO - 'TERM': 'linux', 12:01:38 INFO - 'TMOUT': '86400', 12:01:38 INFO - 'USER': 'cltbld', 12:01:38 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:01:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:38 INFO - '_': '/tools/buildbot/bin/python'} 12:01:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:38 INFO - Downloading/unpacking jsonschema==2.5.1 12:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:01:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:40 INFO - Downloading functools32-3.2.3-2.tar.gz 12:01:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:01:40 INFO - warning: no files found matching '*.txt' 12:01:40 INFO - no previously-included directories found matching 'build' 12:01:40 INFO - no previously-included directories found matching 'dist' 12:01:40 INFO - no previously-included directories found matching '.git*' 12:01:40 INFO - Installing collected packages: jsonschema, functools32 12:01:40 INFO - Running setup.py install for functools32 12:01:40 INFO - warning: no files found matching '*.txt' 12:01:40 INFO - no previously-included directories found matching 'build' 12:01:40 INFO - no previously-included directories found matching 'dist' 12:01:40 INFO - no previously-included directories found matching '.git*' 12:01:40 INFO - Successfully installed jsonschema functools32 12:01:40 INFO - Cleaning up... 12:01:40 INFO - Return code: 0 12:01:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01: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')]} 12:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01: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', '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 0x26241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ecb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29919c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ed60>, '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 0x298fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298fef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:01: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:01: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 functools32==3.2.3-2 12:01:40 INFO - Using env: {'DISPLAY': ':0', 12:01:40 INFO - 'HOME': '/home/cltbld', 12:01:40 INFO - 'LANG': 'en_US.UTF-8', 12:01:40 INFO - 'LANGUAGE': 'en_US:en', 12:01:40 INFO - 'LOGNAME': 'cltbld', 12:01:40 INFO - 'MAIL': '/var/mail/cltbld', 12:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:40 INFO - 'MOZ_NO_REMOTE': '1', 12:01:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:40 INFO - 'NO_EM_RESTART': '1', 12:01:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:40 INFO - 'PWD': '/builds/slave/test', 12:01:40 INFO - 'SHELL': '/bin/bash', 12:01:40 INFO - 'SHLVL': '1', 12:01:40 INFO - 'TERM': 'linux', 12:01:40 INFO - 'TMOUT': '86400', 12:01:40 INFO - 'USER': 'cltbld', 12:01:40 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:40 INFO - '_': '/tools/buildbot/bin/python'} 12:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:01:40 INFO - Cleaning up... 12:01:40 INFO - Return code: 0 12:01:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01: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')]} 12:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01: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', '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 0x26241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ecb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29919c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ed60>, '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 0x298fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298fef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:01: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:01: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 blobuploader==1.2.4 12:01:40 INFO - Using env: {'DISPLAY': ':0', 12:01:40 INFO - 'HOME': '/home/cltbld', 12:01:40 INFO - 'LANG': 'en_US.UTF-8', 12:01:40 INFO - 'LANGUAGE': 'en_US:en', 12:01:40 INFO - 'LOGNAME': 'cltbld', 12:01:40 INFO - 'MAIL': '/var/mail/cltbld', 12:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:40 INFO - 'MOZ_NO_REMOTE': '1', 12:01:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:40 INFO - 'NO_EM_RESTART': '1', 12:01:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:40 INFO - 'PWD': '/builds/slave/test', 12:01:40 INFO - 'SHELL': '/bin/bash', 12:01:40 INFO - 'SHLVL': '1', 12:01:40 INFO - 'TERM': 'linux', 12:01:40 INFO - 'TMOUT': '86400', 12:01:40 INFO - 'USER': 'cltbld', 12:01:40 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:40 INFO - '_': '/tools/buildbot/bin/python'} 12:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:40 INFO - Downloading/unpacking blobuploader==1.2.4 12:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:41 INFO - Downloading blobuploader-1.2.4.tar.gz 12:01:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:01:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:01:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:42 INFO - Downloading docopt-0.6.1.tar.gz 12:01:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:01:42 INFO - Installing collected packages: blobuploader, requests, docopt 12:01:42 INFO - Running setup.py install for blobuploader 12:01:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:01:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:01:42 INFO - Running setup.py install for requests 12:01:43 INFO - Running setup.py install for docopt 12:01:43 INFO - Successfully installed blobuploader requests docopt 12:01:43 INFO - Cleaning up... 12:01:43 INFO - Return code: 0 12:01:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:43 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 0x26241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ecb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29919c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ed60>, '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 0x298fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298fef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:01:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:01:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:01:43 INFO - Using env: {'DISPLAY': ':0', 12:01:43 INFO - 'HOME': '/home/cltbld', 12:01:43 INFO - 'LANG': 'en_US.UTF-8', 12:01:43 INFO - 'LANGUAGE': 'en_US:en', 12:01:43 INFO - 'LOGNAME': 'cltbld', 12:01:43 INFO - 'MAIL': '/var/mail/cltbld', 12:01:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:43 INFO - 'MOZ_NO_REMOTE': '1', 12:01:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:43 INFO - 'NO_EM_RESTART': '1', 12:01:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:43 INFO - 'PWD': '/builds/slave/test', 12:01:43 INFO - 'SHELL': '/bin/bash', 12:01:43 INFO - 'SHLVL': '1', 12:01:43 INFO - 'TERM': 'linux', 12:01:43 INFO - 'TMOUT': '86400', 12:01:43 INFO - 'USER': 'cltbld', 12:01:43 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:01:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:43 INFO - '_': '/tools/buildbot/bin/python'} 12:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:01:43 INFO - Running setup.py (path:/tmp/pip-X8wlOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:01:43 INFO - Running setup.py (path:/tmp/pip-PpYaBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:01:43 INFO - Running setup.py (path:/tmp/pip-jd04am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:01:43 INFO - Running setup.py (path:/tmp/pip-CND081-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:01:43 INFO - Running setup.py (path:/tmp/pip-4T1mxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:01:43 INFO - Running setup.py (path:/tmp/pip-rIFPK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:01:43 INFO - Running setup.py (path:/tmp/pip-0U3PqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:01:43 INFO - Running setup.py (path:/tmp/pip-l6jq_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:01:43 INFO - Running setup.py (path:/tmp/pip-ZvMKBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:01:44 INFO - Running setup.py (path:/tmp/pip-qruh3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:01:44 INFO - Running setup.py (path:/tmp/pip-ziySho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:01:44 INFO - Running setup.py (path:/tmp/pip-HfwIOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:01:44 INFO - Running setup.py (path:/tmp/pip-v4rdnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:01:44 INFO - Running setup.py (path:/tmp/pip-Xa3lhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:44 INFO - Running setup.py (path:/tmp/pip-RfWwgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:01:44 INFO - Running setup.py (path:/tmp/pip-HTWBie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:01:44 INFO - Running setup.py (path:/tmp/pip-Qqbg50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:01:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:01:44 INFO - Running setup.py install for manifestparser 12:01:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:01:44 INFO - Running setup.py install for mozcrash 12:01:44 INFO - Running setup.py install for mozdebug 12:01:44 INFO - Running setup.py install for mozdevice 12:01:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:01:44 INFO - Running setup.py install for mozfile 12:01:45 INFO - Running setup.py install for mozhttpd 12:01:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:01:45 INFO - Running setup.py install for mozinfo 12:01:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:01:45 INFO - Running setup.py install for mozInstall 12:01:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:01:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:01:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:01:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:01:45 INFO - Running setup.py install for mozleak 12:01:45 INFO - Running setup.py install for mozlog 12:01:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:01:45 INFO - Running setup.py install for moznetwork 12:01:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:01:45 INFO - Running setup.py install for mozprocess 12:01:45 INFO - Running setup.py install for mozprofile 12:01:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:01:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:01:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:01:45 INFO - Running setup.py install for mozrunner 12:01:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:01:46 INFO - Running setup.py install for mozscreenshot 12:01:46 INFO - Running setup.py install for moztest 12:01:46 INFO - Running setup.py install for mozversion 12:01:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:01:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:01:46 INFO - Cleaning up... 12:01:46 INFO - Return code: 0 12:01:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:46 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 0x26241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ecb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29919c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ed60>, '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 0x298fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298fef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:01:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:01:46 INFO - Using env: {'DISPLAY': ':0', 12:01:46 INFO - 'HOME': '/home/cltbld', 12:01:46 INFO - 'LANG': 'en_US.UTF-8', 12:01:46 INFO - 'LANGUAGE': 'en_US:en', 12:01:46 INFO - 'LOGNAME': 'cltbld', 12:01:46 INFO - 'MAIL': '/var/mail/cltbld', 12:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:46 INFO - 'MOZ_NO_REMOTE': '1', 12:01:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:46 INFO - 'NO_EM_RESTART': '1', 12:01:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:46 INFO - 'PWD': '/builds/slave/test', 12:01:46 INFO - 'SHELL': '/bin/bash', 12:01:46 INFO - 'SHLVL': '1', 12:01:46 INFO - 'TERM': 'linux', 12:01:46 INFO - 'TMOUT': '86400', 12:01:46 INFO - 'USER': 'cltbld', 12:01:46 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:46 INFO - '_': '/tools/buildbot/bin/python'} 12:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:01:46 INFO - Running setup.py (path:/tmp/pip-GJt105-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:01:46 INFO - Running setup.py (path:/tmp/pip-ZS9Kn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:01:46 INFO - Running setup.py (path:/tmp/pip-WI9Hnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:01:46 INFO - Running setup.py (path:/tmp/pip-9g0aVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:01:46 INFO - Running setup.py (path:/tmp/pip-1HtJXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:01:46 INFO - Running setup.py (path:/tmp/pip-adwnkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:01:46 INFO - Running setup.py (path:/tmp/pip-mz2zcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:01:47 INFO - Running setup.py (path:/tmp/pip-caf6K7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:01:47 INFO - Running setup.py (path:/tmp/pip-1aFf1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:01:47 INFO - Running setup.py (path:/tmp/pip-MIkppE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:01:47 INFO - Running setup.py (path:/tmp/pip-ZOMb2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:01:47 INFO - Running setup.py (path:/tmp/pip-MhtG2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:01:47 INFO - Running setup.py (path:/tmp/pip-5w_1j3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:01:47 INFO - Running setup.py (path:/tmp/pip-RCXDXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:47 INFO - Running setup.py (path:/tmp/pip-o6smB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:01:47 INFO - Running setup.py (path:/tmp/pip-mVzVQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:01:47 INFO - Running setup.py (path:/tmp/pip-dpgxY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:01:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:48 INFO - Downloading blessings-1.6.tar.gz 12:01:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:01:48 INFO - Installing collected packages: blessings 12:01:48 INFO - Running setup.py install for blessings 12:01:49 INFO - Successfully installed blessings 12:01:49 INFO - Cleaning up... 12:01:49 INFO - Return code: 0 12:01:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:01:49 INFO - Reading from file tmpfile_stdout 12:01:49 INFO - Current package versions: 12:01:49 INFO - argparse == 1.2.1 12:01:49 INFO - blessings == 1.6 12:01:49 INFO - blobuploader == 1.2.4 12:01:49 INFO - docopt == 0.6.1 12:01:49 INFO - functools32 == 3.2.3-2 12:01:49 INFO - jsonschema == 2.5.1 12:01:49 INFO - manifestparser == 1.1 12:01:49 INFO - mozInstall == 1.13 12:01:49 INFO - mozcrash == 1.0 12:01:49 INFO - mozdebug == 0.1 12:01:49 INFO - mozdevice == 0.51 12:01:49 INFO - mozfile == 1.2 12:01:49 INFO - mozhttpd == 0.7 12:01:49 INFO - mozinfo == 0.10 12:01:49 INFO - mozleak == 0.1 12:01:49 INFO - mozlog == 3.5 12:01:49 INFO - moznetwork == 0.27 12:01:49 INFO - mozprocess == 0.25 12:01:49 INFO - mozprofile == 0.28 12:01:49 INFO - mozrunner == 6.13 12:01:49 INFO - mozscreenshot == 0.1 12:01:49 INFO - mozsystemmonitor == 0.3 12:01:49 INFO - moztest == 0.8 12:01:49 INFO - mozversion == 1.4 12:01:49 INFO - psutil == 3.1.1 12:01:49 INFO - requests == 1.2.3 12:01:49 INFO - wsgiref == 0.1.2 12:01:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:49 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 0x26241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ecb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29919c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ed60>, '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 0x298fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298fef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:01:49 INFO - Using env: {'DISPLAY': ':0', 12:01:49 INFO - 'HOME': '/home/cltbld', 12:01:49 INFO - 'LANG': 'en_US.UTF-8', 12:01:49 INFO - 'LANGUAGE': 'en_US:en', 12:01:49 INFO - 'LOGNAME': 'cltbld', 12:01:49 INFO - 'MAIL': '/var/mail/cltbld', 12:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:49 INFO - 'MOZ_NO_REMOTE': '1', 12:01:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:49 INFO - 'NO_EM_RESTART': '1', 12:01:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:49 INFO - 'PWD': '/builds/slave/test', 12:01:49 INFO - 'SHELL': '/bin/bash', 12:01:49 INFO - 'SHLVL': '1', 12:01:49 INFO - 'TERM': 'linux', 12:01:49 INFO - 'TMOUT': '86400', 12:01:49 INFO - 'USER': 'cltbld', 12:01:49 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:49 INFO - '_': '/tools/buildbot/bin/python'} 12:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:01:49 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:01:50 INFO - Installing collected packages: simplejson 12:01:50 INFO - Running setup.py install for simplejson 12:01:50 INFO - building 'simplejson._speedups' extension 12:01:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 12:01:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 12:01:51 INFO - Successfully installed simplejson 12:01:51 INFO - Cleaning up... 12:01:51 INFO - Return code: 0 12:01:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ecb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29919c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ed60>, '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 0x298fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298fef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:01:51 INFO - Using env: {'DISPLAY': ':0', 12:01:51 INFO - 'HOME': '/home/cltbld', 12:01:51 INFO - 'LANG': 'en_US.UTF-8', 12:01:51 INFO - 'LANGUAGE': 'en_US:en', 12:01:51 INFO - 'LOGNAME': 'cltbld', 12:01:51 INFO - 'MAIL': '/var/mail/cltbld', 12:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:51 INFO - 'MOZ_NO_REMOTE': '1', 12:01:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:51 INFO - 'NO_EM_RESTART': '1', 12:01:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:51 INFO - 'PWD': '/builds/slave/test', 12:01:51 INFO - 'SHELL': '/bin/bash', 12:01:51 INFO - 'SHLVL': '1', 12:01:51 INFO - 'TERM': 'linux', 12:01:51 INFO - 'TMOUT': '86400', 12:01:51 INFO - 'USER': 'cltbld', 12:01:51 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:51 INFO - '_': '/tools/buildbot/bin/python'} 12:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:01:52 INFO - Cleaning up... 12:01:52 INFO - Return code: 0 12:01:52 INFO - Running post-action listener: _resource_record_post_action 12:01:52 INFO - Running post-action listener: _start_resource_monitoring 12:01:52 INFO - Starting resource monitoring. 12:01:52 INFO - [mozharness: 2017-09-30 19:01:52.092953Z] Finished create-virtualenv step (success) 12:01:52 INFO - [mozharness: 2017-09-30 19:01:52.094029Z] Running install step. 12:01:52 INFO - Running pre-action listener: _resource_record_pre_action 12:01:52 INFO - Running main action method: install 12:01:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:01:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:01:52 INFO - Reading from file tmpfile_stdout 12:01:52 INFO - Detecting whether we're running mozinstall >=1.0... 12:01:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:01:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:01:52 INFO - Reading from file tmpfile_stdout 12:01:52 INFO - Output received: 12:01:52 INFO - Usage: mozinstall [options] installer 12:01:52 INFO - Options: 12:01:52 INFO - -h, --help show this help message and exit 12:01:52 INFO - -d DEST, --destination=DEST 12:01:52 INFO - Directory to install application into. [default: 12:01:52 INFO - "/builds/slave/test"] 12:01:52 INFO - --app=APP Application being installed. [default: firefox] 12:01:52 INFO - mkdir: /builds/slave/test/build/application 12:01:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:01:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:02:10 INFO - Reading from file tmpfile_stdout 12:02:10 INFO - Output received: 12:02:10 INFO - /builds/slave/test/build/application/firefox/firefox 12:02:10 INFO - Running post-action listener: _resource_record_post_action 12:02:10 INFO - [mozharness: 2017-09-30 19:02:10.452066Z] Finished install step (success) 12:02:10 INFO - [mozharness: 2017-09-30 19:02:10.452318Z] Running setup-mitmproxy step. 12:02:10 INFO - Running pre-action listener: _resource_record_pre_action 12:02:10 INFO - Running main action method: setup_mitmproxy 12:02:10 INFO - Skipping: mitmproxy is not required 12:02:10 INFO - Running post-action listener: _resource_record_post_action 12:02:10 INFO - [mozharness: 2017-09-30 19:02:10.453495Z] Finished setup-mitmproxy step (success) 12:02:10 INFO - [mozharness: 2017-09-30 19:02:10.453725Z] Running run-tests step. 12:02:10 INFO - Running pre-action listener: _resource_record_pre_action 12:02:10 INFO - Running pre-action listener: _set_gcov_prefix 12:02:10 INFO - Running main action method: run_tests 12:02:10 WARNING - Try message not found. 12:02:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:02:10 INFO - Python 2.7.3 12:02:10 INFO - Return code: 0 12:02:10 INFO - grabbing minidump binary from tooltool 12:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:10 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 0x298ed60>, '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 0x298fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x298fef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:02:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:02:10 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:02:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:02:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:02:10 INFO - Return code: 0 12:02:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:02:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:02:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:02:10 INFO - ENV: RUST_BACKTRACE is now full 12:02:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:02:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:02:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-070', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-070 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:02:10 INFO - Using env: {'DISPLAY': ':0', 12:02:10 INFO - 'HOME': '/home/cltbld', 12:02:10 INFO - 'LANG': 'en_US.UTF-8', 12:02:10 INFO - 'LANGUAGE': 'en_US:en', 12:02:10 INFO - 'LOGNAME': 'cltbld', 12:02:10 INFO - 'MAIL': '/var/mail/cltbld', 12:02:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:02:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:10 INFO - 'MOZ_NO_REMOTE': '1', 12:02:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:02:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:10 INFO - 'NO_EM_RESTART': '1', 12:02:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:10 INFO - 'PWD': '/builds/slave/test', 12:02:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:02:10 INFO - 'RUST_BACKTRACE': 'full', 12:02:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:02:10 INFO - 'SHELL': '/bin/bash', 12:02:10 INFO - 'SHLVL': '1', 12:02:10 INFO - 'TERM': 'linux', 12:02:10 INFO - 'TMOUT': '86400', 12:02:10 INFO - 'USER': 'cltbld', 12:02:10 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:10 INFO - '_': '/tools/buildbot/bin/python'} 12:02:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-070', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:02:11 INFO - mozversion application_buildid: 20170930180320 12:02:11 INFO - mozversion application_changeset: 8873432294c4ae1431357410497676fbd3e1f855 12:02:11 INFO - mozversion application_display_name: Nightly 12:02:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:02:11 INFO - mozversion application_name: Firefox 12:02:11 INFO - mozversion application_remotingname: firefox 12:02:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:02:11 INFO - mozversion application_vendor: Mozilla 12:02:11 INFO - mozversion application_version: 58.0a1 12:02:11 INFO - mozversion platform_buildid: 20170930180320 12:02:11 INFO - mozversion platform_changeset: 8873432294c4ae1431357410497676fbd3e1f855 12:02:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:02:11 INFO - mozversion platform_version: 58.0a1 12:02:11 INFO - using testdate: 1506798131 12:02:11 INFO - actual date: 1506798131 12:02:11 INFO - starting webserver on 'localhost:46966' 12:02:11 INFO - SUITE-START | Running 2 tests 12:02:11 INFO - TEST-START | dromaeo_css 12:02:11 INFO - Initialising browser for dromaeo_css test... 12:02:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46966/getInfo.html -profile /tmp/tmpJiAjtm/profile 12:02:11 INFO - TEST-INFO | started process 3629 (/builds/slave/test/build/application/firefox/firefox http://localhost:46966/getInfo.html) 12:02:18 INFO - TEST-INFO | 3629: exit 0 12:02:18 INFO - Browser initialized. 12:02:18 INFO - Running cycle 1/1 for dromaeo_css test... 12:02:18 INFO - Using env: {'DISPLAY': ':0', 12:02:18 INFO - 'HOME': '/home/cltbld', 12:02:18 INFO - 'JSGC_DISABLE_POISONING': '1', 12:02:18 INFO - 'LANG': 'en_US.UTF-8', 12:02:18 INFO - 'LANGUAGE': 'en_US:en', 12:02:18 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:02:18 INFO - 'LOGNAME': 'cltbld', 12:02:18 INFO - 'MAIL': '/var/mail/cltbld', 12:02:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:02:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:02:18 INFO - 'MOZ_CRASHREPORTER': '1', 12:02:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:18 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:02:18 INFO - 'MOZ_NO_REMOTE': '1', 12:02:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:02:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:02:18 INFO - 'NO_EM_RESTART': '1', 12:02:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:02:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:18 INFO - 'PWD': '/builds/slave/test', 12:02:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:02:18 INFO - 'RUST_BACKTRACE': 'full', 12:02:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:02:18 INFO - 'SHELL': '/bin/bash', 12:02:18 INFO - 'SHLVL': '1', 12:02:18 INFO - 'TERM': 'linux', 12:02:18 INFO - 'TMOUT': '86400', 12:02:18 INFO - 'USER': 'cltbld', 12:02:18 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:02:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:18 INFO - '_': '/tools/buildbot/bin/python'} 12:02:18 INFO - TEST-INFO | started process 3865 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJiAjtm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:02:19 INFO - PID 3865 | 12:02:19 INFO - PID 3865 | (/builds/slave/test/build/application/firefox/firefox:3914): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:02:19 INFO - PID 3865 | 12:02:29 INFO - PID 3865 | RSS: Main: 147869696 12:02:29 INFO - PID 3865 | 12:05:27 INFO - PID 3865 | Cycle 1(1): loaded http://localhost:46966/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46966/tests/dromaeo/cssquery-ext.html) 12:05:28 INFO - PID 3865 | RSS: Main: 149917696 12:05:28 INFO - PID 3865 | 12:08:29 INFO - PID 3865 | Cycle 1(1): loaded http://localhost:46966/tests/dromaeo/cssquery-ext.html (next: http://localhost:46966/tests/dromaeo/cssquery-jquery.html) 12:08:30 INFO - PID 3865 | RSS: Main: 149508096 12:08:30 INFO - PID 3865 | 12:11:32 INFO - PID 3865 | Cycle 1(1): loaded http://localhost:46966/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46966/tests/dromaeo/cssquery-mootools.html) 12:11:32 INFO - PID 3865 | RSS: Main: 150786048 12:11:32 INFO - PID 3865 | 12:14:34 INFO - PID 3865 | Cycle 1(1): loaded http://localhost:46966/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46966/tests/dromaeo/cssquery-prototype.html) 12:14:34 INFO - PID 3865 | RSS: Main: 150577152 12:14:34 INFO - PID 3865 | 12:17:38 INFO - PID 3865 | Cycle 1(1): loaded http://localhost:46966/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46966/tests/dromaeo/cssquery-yui.html) 12:17:38 INFO - PID 3865 | RSS: Main: 153853952 12:17:38 INFO - PID 3865 | 12:20:40 INFO - PID 3865 | Cycle 1(1): loaded http://localhost:46966/tests/dromaeo/cssquery-yui.html (next: http://localhost:46966/tests/dromaeo/cssquery-dojo.html) 12:20:40 INFO - PID 3865 | RSS: Main: 155009024 12:20:40 INFO - PID 3865 | 12:20:40 INFO - PID 3865 | __start_tp_report 12:20:40 INFO - PID 3865 | _x_x_mozilla_page_load 12:20:40 INFO - PID 3865 | _x_x_mozilla_page_load_details 12:20:40 INFO - PID 3865 | |i|pagename|runs| 12:20:40 INFO - PID 3865 | |0;dojo.html;3183.6497985221627;3202.695743904328;3206.919827004326;3217.5817143771305;3220.613526376834;6145.231846019247;6200.720967556459;6306.8738625227525;6317.873642527146;6348.047792831072;6241.906371404421;6278.0269058296035;6309.242890853104;6312.558120931537;6315.747370105202;5736.569757268201;5789.305283366002;5799.73901174446;5808.390119037504;5935.376785437532;5905.173275741378;5917.378675239081;5946.821595352147;5948.31594366649;5952.70236488176;5855.063189889618;5893.8821223575505;5918.615290006136;5965.373635768247;5971.134185543094;5634.464725851038;5763.596548241625;5839.416058394169;5894.292684877799;5912.17229587858;5785.5660825437835;5797.913031304533;5855.8536036599;5866.589338746289;5876.441738034922;6281.528885333572;6332.936670633327;6333.398327158912;6386.393292637192;6418.326075762025;6598.307177746316;6713.798586042427;6775.525713200078;6781.186257649064;6844.178698556156;6822.897656535156;6910.2053263874795;6914.9308506915295;6925.445964322842;6926;6334.683265836689;6338.207724034496;6414.2302923648995;6422.743090276384;6424.743010279583;6772.762953296709;6919.723211071601;6955.130608673916;6968.163820341591;7048.577085374792;6568.277489476154;6642.335766423319;6705.362990515893;6737.3599508046655;6754.45964322853;6791.388775010273;6806.081179040865;6849.863002739918;6877.759278425231;6886.070380498669;6601.933980660228;6719.361660642231;6762.492813039042;6782.050512928194;6810.62541560219;6609.173853268341;6716;6775.22084960226;6787;6789.694463749143;5253.632245742761;5291.3385826771655;5323.281357016753;5337.172738225504;5337.8131765388025;4567.703099298535;4611.100835337077;4618.976905115453;4656.650751193674;4711.552402521755;5826.82519524415;5873.500752436005;5930.555208359304;5934.13954976526;5937.821865344046;6777.898331525031;6791.728330866808;6879.071325371011;6886.655667216619;6897.241303456616;5693.0891057431445;5782.797602083991;5784.421557844182;5785.0454674978155;5794.21778059965;5769.942300577024;5852.590318677737;5854.560907931837;5856.092305692625;5895.322037965603;6525.771597994048;6600.86798264032;6613.603183808987;6614.470842332602;6669.866602668017;6738.494612903954;6754.290799465985;6755.3582409671;6764.120664313607;6837.247902730596;6432.807015789534;6523.380278873499;6523.7716679915975;6530.8367290818105;6533.67331633407;6578.671066446755;6631.204255489372;6730.697118629576;6747.954067119605;6748.460123190035;6760.492963027891;6866.553674011173;6869.862602747817;6873.312668733087;6879.2432832389395;6527.249366322933;6537.57505762143;6582.308857570078;6637.4690024798865;6640.302768209269;6355.650439225702;6485.027245912962;6532.5427220096035;6614.371634694697;6627.105340778933;6356.555041147075;6468.126802881551;6469.935300646934;6494.837629059311;6498.155239818982;6618.106555614931;6656.367645073904;6728.024436456784;6736.528443008942;6745.426638735762;6406;6407.615543067244;6416.230052393743;6493.253275873335;6497.090407342881 12:20:40 INFO - PID 3865 | |1;ext.html;857.6783706110209;873.1748497669705;885.654594708064;910.1581037540274;915.9816803663927;39986.200275994466;40758.388624170584;40787.59212407875;40857.79571102148;40895;38397.23205535887;38563.22873542528;38571.614283857154;38636.61363386365;38642.8067859661;7176.354128128467;7265.89101163483;7333.706651733924;7337.559746415205;7372.74195403161;7292.854142917165;7384.446166537505;7394.667239974214;7402.777916662509;7433.256674332578;7373.926260737405;7971.322437592811;8170.836583268331;8176.918230817705;8178.672853085899;8019.518828870287;8040.075391329984;8089.95955020227;8112.391570632197;8115.188481151926;7759.573223472707;7874.173211812735;7915.802104947365;7935.285824275843;7969.521828690297;37931.62068379309;38377;38463.423048654295;38501.807490962645;38668.80665596654;3483.738719596015;3531.4526248431534;3531.788092714445;3539.80531070791;3541.8937431877084;30792.230194245098;30985.0704478866;31084.378312433626;31141.532877006863;31159.53260701091;5192.1173400522;5200;5201.8959620807755;5210.088234558937;5220.947790522084;184638;185778.99999999866;186194;186615;187841;46054.078918421445;46333.53666463292;46357.30464043042;46492.140314387725;46723.29915051277;48797.75601122043;48836.755816221405;48898.51101489011;48961.75519122381;48964.51035489671;15653.530394088195;15657.60855978587;15849;15882.205889705469;15902.681946361008;13001.349932503526;13015.154014989184;13023.153495022792;13023.348832558335;13025.869741302466;13453.663658408617;13597.728045439037;13622.727545449035;13629.659258518419;13754.105983111065;5479.75341109651;5502.147167189092;5582.404182055364;5648.096304591328;5661.716914154358;1003.242551873331;1017.7150397888603;1023.959041638341;1024.3239461955222;1043.3166276089173;22898.65652015221;22948.885255573616;23026.194083207007;23055.654165187534;23598.528029439658;1121.6971417717334;1131.9603813866474;1132.4960392625367;1135.982960255597;1140.1448913315014;12156.209846359958;12299;12346.876531234753;12363.93818030904;12623.747525049448;223878;225577;226730;226921;227364;45671.31493027607;45745.77127114343;45810.54189458129;46371;52250.477495224564;1348.3460521646994;1388.7014291927285;1392.5335012770634;1394.637394277495;1431.7494438473225;26392.8680356597;26572.734272657028;26578;26638.200853974413;26967.325816854736;36731.81634091812;36823.63176368202;36852.81573592115;36856.07859803527;36888.81555592205;185345;185939;186052;186787;186850;32351;32377.35245295128;32520.51219231761;32651.18372040718;32934.670653293164;39896.60103398929;41185;41995.7900210497;42056.369154462096;42097.36853947132;30272.697273026988;30811.845940770152;30994.535081973336;31051.223719407197;31269.8436507816;30743.69256307498;30981.690183097882;31026.379472410877;31143.53284700686;31775.68224317727;33942.3211535763;34298.14254643654;34374;34391.82804085963;34485.31029379348;29916.850415747784;29964.250893727833;30254;30290;30978.845105774326;22267.554648907255;22398.440038999157;22413.43966400853;22651.320460386214;22919.197828076605 12:20:40 INFO - PID 3865 | |2;jquery.html;582.4699523433674;586.7770247306023;597.7907732293695;605.9394060593941;609.8048624440179;21986.340409787714;22177.223797167102;22797.974091165917;22802.771972280312;22803.201887933927;24604.384890377703;25156.496870062587;25177.74822251777;25199.244022679348;25385.87307063467;6609.372109649576;6750.459963202957;6777.2545020048;6777.423918966893;6787.185537735479;6254.624722516641;6501.609903405811;6552.93447065528;6594.07682924391;6630.469562434993;6520.804375868732;6537.248216455121;6538.509611779112;6542.247641521239;6542.967285163568;6486.578372405778;6502.512311576627;6517.771877984248;6522.673866306689;6533.673316334211;6045.12345709874;6050.6369617823075;6078.726457309432;6098.3596722343955;6114.541409394269;27361.589576156373;27959.440811183867;28345.574816377564;28504.572431413337;28579.571306430422;2081.053120830034;2101.432613194444;2108.8734675919495;2111.926082587102;2112.8943552822298;19444.125014374386;19608.117634706472;19793.50516237091;19854.205831766856;19950.201991920305;2394.2761211927386;2400.23192578375;2473.6784218051716;2479.528889511005;2488.427661637815;136011;138702;139180;139354;139581;14667.48663796762;15252;15282.31229594671;15413.691726165414;15768;23623.40941476477;23626.29121126369;23685.28944131679;23745.525089498115;23765.881170594035;9807.117359437685;9856.950715246378;9877.50612469388;9899.406035637885;9976.401415915068;7271.3455788979245;7518.812029699192;9038.86441703375;9070.682526111554;9094.636214551343;8780.165884240987;9438.433693978383;9444.716658500256;9571.808563828685;9576.473293968898;2766.9446611067665;2771.1270949650793;2776.8750406231366;2790.2745286225727;2839.872205750746;946.4226821638795;963.1091240602415;976.6532880827347;979.1432496565506;981.7545613596601;19063.809361906482;19168.424947251606;19290.517737056685;19595.60808783816;19627.705584416246;994.6966175316597;999.5002498750624;1012.2172733832582;1021.918246540275;1026.440589878518;10857.239993200557;10942.56229750817;10986.395748233925;11030.338179709242;11302;467864;474358;479247;483885.161148384;494652;112904;113942.43028784802;114820;115645.42177289228;116577.41711291559;1133.240728711765;1164.190887333312;1164.574930150506;1168.4041139019162;1170.3796987596588;17555.0344731041;17884.642307153525;17891.552711182325;17997.370092046716;18064.638707226044;54368.45631543634;57114.42885571091;57335.71332143479;57472.42527574838;57529.71235143798;70466.64766676133;70709;71996.64001679957;72606.63696681483;72692.63653681698;14503.92748036253;14875.94223458821;14951.401943922121;14990.625234369229;14997.2501374933;22584.322470325915;22745.658815117455;22838.543229135656;22980;23165.3050408488;18249.635007299516;18351.724724128882;18438.170282337058;18551.35070272534;18689.345872894384;15412.614684632974;15761.290741916411;16110.838891611404;16138.515844524683;16450.67098658044;18370.72443913369;18593.256269749058;18731.250749969848;18739.437816865517;18794.718079229096;14418.639534011314;14481.493147739779;14602;14630.195339256443;14665.266736663338;18136;18181.909090454465;18198.272069117087;18237;18302 12:20:40 INFO - PID 3865 | |3;mootools.html;1316.414195682921;1372.5676411930244;1375.5735721926203;1384.7992041154027;1394.4143459746906;14834.554963351104;15400.152991585472;15428.074315541062;15439.691206175898;15443.45947891824;15265.465708700198;15295.617609559766;15368.923155384235;15376.692466150671;15382.769258461132;2764.834109953404;2847.074700722263;2852.215640698804;2855.2433605094666;2863.083813179783;2779.4163225722623;2796.7762578993634;2804.8878044878284;2812.7749780017652;2828.490871643103;2750.752432281094;2794.9720502794917;2808.8314701117897;2813.8874445022275;2819.4502072095947;2646.603009548564;2657.8139530232893;2671.4389978104623;2672.4521473098066;2675.3177939625466;2675.0770984010487;2730.30377253801;2732.0574401831336;2741.533939230336;2742.067696983035;14878.256087195705;15025.774613380809;15031.774523382159;15035.699286014218;15046.247687615576;8095.959520202361;8343.54110523927;8349.624266907944;8372.748817535483;8374.958125209396;5138.383393992707;5374.435684253136;5487.314085739283;5503.862403439906;5520.199571062173;1952.6778081616517;1966.5280332720188;2012.2856385982911;2050.979490205094;2068.379486154163;23636.1727339544;24100.517989640284;25593.23220303394;26447.206583802516;26506.072287469848;6913.930860691429;6923.307669233036;6969.860602788017;7067.787966361017;7151.964240178766;17185;17223.56941076458;17255.3960611378;17286.22212000463;17300.134993250285;7984.68061277554;8049.4365394423;8101.6354264058255;8145.266925976572;8168.183181681784;7341.082364704412;7398.297161769623;7502;7508.587027713528;7554.131274903347;5085.694858308513;5200.97399513;5266.552343050807;5279.947200528022;5288.889333240063;1530.7780371846243;1534.6547026918854;1534.6777176792998;1549.7210502109729;1549.7365447873888;912.9726108216764;916.564631799903;924.2790623313691;933;936.4521754773534;7753.689852405841;7755.92244077552;7790.493617914817;7835.2948234658015;7862.410319226081;713.9393151582174;716.8279612892868;717.1358512991833;725.6770737021975;731.6853752886203;5122.795088196505;5253.13323301651;5388.7305634718105;5632.352279487829;5661.490465858093;20914.268000619904;21441;21622.89188554047;21845;21870.45323866916;21042.579148416946;21511.78488215098;21666.56666866654;21815.345539633836;21927.561448770935;10605.946970265099;10797.730056748644;10806.837897431546;11151.553937842558;11355.261907975955;8158.755237342889;8315.126911674188;8328.333733301442;8408.87386689188;8725.563721814011;18303.816961830213;18381.54046148857;18524.44426667202;18560;18891.811081889555;18725.344612939025;18852.24591016344;18946.52633684169;19053.904730476257;19159.712604311226;11143.554257829921;11345.829812552653;11364.715882103013;11365.772684546428;11366.886331136913;14110.082844615066;14196.645083872985;14206.07660502064;14344.067635603653;14395.352209150822;11417.1437142213;11527.135464840003;11564.768704626029;11644;11838.881611184122;11142.220044596801;11261.380624065427;11547.538098475967;11615.825762613737;11729.530818767154;9823.803523929624;10006.749831254278;10098.192144628265;10102.292839501164;10102.343347682377;6737.730490780314;6776.424003959719;6903.482238832007;6910.689018994057;6994.02083708271;7467.551946883204;7554.433417493819;7605.923940760522;7621.847563048819;7737.845243095219 12:20:40 INFO - PID 3865 | |4;prototype.html;488.74585215687864;532.1246549426194;535.4591862219157;535.8955003774261;537.1629028721771;6195.845103872405;6225;6316.020906828698;6324.873502529947;6333.904991425133;6158.353372895848;6204.81385558434;6216.595921265114;6245.281792593853;6280;5189;5192.870178245536;5199.922001169985;5216.2958000669805;5216.8174113906025;4963.35476388069;5043.218301163325;5073.112205364065;5161.17421212606;5188.948110518903;4937.23472861707;5006.799728010875;5037.697738135706;5039.143345631233;5052.24216367546;4895.265710143472;4962.776675049596;4982.177940639772;4997.925031124536;5008.7996480140755;4482.417285752864;4483.730976141442;4521.349707356824;4529.977350113261;4535.591796738309;6014.30835453924;6061.575689701723;6097.695115244264;6108.755649774003;6152.600080994721;5623.240862483594;5634.239377684042;5647.943520564783;5690.374058853523;5694.2312787773535;6406.2953075161695;6467.579607325509;6566.277709452004;6569.37590928861;6577.80266592003;4921.163402221631;4925.310456536088;4950.554450099508;4961.355023846912;4976.2784396262705;6665.633390163539;6712.395884370381;6816.625085620339;6862.588244705334;6880.2431732509385;5033.119204139261;5063.911259079242;5067.315912351859;5090.312807770978;5102.668326558836;5107.974460127675;5117.41149767774;5145.151050076695;5158.226266060121;5165.483451654804;5725.341585717612;5732.168835518826;5761.798337058183;5763.464075806632;5781.797637082682;6605.1083103780375;6630.7679231226675;6700.497462690318;6700.765473208415;6714.160729908762;1785.5179101642484;1785.9821401785816;1790.7940586832422;1794.8294911983342;1801.3785244090768;1179.168686076314;1228.2804074295877;1241.366902879538;1257.0948916779903;1257.7547378261152;5744.167095771173;5767.394423585547;5767.88464230713;5793.449622285876;5805.680687562224;6752;6755.662216889136;6758.256591774901;6758.763443279461;6795.898061529081;5757.251557297608;5765.365809760923;5779.450952159538;5792.536597072224;5824;3535.646435356444;3536.3457760314423;3541.149374753348;3542.946855797165;3550.7336949728356;6184.474319682788;6501.739930402826;6515.120458738104;6569.704363303663;6575.572587781779;6826.596845035024;6829.52193346461;6886.449084073262;6886.51794374389;6920.273371296042;6408.903866442007;6435.420812126931;6472.385123413268;6472.838179045468;6536.967315163489;6573.19237209752;6581.407673309425;6692.230393504809;6770.2891196425635;6813;6556.541042127005;6647.401733843977;6667.899981500378;6726.7981960541265;6743.865122697421;6492.156019717407;6503.382178693017;6511.73953041873;6514.478841692748;6568.934310656832;5037.92443113361;5042.117629414764;5048.1165795984825;5066.82266120684;5109.241009854637;6516.609003459618;6519.250286217146;6553.311902250195;6853.657317134223;6878.415334696607;6129.448349648553;6361.554691171574;6387.77642782519;6438.195225596801;6523.3898846990605;6375.489960803217;6407.391297826699;6530.67346632676;6541.771038013647;6566.737330506726;6375.234971803413;6376.107344971801;6448.516361272829;6450.258220304725;6540;6504.918426103602;6528.804135875931;6529.085927969993;6550.213974323112;6599.307072757483;6272.243771743919;6274.686265686789;6278.46633036197;6309.936900630935;6391.552591318563 12:20:40 INFO - PID 3865 | |5;yui.html;907.2016625369673;938.9624415023397;942.2509105261321;943.1087622197026;943.9150476457122;20005.399838004883;20232.59534809303;20545.794542054595;20569.074391652375;20659;19371.612567748638;19728.506787330323;19820.50448738782;19822.40532784015;19825.504362390948;973.6981535723949;1001.319103009953;1004.4826914139225;1007.4710776842143;1007.9949600251988;960.4669408478292;984.6996666016862;985.2019863910256;986.5807032011402;1001.0640051551786;1046.6807623674777;1048.973775655611;1048.984265236022;1050.5325130317012;1061.3207547169818;1043.1758910460726;1043.7129789307926;1046.9895301047006;1048.1614708233421;1055.6780182044474;758.8216769059267;765.9157492675857;767.5893397032561;769.0502229746259;774.2915232562199;20257.594848103105;20548.17807287707;20686.896565517225;20857.374278771662;20875.269365572283;21105.577888442145;21684.89157554218;22405.43986400337;22410.663840042413;22416.32751017472;17188.624910007293;17513.912430437893;17531.824681753147;17571.5607109822;17586.560335991573;3711.9814400928094;3785.9053523661855;3808.771473711586;3828.444875493066;3834.6740527055235;137313.31343343318;141331.29334353365;141527;142304;143001.28499357542;16783.58041048959;16930.153492325582;17121.571960701083;17195.1402429878;17209.655806884042;31594.684053159635;31776.20559485985;31795.20511987219;31806.363872722417;31837;8372.204640559137;8457.53483558398;8561.0582835888;8574.442661227;8578;4161.791910404468;4233.894152646209;4254.255505286563;4258.425112609758;4267.445232119804;5330.546903513167;5390.029794637003;5399.865003374868;5434.83695489136;5468.097763868917;996.9750756231152;1013.7465633591602;1021.4075836015094;1024.2164743970868;1024.7438140464883;662.1259936883267;681.431005110738;682.8873235916118;690.848013437043;693.6809067828855;11294.152938529644;11788.528458861721;12021.759564808655;12204.145709800407;12420.254784712946;666.6166954001469;687.3229868579446;694.0838093716246;694.2363400259674;701.4773993374866;5318.175682769177;5341;5359.839204823861;5418;5426.484483974016;113956.43021785004;115091;115925;117646.41176794062;120250;100868.49565752124;101436;107676;111627;113062;97480;97719.51140244395;98190.50904745431;100341;100930.49534752427;11896.881031189578;12081.879181208427;12109.273443593413;12120.515179393078;12638.620841374774;42866.57133428626;43848.780756096014;43885.34171987359;43908.560914390444;44877.55122448734;68190.3180968184;81332;81940.18059819564;86222;86583;18614.44156675302;18774;18788.154533045774;18958.810411896255;19093.42719718355;20711.171553137705;20901.268455603982;21181.57636847285;21189.470263243544;21381.144754209658;17995.82004179994;18197.45407637773;18205.817941820944;18267.817321826613;18356.54108647241;18832.7175092371;19157;19250;19393.612127757082;19438.51403714862;18037.729434058238;18160.72758908591;18174;18194.181261842983;18262.36081737133;13736.175829450265;14000.089994150349;14181.574552763434;14216.21810800416;14239.71520569562;13524.256165910969;13535.458581656623;13548.796768048684;13666.590002299947;13699.315034248448 12:20:40 INFO - PID 3865 | __end_tp_report 12:20:40 INFO - PID 3865 | __start_cc_report 12:20:40 INFO - PID 3865 | _x_x_mozilla_cycle_collect,301 12:20:40 INFO - PID 3865 | __end_cc_report 12:20:40 INFO - PID 3865 | __startTimestamp1506799240581__endTimestamp 12:20:40 INFO - PID 3865 | 12:20:40 INFO - PID 3865 | ------- Summary: start ------- 12:20:40 INFO - PID 3865 | Number of tests: 6 12:20:40 INFO - PID 3865 | 12:20:40 INFO - PID 3865 | [#0] dojo.html Cycles:165 Average:6236.75 Median:6469.94 stddev:743.92 (11.5%) stddev-sans-first:706.59 12:20:40 INFO - PID 3865 | Values: 3183.6 3202.7 3206.9 3217.6 3220.6 6145.2 6200.7 6306.9 6317.9 6348.0 6241.9 6278.0 6309.2 6312.6 6315.7 5736.6 5789.3 5799.7 5808.4 5935.4 5905.2 5917.4 5946.8 5948.3 5952.7 5855.1 5893.9 5918.6 5965.4 5971.1 5634.5 5763.6 5839.4 5894.3 5912.2 5785.6 5797.9 5855.9 5866.6 5876.4 6281.5 6332.9 6333.4 6386.4 6418.3 6598.3 6713.8 6775.5 6781.2 6844.2 6822.9 6910.2 6914.9 6925.4 6926.0 6334.7 6338.2 6414.2 6422.7 6424.7 6772.8 6919.7 6955.1 6968.2 7048.6 6568.3 6642.3 6705.4 6737.4 6754.5 6791.4 6806.1 6849.9 6877.8 6886.1 6601.9 6719.4 6762.5 6782.1 6810.6 6609.2 6716.0 6775.2 6787.0 6789.7 5253.6 5291.3 5323.3 5337.2 5337.8 4567.7 4611.1 4619.0 4656.7 4711.6 5826.8 5873.5 5930.6 5934.1 5937.8 6777.9 6791.7 6879.1 6886.7 6897.2 5693.1 5782.8 5784.4 5785.0 5794.2 5769.9 5852.6 5854.6 5856.1 5895.3 6525.8 6600.9 6613.6 6614.5 6669.9 6738.5 6754.3 6755.4 6764.1 6837.2 6432.8 6523.4 6523.8 6530.8 6533.7 6578.7 6631.2 6730.7 6748.0 6748.5 6760.5 6866.6 6869.9 6873.3 6879.2 6527.2 6537.6 6582.3 6637.5 6640.3 6355.7 6485.0 6532.5 6614.4 6627.1 6356.6 6468.1 6469.9 6494.8 6498.2 6618.1 6656.4 6728.0 6736.5 6745.4 6406.0 6407.6 6416.2 6493.3 6497.1 12:20:40 INFO - PID 3865 | 12:20:40 INFO - PID 3865 | [#1] ext.html Cycles:180 Average:36428.74 Median:34120.23 stddev:51804.58 (151.8%) stddev-sans-first:51881.05 12:20:40 INFO - PID 3865 | Values: 857.7 873.2 885.7 910.2 916.0 39986.2 40758.4 40787.6 40857.8 40895.0 38397.2 38563.2 38571.6 38636.6 38642.8 7176.4 7265.9 7333.7 7337.6 7372.7 7292.9 7384.4 7394.7 7402.8 7433.3 7373.9 7971.3 8170.8 8176.9 8178.7 8019.5 8040.1 8090.0 8112.4 8115.2 7759.6 7874.2 7915.8 7935.3 7969.5 37931.6 38377.0 38463.4 38501.8 38668.8 3483.7 3531.5 3531.8 3539.8 3541.9 30792.2 30985.1 31084.4 31141.5 31159.5 5192.1 5200.0 5201.9 5210.1 5220.9 184638.0 185779.0 186194.0 186615.0 187841.0 46054.1 46333.5 46357.3 46492.1 46723.3 48797.8 48836.8 48898.5 48961.8 48964.5 15653.5 15657.6 15849.0 15882.2 15902.7 13001.3 13015.2 13023.2 13023.3 13025.9 13453.7 13597.7 13622.7 13629.7 13754.1 5479.8 5502.1 5582.4 5648.1 5661.7 1003.2 1017.7 1024.0 1024.3 1043.3 22898.7 22948.9 23026.2 23055.7 23598.5 1121.7 1132.0 1132.5 1136.0 1140.1 12156.2 12299.0 12346.9 12363.9 12623.7 223878.0 225577.0 226730.0 226921.0 227364.0 45671.3 45745.8 45810.5 46371.0 52250.5 1348.3 1388.7 1392.5 1394.6 1431.7 26392.9 26572.7 26578.0 26638.2 26967.3 36731.8 36823.6 36852.8 36856.1 36888.8 185345.0 185939.0 186052.0 186787.0 186850.0 32351.0 32377.4 32520.5 32651.2 32934.7 39896.6 41185.0 41995.8 42056.4 42097.4 30272.7 30811.8 30994.5 31051.2 31269.8 30743.7 30981.7 31026.4 31143.5 31775.7 33942.3 34298.1 34374.0 34391.8 34485.3 29916.9 29964.3 30254.0 30290.0 30978.8 22267.6 22398.4 22413.4 22651.3 22919.2 12:20:40 INFO - PID 3865 | 12:20:40 INFO - PID 3865 | [#2] jquery.html Cycles:180 Average:34421.88 Median:23715.41 stddev:81057.81 (341.8%) stddev-sans-first:81245.38 12:20:40 INFO - PID 3865 | Values: 582.5 586.8 597.8 605.9 609.8 21986.3 22177.2 22798.0 22802.8 22803.2 24604.4 25156.5 25177.7 25199.2 25385.9 6609.4 6750.5 6777.3 6777.4 6787.2 6254.6 6501.6 6552.9 6594.1 6630.5 6520.8 6537.2 6538.5 6542.2 6543.0 6486.6 6502.5 6517.8 6522.7 6533.7 6045.1 6050.6 6078.7 6098.4 6114.5 27361.6 27959.4 28345.6 28504.6 28579.6 2081.1 2101.4 2108.9 2111.9 2112.9 19444.1 19608.1 19793.5 19854.2 19950.2 2394.3 2400.2 2473.7 2479.5 2488.4 136011.0 138702.0 139180.0 139354.0 139581.0 14667.5 15252.0 15282.3 15413.7 15768.0 23623.4 23626.3 23685.3 23745.5 23765.9 9807.1 9857.0 9877.5 9899.4 9976.4 7271.3 7518.8 9038.9 9070.7 9094.6 8780.2 9438.4 9444.7 9571.8 9576.5 2766.9 2771.1 2776.9 2790.3 2839.9 946.4 963.1 976.7 979.1 981.8 19063.8 19168.4 19290.5 19595.6 19627.7 994.7 999.5 1012.2 1021.9 1026.4 10857.2 10942.6 10986.4 11030.3 11302.0 467864.0 474358.0 479247.0 483885.2 494652.0 112904.0 113942.4 114820.0 115645.4 116577.4 1133.2 1164.2 1164.6 1168.4 1170.4 17555.0 17884.6 17891.6 17997.4 18064.6 54368.5 57114.4 57335.7 57472.4 57529.7 70466.6 70709.0 71996.6 72606.6 72692.6 14503.9 14875.9 14951.4 14990.6 14997.3 22584.3 22745.7 22838.5 22980.0 23165.3 18249.6 18351.7 18438.2 18551.4 18689.3 15412.6 15761.3 16110.8 16138.5 16450.7 18370.7 18593.3 18731.3 18739.4 18794.7 14418.6 14481.5 14602.0 14630.2 14665.3 18136.0 18181.9 18198.3 18237.0 18302.0 12:20:40 INFO - PID 3865 | 12:20:40 INFO - PID 3865 | [#3] mootools.html Cycles:180 Average:9341.93 Median:2664.63 stddev:6578.58 (246.9%) stddev-sans-first:6569.40 12:20:40 INFO - PID 3865 | Values: 1316.4 1372.6 1375.6 1384.8 1394.4 14834.6 15400.2 15428.1 15439.7 15443.5 15265.5 15295.6 15368.9 15376.7 15382.8 2764.8 2847.1 2852.2 2855.2 2863.1 2779.4 2796.8 2804.9 2812.8 2828.5 2750.8 2795.0 2808.8 2813.9 2819.5 2646.6 2657.8 2671.4 2672.5 2675.3 2675.1 2730.3 2732.1 2741.5 2742.1 14878.3 15025.8 15031.8 15035.7 15046.2 8096.0 8343.5 8349.6 8372.7 8375.0 5138.4 5374.4 5487.3 5503.9 5520.2 1952.7 1966.5 2012.3 2051.0 2068.4 23636.2 24100.5 25593.2 26447.2 26506.1 6913.9 6923.3 6969.9 7067.8 7152.0 17185.0 17223.6 17255.4 17286.2 17300.1 7984.7 8049.4 8101.6 8145.3 8168.2 7341.1 7398.3 7502.0 7508.6 7554.1 5085.7 5201.0 5266.6 5279.9 5288.9 1530.8 1534.7 1534.7 1549.7 1549.7 913.0 916.6 924.3 933.0 936.5 7753.7 7755.9 7790.5 7835.3 7862.4 713.9 716.8 717.1 725.7 731.7 5122.8 5253.1 5388.7 5632.4 5661.5 20914.3 21441.0 21622.9 21845.0 21870.5 21042.6 21511.8 21666.6 21815.3 21927.6 10605.9 10797.7 10806.8 11151.6 11355.3 8158.8 8315.1 8328.3 8408.9 8725.6 18303.8 18381.5 18524.4 18560.0 18891.8 18725.3 18852.2 18946.5 19053.9 19159.7 11143.6 11345.8 11364.7 11365.8 11366.9 14110.1 14196.6 14206.1 14344.1 14395.4 11417.1 11527.1 11564.8 11644.0 11838.9 11142.2 11261.4 11547.5 11615.8 11729.5 9823.8 10006.7 10098.2 10102.3 10102.3 6737.7 6776.4 6903.5 6910.7 6994.0 7467.6 7554.4 7605.9 7621.8 7737.8 12:20:40 INFO - PID 3865 | 12:20:40 INFO - PID 3865 | [#4] prototype.html Cycles:180 Average:5524.16 Median:6155.48 stddev:1530.40 (24.9%) stddev-sans-first:1487.29 12:20:40 INFO - PID 3865 | Values: 488.7 532.1 535.5 535.9 537.2 6195.8 6225.0 6316.0 6324.9 6333.9 6158.4 6204.8 6216.6 6245.3 6280.0 5189.0 5192.9 5199.9 5216.3 5216.8 4963.4 5043.2 5073.1 5161.2 5188.9 4937.2 5006.8 5037.7 5039.1 5052.2 4895.3 4962.8 4982.2 4997.9 5008.8 4482.4 4483.7 4521.3 4530.0 4535.6 6014.3 6061.6 6097.7 6108.8 6152.6 5623.2 5634.2 5647.9 5690.4 5694.2 6406.3 6467.6 6566.3 6569.4 6577.8 4921.2 4925.3 4950.6 4961.4 4976.3 6665.6 6712.4 6816.6 6862.6 6880.2 5033.1 5063.9 5067.3 5090.3 5102.7 5108.0 5117.4 5145.2 5158.2 5165.5 5725.3 5732.2 5761.8 5763.5 5781.8 6605.1 6630.8 6700.5 6700.8 6714.2 1785.5 1786.0 1790.8 1794.8 1801.4 1179.2 1228.3 1241.4 1257.1 1257.8 5744.2 5767.4 5767.9 5793.4 5805.7 6752.0 6755.7 6758.3 6758.8 6795.9 5757.3 5765.4 5779.5 5792.5 5824.0 3535.6 3536.3 3541.1 3542.9 3550.7 6184.5 6501.7 6515.1 6569.7 6575.6 6826.6 6829.5 6886.4 6886.5 6920.3 6408.9 6435.4 6472.4 6472.8 6537.0 6573.2 6581.4 6692.2 6770.3 6813.0 6556.5 6647.4 6667.9 6726.8 6743.9 6492.2 6503.4 6511.7 6514.5 6568.9 5037.9 5042.1 5048.1 5066.8 5109.2 6516.6 6519.3 6553.3 6853.7 6878.4 6129.4 6361.6 6387.8 6438.2 6523.4 6375.5 6407.4 6530.7 6541.8 6566.7 6375.2 6376.1 6448.5 6450.3 6540.0 6504.9 6528.8 6529.1 6550.2 6599.3 6272.2 6274.7 6278.5 6309.9 6391.6 12:20:40 INFO - PID 3865 | 12:20:40 INFO - PID 3865 | [#5] yui.html Cycles:180 Average:25558.19 Median:19915.45 stddev:35745.36 (179.5%) stddev-sans-first:35797.71 12:20:40 INFO - PID 3865 | Values: 907.2 939.0 942.3 943.1 943.9 20005.4 20232.6 20545.8 20569.1 20659.0 19371.6 19728.5 19820.5 19822.4 19825.5 973.7 1001.3 1004.5 1007.5 1008.0 960.5 984.7 985.2 986.6 1001.1 1046.7 1049.0 1049.0 1050.5 1061.3 1043.2 1043.7 1047.0 1048.2 1055.7 758.8 765.9 767.6 769.1 774.3 20257.6 20548.2 20686.9 20857.4 20875.3 21105.6 21684.9 22405.4 22410.7 22416.3 17188.6 17513.9 17531.8 17571.6 17586.6 3712.0 3785.9 3808.8 3828.4 3834.7 137313.3 141331.3 141527.0 142304.0 143001.3 16783.6 16930.2 17121.6 17195.1 17209.7 31594.7 31776.2 31795.2 31806.4 31837.0 8372.2 8457.5 8561.1 8574.4 8578.0 4161.8 4233.9 4254.3 4258.4 4267.4 5330.5 5390.0 5399.9 5434.8 5468.1 997.0 1013.7 1021.4 1024.2 1024.7 662.1 681.4 682.9 690.8 693.7 11294.2 11788.5 12021.8 12204.1 12420.3 666.6 687.3 694.1 694.2 701.5 5318.2 5341.0 5359.8 5418.0 5426.5 113956.4 115091.0 115925.0 117646.4 120250.0 100868.5 101436.0 107676.0 111627.0 113062.0 97480.0 97719.5 98190.5 100341.0 100930.5 11896.9 12081.9 12109.3 12120.5 12638.6 42866.6 43848.8 43885.3 43908.6 44877.6 68190.3 81332.0 81940.2 86222.0 86583.0 18614.4 18774.0 18788.2 18958.8 19093.4 20711.2 20901.3 21181.6 21189.5 21381.1 17995.8 18197.5 18205.8 18267.8 18356.5 18832.7 19157.0 19250.0 19393.6 19438.5 18037.7 18160.7 18174.0 18194.2 18262.4 13736.2 14000.1 14181.6 14216.2 14239.7 13524.3 13535.5 13548.8 13666.6 13699.3 12:20:40 INFO - PID 3865 | -------- Summary: end -------- 12:20:40 INFO - PID 3865 | 12:20:41 INFO - TEST-INFO | 3865: exit 0 12:20:41 INFO - TEST-OK | dromaeo_css | took 1110086ms 12:20:41 INFO - TEST-START | kraken 12:20:41 INFO - Initialising browser for kraken test... 12:20:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46966/getInfo.html -profile /tmp/tmpeB1QNj/profile 12:20:41 INFO - TEST-INFO | started process 4250 (/builds/slave/test/build/application/firefox/firefox http://localhost:46966/getInfo.html) 12:20:46 INFO - TEST-INFO | 4250: exit 0 12:20:46 INFO - Browser initialized. 12:20:46 INFO - Running cycle 1/1 for kraken test... 12:20:46 INFO - Using env: {'DISPLAY': ':0', 12:20:46 INFO - 'HOME': '/home/cltbld', 12:20:46 INFO - 'JSGC_DISABLE_POISONING': '1', 12:20:46 INFO - 'LANG': 'en_US.UTF-8', 12:20:46 INFO - 'LANGUAGE': 'en_US:en', 12:20:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:20:46 INFO - 'LOGNAME': 'cltbld', 12:20:46 INFO - 'MAIL': '/var/mail/cltbld', 12:20:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:20:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:20:46 INFO - 'MOZ_CRASHREPORTER': '1', 12:20:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:20:46 INFO - 'MOZ_NO_REMOTE': '1', 12:20:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:20:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:20:46 INFO - 'NO_EM_RESTART': '1', 12:20:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:20:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:46 INFO - 'PWD': '/builds/slave/test', 12:20:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:20:46 INFO - 'RUST_BACKTRACE': 'full', 12:20:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:20:46 INFO - 'SHELL': '/bin/bash', 12:20:46 INFO - 'SHLVL': '1', 12:20:46 INFO - 'TERM': 'linux', 12:20:46 INFO - 'TMOUT': '86400', 12:20:46 INFO - 'USER': 'cltbld', 12:20:46 INFO - 'XDG_SESSION_COOKIE': '2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493', 12:20:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:46 INFO - '_': '/tools/buildbot/bin/python'} 12:20:46 INFO - TEST-INFO | started process 4483 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeB1QNj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:20:47 INFO - PID 4483 | 12:20:47 INFO - PID 4483 | (/builds/slave/test/build/application/firefox/firefox:4532): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:20:47 INFO - PID 4483 | 12:20:57 INFO - PID 4483 | RSS: Main: 153784320 12:20:57 INFO - PID 4483 | 12:21:00 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46966/tests/kraken/driver.html?testName=audio-beat-detection) 12:21:01 INFO - PID 4483 | RSS: Main: 161050624 12:21:01 INFO - PID 4483 | 12:21:03 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46966/tests/kraken/driver.html?testName=audio-dft) 12:21:04 INFO - PID 4483 | RSS: Main: 161193984 12:21:04 INFO - PID 4483 | 12:21:07 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46966/tests/kraken/driver.html?testName=audio-fft) 12:21:07 INFO - PID 4483 | RSS: Main: 156315648 12:21:07 INFO - PID 4483 | 12:21:09 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46966/tests/kraken/driver.html?testName=audio-oscillator) 12:21:10 INFO - PID 4483 | RSS: Main: 156794880 12:21:10 INFO - PID 4483 | 12:21:12 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46966/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:21:13 INFO - PID 4483 | RSS: Main: 157368320 12:21:13 INFO - PID 4483 | 12:21:16 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46966/tests/kraken/driver.html?testName=imaging-darkroom) 12:21:17 INFO - PID 4483 | RSS: Main: 159068160 12:21:17 INFO - PID 4483 | 12:21:20 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46966/tests/kraken/driver.html?testName=imaging-desaturate) 12:21:21 INFO - PID 4483 | RSS: Main: 160100352 12:21:21 INFO - PID 4483 | 12:21:24 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46966/tests/kraken/driver.html?testName=json-parse-financial) 12:21:24 INFO - PID 4483 | RSS: Main: 159936512 12:21:24 INFO - PID 4483 | 12:21:26 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46966/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:21:27 INFO - PID 4483 | RSS: Main: 159866880 12:21:27 INFO - PID 4483 | 12:21:28 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46966/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:21:29 INFO - PID 4483 | RSS: Main: 159899648 12:21:29 INFO - PID 4483 | 12:21:31 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46966/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:21:32 INFO - PID 4483 | RSS: Main: 156815360 12:21:32 INFO - PID 4483 | 12:21:34 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46966/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:21:35 INFO - PID 4483 | RSS: Main: 157327360 12:21:35 INFO - PID 4483 | 12:21:38 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46966/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:21:38 INFO - PID 4483 | RSS: Main: 157532160 12:21:38 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | Cycle 1(1): loaded http://localhost:46966/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46966/tests/kraken/driver.html?testName=ai-astar) 12:21:40 INFO - PID 4483 | RSS: Main: 158674944 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | __start_tp_report 12:21:40 INFO - PID 4483 | _x_x_mozilla_page_load 12:21:40 INFO - PID 4483 | _x_x_mozilla_page_load_details 12:21:40 INFO - PID 4483 | |i|pagename|runs| 12:21:40 INFO - PID 4483 | |0;ai-astar;111;108;109;109;109;96;109;111;108;109 12:21:40 INFO - PID 4483 | |1;audio-beat-detection;122;116;117;114;145;115;119;117;119;117 12:21:40 INFO - PID 4483 | |2;audio-dft;161;163;162;162;161;165;162;173;165;168 12:21:40 INFO - PID 4483 | |3;audio-fft;74;74;74;74;75;75;79;75;75;75 12:21:40 INFO - PID 4483 | |4;audio-oscillator;81;80;77;78;80;80;80;80;79;80 12:21:40 INFO - PID 4483 | |5;imaging-gaussian-blur;117;111;111;111;112;112;112;112;112;111 12:21:40 INFO - PID 4483 | |6;imaging-darkroom;149;149;148;148;148;149;150;148;148;147 12:21:40 INFO - PID 4483 | |7;imaging-desaturate;95;95;95;94;95;94;94;94;94;95 12:21:40 INFO - PID 4483 | |8;json-parse-financial;51;51;53;52;58;51;54;53;50;51 12:21:40 INFO - PID 4483 | |9;json-stringify-tinderbox;37;38;38;37;37;38;37;37;38;37 12:21:40 INFO - PID 4483 | |10;stanford-crypto-aes;73;78;74;74;78;73;80;81;75;74 12:21:40 INFO - PID 4483 | |11;stanford-crypto-ccm;114;113;118;110;113;114;109;117;118;124 12:21:40 INFO - PID 4483 | |12;stanford-crypto-pbkdf2;168;169;164;162;164;167;168;165;165;173 12:21:40 INFO - PID 4483 | |13;stanford-crypto-sha256-iterative;81;63;64;71;66;65;65;64;69;64 12:21:40 INFO - PID 4483 | __end_tp_report 12:21:40 INFO - PID 4483 | __start_cc_report 12:21:40 INFO - PID 4483 | _x_x_mozilla_cycle_collect,724 12:21:40 INFO - PID 4483 | __end_cc_report 12:21:40 INFO - PID 4483 | __startTimestamp1506799300875__endTimestamp 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | ------- Summary: start ------- 12:21:40 INFO - PID 4483 | Number of tests: 14 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#0] ai-astar Cycles:10 Average:107.90 Median:109.00 stddev:4.31 (4.0%) stddev-sans-first:4.42 12:21:40 INFO - PID 4483 | Values: 111.0 108.0 109.0 109.0 109.0 96.0 109.0 111.0 108.0 109.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#1] audio-beat-detection Cycles:10 Average:120.10 Median:118.00 stddev:9.04 (7.7%) stddev-sans-first:9.56 12:21:40 INFO - PID 4483 | Values: 122.0 116.0 117.0 114.0 145.0 115.0 119.0 117.0 119.0 117.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#2] audio-dft Cycles:10 Average:164.20 Median:164.00 stddev:3.79 (2.3%) stddev-sans-first:3.84 12:21:40 INFO - PID 4483 | Values: 161.0 163.0 162.0 162.0 161.0 165.0 162.0 173.0 165.0 168.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#3] audio-fft Cycles:10 Average:75.00 Median:75.00 stddev:1.49 (2.0%) stddev-sans-first:1.54 12:21:40 INFO - PID 4483 | Values: 74.0 74.0 74.0 74.0 75.0 75.0 79.0 75.0 75.0 75.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#4] audio-oscillator Cycles:10 Average:79.50 Median:80.00 stddev:1.18 (1.5%) stddev-sans-first:1.12 12:21:40 INFO - PID 4483 | Values: 81.0 80.0 77.0 78.0 80.0 80.0 80.0 80.0 79.0 80.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#5] imaging-gaussian-blur Cycles:10 Average:112.10 Median:112.00 stddev:1.79 (1.6%) stddev-sans-first:0.53 12:21:40 INFO - PID 4483 | Values: 117.0 111.0 111.0 111.0 112.0 112.0 112.0 112.0 112.0 111.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 12:21:40 INFO - PID 4483 | Values: 149.0 149.0 148.0 148.0 148.0 149.0 150.0 148.0 148.0 147.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 12:21:40 INFO - PID 4483 | Values: 95.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 94.0 95.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.50 stddev:2.32 (4.4%) stddev-sans-first:2.40 12:21:40 INFO - PID 4483 | Values: 51.0 51.0 53.0 52.0 58.0 51.0 54.0 53.0 50.0 51.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#9] json-stringify-tinderbox Cycles:10 Average:37.40 Median:37.50 stddev:0.52 (1.4%) stddev-sans-first:0.53 12:21:40 INFO - PID 4483 | Values: 37.0 38.0 38.0 37.0 37.0 38.0 37.0 37.0 38.0 37.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:76.50 stddev:2.98 (3.9%) stddev-sans-first:2.96 12:21:40 INFO - PID 4483 | Values: 73.0 78.0 74.0 74.0 78.0 73.0 80.0 81.0 75.0 74.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#11] stanford-crypto-ccm Cycles:10 Average:115.00 Median:115.50 stddev:4.40 (3.8%) stddev-sans-first:4.65 12:21:40 INFO - PID 4483 | Values: 114.0 113.0 118.0 110.0 113.0 114.0 109.0 117.0 118.0 124.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:167.50 stddev:3.17 (1.9%) stddev-sans-first:3.32 12:21:40 INFO - PID 4483 | Values: 168.0 169.0 164.0 162.0 164.0 167.0 168.0 165.0 165.0 173.0 12:21:40 INFO - PID 4483 | 12:21:40 INFO - PID 4483 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:65.50 stddev:5.45 (8.3%) stddev-sans-first:2.65 12:21:40 INFO - PID 4483 | Values: 81.0 63.0 64.0 71.0 66.0 65.0 65.0 64.0 69.0 64.0 12:21:40 INFO - PID 4483 | -------- Summary: end -------- 12:21:40 INFO - PID 4483 | 12:21:41 INFO - PID 4483 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:21:41 INFO - TEST-INFO | 4483: exit 0 12:21:41 INFO - TEST-OK | kraken | took 60385ms 12:21:41 INFO - SUITE-END | took 1170s 12:21:41 INFO - Completed test suite (00:19:30) 12:21:41 INFO - javascript benchmark 12:21:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8572.806202892067, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3183.6497985221627, 3202.695743904328, 3206.919827004326, 3217.5817143771305, 3220.613526376834, 6145.231846019247, 6200.720967556459, 6306.8738625227525, 6317.873642527146, 6348.047792831072, 6241.906371404421, 6278.0269058296035, 6309.242890853104, 6312.558120931537, 6315.747370105202, 5736.569757268201, 5789.305283366002, 5799.73901174446, 5808.390119037504, 5935.376785437532, 5905.173275741378, 5917.378675239081, 5946.821595352147, 5948.31594366649, 5952.70236488176, 5855.063189889618, 5893.8821223575505, 5918.615290006136, 5965.373635768247, 5971.134185543094, 5634.464725851038, 5763.596548241625, 5839.416058394169, 5894.292684877799, 5912.17229587858, 5785.5660825437835, 5797.913031304533, 5855.8536036599, 5866.589338746289, 5876.441738034922, 6281.528885333572, 6332.936670633327, 6333.398327158912, 6386.393292637192, 6418.326075762025, 6598.307177746316, 6713.798586042427, 6775.525713200078, 6781.186257649064, 6844.178698556156, 6822.897656535156, 6910.2053263874795, 6914.9308506915295, 6925.445964322842, 6926.0, 6334.683265836689, 6338.207724034496, 6414.2302923648995, 6422.743090276384, 6424.743010279583, 6772.762953296709, 6919.723211071601, 6955.130608673916, 6968.163820341591, 7048.577085374792, 6568.277489476154, 6642.335766423319, 6705.362990515893, 6737.3599508046655, 6754.45964322853, 6791.388775010273, 6806.081179040865, 6849.863002739918, 6877.759278425231, 6886.070380498669, 6601.933980660228, 6719.361660642231, 6762.492813039042, 6782.050512928194, 6810.62541560219, 6609.173853268341, 6716.0, 6775.22084960226, 6787.0, 6789.694463749143, 5253.632245742761, 5291.3385826771655, 5323.281357016753, 5337.172738225504, 5337.8131765388025, 4567.703099298535, 4611.100835337077, 4618.976905115453, 4656.650751193674, 4711.552402521755, 5826.82519524415, 5873.500752436005, 5930.555208359304, 5934.13954976526, 5937.821865344046, 6777.898331525031, 6791.728330866808, 6879.071325371011, 6886.655667216619, 6897.241303456616, 5693.0891057431445, 5782.797602083991, 5784.421557844182, 5785.0454674978155, 5794.21778059965, 5769.942300577024, 5852.590318677737, 5854.560907931837, 5856.092305692625, 5895.322037965603, 6525.771597994048, 6600.86798264032, 6613.603183808987, 6614.470842332602, 6669.866602668017, 6738.494612903954, 6754.290799465985, 6755.3582409671, 6764.120664313607, 6837.247902730596, 6432.807015789534, 6523.380278873499, 6523.7716679915975, 6530.8367290818105, 6533.67331633407, 6578.671066446755, 6631.204255489372, 6730.697118629576, 6747.954067119605, 6748.460123190035, 6760.492963027891, 6866.553674011173, 6869.862602747817, 6873.312668733087, 6879.2432832389395, 6527.249366322933, 6537.57505762143, 6582.308857570078, 6637.4690024798865, 6640.302768209269, 6355.650439225702, 6485.027245912962, 6532.5427220096035, 6614.371634694697, 6627.105340778933, 6356.555041147075, 6468.126802881551, 6469.935300646934, 6494.837629059311, 6498.155239818982, 6618.106555614931, 6656.367645073904, 6728.024436456784, 6736.528443008942, 6745.426638735762, 6406.0, 6407.615543067244, 6416.230052393743, 6493.253275873335, 6497.090407342881], "value": 6180.428436558246, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [857.6783706110209, 873.1748497669705, 885.654594708064, 910.1581037540274, 915.9816803663927, 39986.200275994466, 40758.388624170584, 40787.59212407875, 40857.79571102148, 40895.0, 38397.23205535887, 38563.22873542528, 38571.614283857154, 38636.61363386365, 38642.8067859661, 7176.354128128467, 7265.89101163483, 7333.706651733924, 7337.559746415205, 7372.74195403161, 7292.854142917165, 7384.446166537505, 7394.667239974214, 7402.777916662509, 7433.256674332578, 7373.926260737405, 7971.322437592811, 8170.836583268331, 8176.918230817705, 8178.672853085899, 8019.518828870287, 8040.075391329984, 8089.95955020227, 8112.391570632197, 8115.188481151926, 7759.573223472707, 7874.173211812735, 7915.802104947365, 7935.285824275843, 7969.521828690297, 37931.62068379309, 38377.0, 38463.423048654295, 38501.807490962645, 38668.80665596654, 3483.738719596015, 3531.4526248431534, 3531.788092714445, 3539.80531070791, 3541.8937431877084, 30792.230194245098, 30985.0704478866, 31084.378312433626, 31141.532877006863, 31159.53260701091, 5192.1173400522, 5200.0, 5201.8959620807755, 5210.088234558937, 5220.947790522084, 184638.0, 185778.99999999866, 186194.0, 186615.0, 187841.0, 46054.078918421445, 46333.53666463292, 46357.30464043042, 46492.140314387725, 46723.29915051277, 48797.75601122043, 48836.755816221405, 48898.51101489011, 48961.75519122381, 48964.51035489671, 15653.530394088195, 15657.60855978587, 15849.0, 15882.205889705469, 15902.681946361008, 13001.349932503526, 13015.154014989184, 13023.153495022792, 13023.348832558335, 13025.869741302466, 13453.663658408617, 13597.728045439037, 13622.727545449035, 13629.659258518419, 13754.105983111065, 5479.75341109651, 5502.147167189092, 5582.404182055364, 5648.096304591328, 5661.716914154358, 1003.242551873331, 1017.7150397888603, 1023.959041638341, 1024.3239461955222, 1043.3166276089173, 22898.65652015221, 22948.885255573616, 23026.194083207007, 23055.654165187534, 23598.528029439658, 1121.6971417717334, 1131.9603813866474, 1132.4960392625367, 1135.982960255597, 1140.1448913315014, 12156.209846359958, 12299.0, 12346.876531234753, 12363.93818030904, 12623.747525049448, 223878.0, 225577.0, 226730.0, 226921.0, 227364.0, 45671.31493027607, 45745.77127114343, 45810.54189458129, 46371.0, 52250.477495224564, 1348.3460521646994, 1388.7014291927285, 1392.5335012770634, 1394.637394277495, 1431.7494438473225, 26392.8680356597, 26572.734272657028, 26578.0, 26638.200853974413, 26967.325816854736, 36731.81634091812, 36823.63176368202, 36852.81573592115, 36856.07859803527, 36888.81555592205, 185345.0, 185939.0, 186052.0, 186787.0, 186850.0, 32351.0, 32377.35245295128, 32520.51219231761, 32651.18372040718, 32934.670653293164, 39896.60103398929, 41185.0, 41995.7900210497, 42056.369154462096, 42097.36853947132, 30272.697273026988, 30811.845940770152, 30994.535081973336, 31051.223719407197, 31269.8436507816, 30743.69256307498, 30981.690183097882, 31026.379472410877, 31143.53284700686, 31775.68224317727, 33942.3211535763, 34298.14254643654, 34374.0, 34391.82804085963, 34485.31029379348, 29916.850415747784, 29964.250893727833, 30254.0, 30290.0, 30978.845105774326, 22267.554648907255, 22398.440038999157, 22413.43966400853, 22651.320460386214, 22919.197828076605], "value": 16869.7338449342, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.4699523433674, 586.7770247306023, 597.7907732293695, 605.9394060593941, 609.8048624440179, 21986.340409787714, 22177.223797167102, 22797.974091165917, 22802.771972280312, 22803.201887933927, 24604.384890377703, 25156.496870062587, 25177.74822251777, 25199.244022679348, 25385.87307063467, 6609.372109649576, 6750.459963202957, 6777.2545020048, 6777.423918966893, 6787.185537735479, 6254.624722516641, 6501.609903405811, 6552.93447065528, 6594.07682924391, 6630.469562434993, 6520.804375868732, 6537.248216455121, 6538.509611779112, 6542.247641521239, 6542.967285163568, 6486.578372405778, 6502.512311576627, 6517.771877984248, 6522.673866306689, 6533.673316334211, 6045.12345709874, 6050.6369617823075, 6078.726457309432, 6098.3596722343955, 6114.541409394269, 27361.589576156373, 27959.440811183867, 28345.574816377564, 28504.572431413337, 28579.571306430422, 2081.053120830034, 2101.432613194444, 2108.8734675919495, 2111.926082587102, 2112.8943552822298, 19444.125014374386, 19608.117634706472, 19793.50516237091, 19854.205831766856, 19950.201991920305, 2394.2761211927386, 2400.23192578375, 2473.6784218051716, 2479.528889511005, 2488.427661637815, 136011.0, 138702.0, 139180.0, 139354.0, 139581.0, 14667.48663796762, 15252.0, 15282.31229594671, 15413.691726165414, 15768.0, 23623.40941476477, 23626.29121126369, 23685.28944131679, 23745.525089498115, 23765.881170594035, 9807.117359437685, 9856.950715246378, 9877.50612469388, 9899.406035637885, 9976.401415915068, 7271.3455788979245, 7518.812029699192, 9038.86441703375, 9070.682526111554, 9094.636214551343, 8780.165884240987, 9438.433693978383, 9444.716658500256, 9571.808563828685, 9576.473293968898, 2766.9446611067665, 2771.1270949650793, 2776.8750406231366, 2790.2745286225727, 2839.872205750746, 946.4226821638795, 963.1091240602415, 976.6532880827347, 979.1432496565506, 981.7545613596601, 19063.809361906482, 19168.424947251606, 19290.517737056685, 19595.60808783816, 19627.705584416246, 994.6966175316597, 999.5002498750624, 1012.2172733832582, 1021.918246540275, 1026.440589878518, 10857.239993200557, 10942.56229750817, 10986.395748233925, 11030.338179709242, 11302.0, 467864.0, 474358.0, 479247.0, 483885.161148384, 494652.0, 112904.0, 113942.43028784802, 114820.0, 115645.42177289228, 116577.41711291559, 1133.240728711765, 1164.190887333312, 1164.574930150506, 1168.4041139019162, 1170.3796987596588, 17555.0344731041, 17884.642307153525, 17891.552711182325, 17997.370092046716, 18064.638707226044, 54368.45631543634, 57114.42885571091, 57335.71332143479, 57472.42527574838, 57529.71235143798, 70466.64766676133, 70709.0, 71996.64001679957, 72606.63696681483, 72692.63653681698, 14503.92748036253, 14875.94223458821, 14951.401943922121, 14990.625234369229, 14997.2501374933, 22584.322470325915, 22745.658815117455, 22838.543229135656, 22980.0, 23165.3050408488, 18249.635007299516, 18351.724724128882, 18438.170282337058, 18551.35070272534, 18689.345872894384, 15412.614684632974, 15761.290741916411, 16110.838891611404, 16138.515844524683, 16450.67098658044, 18370.72443913369, 18593.256269749058, 18731.250749969848, 18739.437816865517, 18794.718079229096, 14418.639534011314, 14481.493147739779, 14602.0, 14630.195339256443, 14665.266736663338, 18136.0, 18181.909090454465, 18198.272069117087, 18237.0, 18302.0], "value": 11930.58080712178, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.414195682921, 1372.5676411930244, 1375.5735721926203, 1384.7992041154027, 1394.4143459746906, 14834.554963351104, 15400.152991585472, 15428.074315541062, 15439.691206175898, 15443.45947891824, 15265.465708700198, 15295.617609559766, 15368.923155384235, 15376.692466150671, 15382.769258461132, 2764.834109953404, 2847.074700722263, 2852.215640698804, 2855.2433605094666, 2863.083813179783, 2779.4163225722623, 2796.7762578993634, 2804.8878044878284, 2812.7749780017652, 2828.490871643103, 2750.752432281094, 2794.9720502794917, 2808.8314701117897, 2813.8874445022275, 2819.4502072095947, 2646.603009548564, 2657.8139530232893, 2671.4389978104623, 2672.4521473098066, 2675.3177939625466, 2675.0770984010487, 2730.30377253801, 2732.0574401831336, 2741.533939230336, 2742.067696983035, 14878.256087195705, 15025.774613380809, 15031.774523382159, 15035.699286014218, 15046.247687615576, 8095.959520202361, 8343.54110523927, 8349.624266907944, 8372.748817535483, 8374.958125209396, 5138.383393992707, 5374.435684253136, 5487.314085739283, 5503.862403439906, 5520.199571062173, 1952.6778081616517, 1966.5280332720188, 2012.2856385982911, 2050.979490205094, 2068.379486154163, 23636.1727339544, 24100.517989640284, 25593.23220303394, 26447.206583802516, 26506.072287469848, 6913.930860691429, 6923.307669233036, 6969.860602788017, 7067.787966361017, 7151.964240178766, 17185.0, 17223.56941076458, 17255.3960611378, 17286.22212000463, 17300.134993250285, 7984.68061277554, 8049.4365394423, 8101.6354264058255, 8145.266925976572, 8168.183181681784, 7341.082364704412, 7398.297161769623, 7502.0, 7508.587027713528, 7554.131274903347, 5085.694858308513, 5200.97399513, 5266.552343050807, 5279.947200528022, 5288.889333240063, 1530.7780371846243, 1534.6547026918854, 1534.6777176792998, 1549.7210502109729, 1549.7365447873888, 912.9726108216764, 916.564631799903, 924.2790623313691, 933.0, 936.4521754773534, 7753.689852405841, 7755.92244077552, 7790.493617914817, 7835.2948234658015, 7862.410319226081, 713.9393151582174, 716.8279612892868, 717.1358512991833, 725.6770737021975, 731.6853752886203, 5122.795088196505, 5253.13323301651, 5388.7305634718105, 5632.352279487829, 5661.490465858093, 20914.268000619904, 21441.0, 21622.89188554047, 21845.0, 21870.45323866916, 21042.579148416946, 21511.78488215098, 21666.56666866654, 21815.345539633836, 21927.561448770935, 10605.946970265099, 10797.730056748644, 10806.837897431546, 11151.553937842558, 11355.261907975955, 8158.755237342889, 8315.126911674188, 8328.333733301442, 8408.87386689188, 8725.563721814011, 18303.816961830213, 18381.54046148857, 18524.44426667202, 18560.0, 18891.811081889555, 18725.344612939025, 18852.24591016344, 18946.52633684169, 19053.904730476257, 19159.712604311226, 11143.554257829921, 11345.829812552653, 11364.715882103013, 11365.772684546428, 11366.886331136913, 14110.082844615066, 14196.645083872985, 14206.07660502064, 14344.067635603653, 14395.352209150822, 11417.1437142213, 11527.135464840003, 11564.768704626029, 11644.0, 11838.881611184122, 11142.220044596801, 11261.380624065427, 11547.538098475967, 11615.825762613737, 11729.530818767154, 9823.803523929624, 10006.749831254278, 10098.192144628265, 10102.292839501164, 10102.343347682377, 6737.730490780314, 6776.424003959719, 6903.482238832007, 6910.689018994057, 6994.02083708271, 7467.551946883204, 7554.433417493819, 7605.923940760522, 7621.847563048819, 7737.845243095219], "value": 6680.8991482888805, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.74585215687864, 532.1246549426194, 535.4591862219157, 535.8955003774261, 537.1629028721771, 6195.845103872405, 6225.0, 6316.020906828698, 6324.873502529947, 6333.904991425133, 6158.353372895848, 6204.81385558434, 6216.595921265114, 6245.281792593853, 6280.0, 5189.0, 5192.870178245536, 5199.922001169985, 5216.2958000669805, 5216.8174113906025, 4963.35476388069, 5043.218301163325, 5073.112205364065, 5161.17421212606, 5188.948110518903, 4937.23472861707, 5006.799728010875, 5037.697738135706, 5039.143345631233, 5052.24216367546, 4895.265710143472, 4962.776675049596, 4982.177940639772, 4997.925031124536, 5008.7996480140755, 4482.417285752864, 4483.730976141442, 4521.349707356824, 4529.977350113261, 4535.591796738309, 6014.30835453924, 6061.575689701723, 6097.695115244264, 6108.755649774003, 6152.600080994721, 5623.240862483594, 5634.239377684042, 5647.943520564783, 5690.374058853523, 5694.2312787773535, 6406.2953075161695, 6467.579607325509, 6566.277709452004, 6569.37590928861, 6577.80266592003, 4921.163402221631, 4925.310456536088, 4950.554450099508, 4961.355023846912, 4976.2784396262705, 6665.633390163539, 6712.395884370381, 6816.625085620339, 6862.588244705334, 6880.2431732509385, 5033.119204139261, 5063.911259079242, 5067.315912351859, 5090.312807770978, 5102.668326558836, 5107.974460127675, 5117.41149767774, 5145.151050076695, 5158.226266060121, 5165.483451654804, 5725.341585717612, 5732.168835518826, 5761.798337058183, 5763.464075806632, 5781.797637082682, 6605.1083103780375, 6630.7679231226675, 6700.497462690318, 6700.765473208415, 6714.160729908762, 1785.5179101642484, 1785.9821401785816, 1790.7940586832422, 1794.8294911983342, 1801.3785244090768, 1179.168686076314, 1228.2804074295877, 1241.366902879538, 1257.0948916779903, 1257.7547378261152, 5744.167095771173, 5767.394423585547, 5767.88464230713, 5793.449622285876, 5805.680687562224, 6752.0, 6755.662216889136, 6758.256591774901, 6758.763443279461, 6795.898061529081, 5757.251557297608, 5765.365809760923, 5779.450952159538, 5792.536597072224, 5824.0, 3535.646435356444, 3536.3457760314423, 3541.149374753348, 3542.946855797165, 3550.7336949728356, 6184.474319682788, 6501.739930402826, 6515.120458738104, 6569.704363303663, 6575.572587781779, 6826.596845035024, 6829.52193346461, 6886.449084073262, 6886.51794374389, 6920.273371296042, 6408.903866442007, 6435.420812126931, 6472.385123413268, 6472.838179045468, 6536.967315163489, 6573.19237209752, 6581.407673309425, 6692.230393504809, 6770.2891196425635, 6813.0, 6556.541042127005, 6647.401733843977, 6667.899981500378, 6726.7981960541265, 6743.865122697421, 6492.156019717407, 6503.382178693017, 6511.73953041873, 6514.478841692748, 6568.934310656832, 5037.92443113361, 5042.117629414764, 5048.1165795984825, 5066.82266120684, 5109.241009854637, 6516.609003459618, 6519.250286217146, 6553.311902250195, 6853.657317134223, 6878.415334696607, 6129.448349648553, 6361.554691171574, 6387.77642782519, 6438.195225596801, 6523.3898846990605, 6375.489960803217, 6407.391297826699, 6530.67346632676, 6541.771038013647, 6566.737330506726, 6375.234971803413, 6376.107344971801, 6448.516361272829, 6450.258220304725, 6540.0, 6504.918426103602, 6528.804135875931, 6529.085927969993, 6550.213974323112, 6599.307072757483, 6272.243771743919, 6274.686265686789, 6278.46633036197, 6309.936900630935, 6391.552591318563], "value": 5076.781892155237, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [907.2016625369673, 938.9624415023397, 942.2509105261321, 943.1087622197026, 943.9150476457122, 20005.399838004883, 20232.59534809303, 20545.794542054595, 20569.074391652375, 20659.0, 19371.612567748638, 19728.506787330323, 19820.50448738782, 19822.40532784015, 19825.504362390948, 973.6981535723949, 1001.319103009953, 1004.4826914139225, 1007.4710776842143, 1007.9949600251988, 960.4669408478292, 984.6996666016862, 985.2019863910256, 986.5807032011402, 1001.0640051551786, 1046.6807623674777, 1048.973775655611, 1048.984265236022, 1050.5325130317012, 1061.3207547169818, 1043.1758910460726, 1043.7129789307926, 1046.9895301047006, 1048.1614708233421, 1055.6780182044474, 758.8216769059267, 765.9157492675857, 767.5893397032561, 769.0502229746259, 774.2915232562199, 20257.594848103105, 20548.17807287707, 20686.896565517225, 20857.374278771662, 20875.269365572283, 21105.577888442145, 21684.89157554218, 22405.43986400337, 22410.663840042413, 22416.32751017472, 17188.624910007293, 17513.912430437893, 17531.824681753147, 17571.5607109822, 17586.560335991573, 3711.9814400928094, 3785.9053523661855, 3808.771473711586, 3828.444875493066, 3834.6740527055235, 137313.31343343318, 141331.29334353365, 141527.0, 142304.0, 143001.28499357542, 16783.58041048959, 16930.153492325582, 17121.571960701083, 17195.1402429878, 17209.655806884042, 31594.684053159635, 31776.20559485985, 31795.20511987219, 31806.363872722417, 31837.0, 8372.204640559137, 8457.53483558398, 8561.0582835888, 8574.442661227, 8578.0, 4161.791910404468, 4233.894152646209, 4254.255505286563, 4258.425112609758, 4267.445232119804, 5330.546903513167, 5390.029794637003, 5399.865003374868, 5434.83695489136, 5468.097763868917, 996.9750756231152, 1013.7465633591602, 1021.4075836015094, 1024.2164743970868, 1024.7438140464883, 662.1259936883267, 681.431005110738, 682.8873235916118, 690.848013437043, 693.6809067828855, 11294.152938529644, 11788.528458861721, 12021.759564808655, 12204.145709800407, 12420.254784712946, 666.6166954001469, 687.3229868579446, 694.0838093716246, 694.2363400259674, 701.4773993374866, 5318.175682769177, 5341.0, 5359.839204823861, 5418.0, 5426.484483974016, 113956.43021785004, 115091.0, 115925.0, 117646.41176794062, 120250.0, 100868.49565752124, 101436.0, 107676.0, 111627.0, 113062.0, 97480.0, 97719.51140244395, 98190.50904745431, 100341.0, 100930.49534752427, 11896.881031189578, 12081.879181208427, 12109.273443593413, 12120.515179393078, 12638.620841374774, 42866.57133428626, 43848.780756096014, 43885.34171987359, 43908.560914390444, 44877.55122448734, 68190.3180968184, 81332.0, 81940.18059819564, 86222.0, 86583.0, 18614.44156675302, 18774.0, 18788.154533045774, 18958.810411896255, 19093.42719718355, 20711.171553137705, 20901.268455603982, 21181.57636847285, 21189.470263243544, 21381.144754209658, 17995.82004179994, 18197.45407637773, 18205.817941820944, 18267.817321826613, 18356.54108647241, 18832.7175092371, 19157.0, 19250.0, 19393.612127757082, 19438.51403714862, 18037.729434058238, 18160.72758908591, 18174.0, 18194.181261842983, 18262.36081737133, 13736.175829450265, 14000.089994150349, 14181.574552763434, 14216.21810800416, 14239.71520569562, 13524.256165910969, 13535.458581656623, 13548.796768048684, 13666.590002299947, 13699.315034248448], "value": 9408.081081543296, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1416.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 108.0, 109.0, 109.0, 109.0, 96.0, 109.0, 111.0, 108.0, 109.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 116.0, 117.0, 114.0, 145.0, 115.0, 119.0, 117.0, 119.0, 117.0], "value": 120.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 162.0, 162.0, 161.0, 165.0, 162.0, 173.0, 165.0, 168.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 74.0, 75.0, 75.0, 79.0, 75.0, 75.0, 75.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 77.0, 78.0, 80.0, 80.0, 80.0, 80.0, 79.0, 80.0], "value": 79.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 111.0, 111.0, 111.0, 112.0, 112.0, 112.0, 112.0, 112.0, 111.0], "value": 112.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 148.0, 148.0, 148.0, 149.0, 150.0, 148.0, 148.0, 147.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 53.0, 52.0, 58.0, 51.0, 54.0, 53.0, 50.0, 51.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 38.0, 37.0, 37.0, 38.0, 37.0, 37.0, 38.0, 37.0], "value": 37.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 74.0, 74.0, 78.0, 73.0, 80.0, 81.0, 75.0, 74.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 118.0, 110.0, 113.0, 114.0, 109.0, 117.0, 118.0, 124.0], "value": 115.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 164.0, 162.0, 164.0, 167.0, 168.0, 165.0, 165.0, 173.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 63.0, 64.0, 71.0, 66.0, 65.0, 65.0, 64.0, 69.0, 64.0], "value": 67.2, "unit": "score"}]}]} 12:21:41 INFO - Return code: 0 12:21:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:21:41 INFO - # TBPL SUCCESS # 12:21:41 INFO - Running post-action listener: _package_coverage_data 12:21:41 INFO - Running post-action listener: _resource_record_post_action 12:21:41 INFO - [mozharness: 2017-09-30 19:21:41.636075Z] Finished run-tests step (success) 12:21:41 INFO - Running post-run listener: _resource_record_post_run 12:21:41 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 11309056; Write bytes: 461934592; Read time: 3804; Write time: 397100 12:21:41 INFO - TinderboxPrint: CPU usage
13.1% 12:21:41 INFO - TinderboxPrint: I/O read bytes / time
11,309,056 / 3,804 12:21:41 INFO - TinderboxPrint: I/O write bytes / time
461,934,592 / 397,100 12:21:41 INFO - TinderboxPrint: CPU idle
8,234.6 (86.9%) 12:21:41 INFO - TinderboxPrint: CPU user
1,192.9 (12.6%) 12:21:41 INFO - TinderboxPrint: Swap in / out
0 / 0 12:21:41 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 42655744; Read time: 0; Write time: 12336 12:21:41 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:21:41 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 9113600; Write bytes: 327995392; Read time: 3352; Write time: 275168 12:21:42 INFO - Running post-run listener: _upload_blobber_files 12:21:42 INFO - Blob upload gear active. 12:21:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:21:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:21:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:21:42 INFO - (blobuploader) - INFO - Open directory for files ... 12:21:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:21:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:21:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:43 INFO - (blobuploader) - INFO - Done attempting. 12:21:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:21:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:21:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:45 INFO - (blobuploader) - INFO - Done attempting. 12:21:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:21:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:21:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:46 INFO - (blobuploader) - INFO - Done attempting. 12:21:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:21:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:21:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:54 INFO - (blobuploader) - INFO - Done attempting. 12:21:54 INFO - (blobuploader) - INFO - Iteration through files over. 12:21:54 INFO - Return code: 0 12:21:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:21:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:21:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb14ce03aa4a59bfc41c7029f030a849a66599b34b76abab29c9d9e64aaf23564675e3815df2a83435127f59f0c94b085c5974165a8bc6e0946138529bbe87c4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0336789eb9fd92e96658943e13123c63e8e0d53d08335c98494a07c952a9fd4e088cfbae4f8551ba7c9acdfc38cf40948b5c08608044d61b4022cb64f81d324e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/beca91daec9f145bffbf91c72043fbec1d2b96c500c08d29ff2e4bc768dd9a4c6778ecaed4eb4f7abd7bace75b651e1ff7a4910456394188a888b16c9daa1a65", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7780a59660106a5ffeb82f4ad19187b97afc15f294a5257595a269bdf9e5a03f4c892ff6db049bc862b3e715ef96dd16ee8792dbcc3a743d6c1c3ca49d7b0941"} 12:21:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:21:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:21:54 INFO - Contents: 12:21:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb14ce03aa4a59bfc41c7029f030a849a66599b34b76abab29c9d9e64aaf23564675e3815df2a83435127f59f0c94b085c5974165a8bc6e0946138529bbe87c4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0336789eb9fd92e96658943e13123c63e8e0d53d08335c98494a07c952a9fd4e088cfbae4f8551ba7c9acdfc38cf40948b5c08608044d61b4022cb64f81d324e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/beca91daec9f145bffbf91c72043fbec1d2b96c500c08d29ff2e4bc768dd9a4c6778ecaed4eb4f7abd7bace75b651e1ff7a4910456394188a888b16c9daa1a65", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7780a59660106a5ffeb82f4ad19187b97afc15f294a5257595a269bdf9e5a03f4c892ff6db049bc862b3e715ef96dd16ee8792dbcc3a743d6c1c3ca49d7b0941"} 12:21:54 INFO - Running post-run listener: copy_logs_to_upload_dir 12:21:54 INFO - Copying logs to upload dir... 12:21:54 INFO - mkdir: /builds/slave/test/build/upload/logs 12:21:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.142195 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-30 12:21:54.912140) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-30 12:21:54.920978) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb14ce03aa4a59bfc41c7029f030a849a66599b34b76abab29c9d9e64aaf23564675e3815df2a83435127f59f0c94b085c5974165a8bc6e0946138529bbe87c4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0336789eb9fd92e96658943e13123c63e8e0d53d08335c98494a07c952a9fd4e088cfbae4f8551ba7c9acdfc38cf40948b5c08608044d61b4022cb64f81d324e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/beca91daec9f145bffbf91c72043fbec1d2b96c500c08d29ff2e4bc768dd9a4c6778ecaed4eb4f7abd7bace75b651e1ff7a4910456394188a888b16c9daa1a65", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7780a59660106a5ffeb82f4ad19187b97afc15f294a5257595a269bdf9e5a03f4c892ff6db049bc862b3e715ef96dd16ee8792dbcc3a743d6c1c3ca49d7b0941"} build_url:https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011195 build_url: 'https://queue.taskcluster.net/v1/task/K5-cUQKoRSWuOasNB_ERwQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/eb14ce03aa4a59bfc41c7029f030a849a66599b34b76abab29c9d9e64aaf23564675e3815df2a83435127f59f0c94b085c5974165a8bc6e0946138529bbe87c4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0336789eb9fd92e96658943e13123c63e8e0d53d08335c98494a07c952a9fd4e088cfbae4f8551ba7c9acdfc38cf40948b5c08608044d61b4022cb64f81d324e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/beca91daec9f145bffbf91c72043fbec1d2b96c500c08d29ff2e4bc768dd9a4c6778ecaed4eb4f7abd7bace75b651e1ff7a4910456394188a888b16c9daa1a65", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7780a59660106a5ffeb82f4ad19187b97afc15f294a5257595a269bdf9e5a03f4c892ff6db049bc862b3e715ef96dd16ee8792dbcc3a743d6c1c3ca49d7b0941"}' ========= master_lag: 0.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-30 12:21:55.183366) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 12:21:55.183722) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2432103b8b3de4003b23cad4000001c2-1506797140.659222-918718493 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 12:21:55.232121) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-30 12:21:55.232422) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-30 12:21:55.232745) ========= ========= Total master_lag: 0.58 =========