builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-045 starttime: 1507056437.06 results: success (0) revision: 9be05b2177667ed8221f9da4fdcc200dbdf3de62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:17.057977) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:17.058456) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:17.058735) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:17.076814) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:17.077326) ========= 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=4300f881736b422476174874000001bc-1507056436.139313-1322947943 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-03 11:47:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.3M=0s 2017-10-03 11:47:17 (50.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128025 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:17.224064) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:17.224387) ========= 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=4300f881736b422476174874000001bc-1507056436.139313-1322947943 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015725 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:17.274132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-03 11:47:17.274469) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9be05b2177667ed8221f9da4fdcc200dbdf3de62 --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 9be05b2177667ed8221f9da4fdcc200dbdf3de62 --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=4300f881736b422476174874000001bc-1507056436.139313-1322947943 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-03 11:47:17,249 truncating revision to first 12 chars 2017-10-03 11:47:17,249 Setting DEBUG logging. 2017-10-03 11:47:17,249 attempt 1/10 2017-10-03 11:47:17,249 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9be05b217766?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-03 11:47:18,169 unpacking tar archive at: mozilla-central-9be05b217766/testing/mozharness/ program finished with exit code 0 elapsedTime=1.226522 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-03 11:47:18.517941) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:18.518314) ========= script_repo_revision: 9be05b2177667ed8221f9da4fdcc200dbdf3de62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:18.518770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:18.519109) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 11:47:18.533219) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-10-03 11:47:18.533622) ========= /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=4300f881736b422476174874000001bc-1507056436.139313-1322947943 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:47:18 INFO - MultiFileLogger online at 20171003 11:47:18 in /builds/slave/test 11:47:18 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 11:47:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:47:18 INFO - {'append_to_log': False, 11:47:18 INFO - 'base_work_dir': '/builds/slave/test', 11:47:18 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:47:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:47:18 INFO - 'branch': 'Firefox-Non-PGO', 11:47:18 INFO - 'buildbot_json_path': 'buildprops.json', 11:47:18 INFO - 'code_coverage': False, 11:47:18 INFO - 'config_files': ('talos/linux_config.py',), 11:47:18 INFO - 'default_actions': ('clobber', 11:47:18 INFO - 'read-buildbot-config', 11:47:18 INFO - 'download-and-extract', 11:47:18 INFO - 'populate-webroot', 11:47:18 INFO - 'create-virtualenv', 11:47:18 INFO - 'install', 11:47:18 INFO - 'setup-mitmproxy', 11:47:18 INFO - 'run-tests'), 11:47:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:47:18 INFO - 'disable_ccov_upload': False, 11:47:18 INFO - 'disable_stylo': False, 11:47:18 INFO - 'download_minidump_stackwalk': True, 11:47:18 INFO - 'download_symbols': 'ondemand', 11:47:18 INFO - 'enable_stylo': False, 11:47:18 INFO - 'enable_webrender': False, 11:47:18 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:47:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:47:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:47:18 INFO - 'gecko_profile': False, 11:47:18 INFO - 'gecko_profile_interval': 0, 11:47:18 INFO - 'installer_path': 'installer.exe', 11:47:18 INFO - 'log_level': 'info', 11:47:18 INFO - 'log_name': 'talos', 11:47:18 INFO - 'log_to_console': True, 11:47:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:47:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:47:18 INFO - 'opt_config_files': (), 11:47:18 INFO - 'pip_index': False, 11:47:18 INFO - 'suite': 'dromaeojs-e10s', 11:47:18 INFO - 'system_bits': '32', 11:47:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:47:18 INFO - 'title': 'talos-linux64-ix-045', 11:47:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:47:18 INFO - 'use_talos_json': True, 11:47:18 INFO - 'verify': 'False', 11:47:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:47:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:47:18 INFO - 'work_dir': 'build'} 11:47:18 INFO - [mozharness: 2017-10-03 18:47:18.641373Z] Running clobber step. 11:47:18 INFO - Running pre-action listener: _resource_record_pre_action 11:47:18 INFO - Running main action method: clobber 11:47:18 INFO - rmtree: /builds/slave/test/build 11:47:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:47:20 INFO - Running post-action listener: _resource_record_post_action 11:47:20 INFO - [mozharness: 2017-10-03 18:47:20.208517Z] Finished clobber step (success) 11:47:20 INFO - [mozharness: 2017-10-03 18:47:20.208614Z] Running read-buildbot-config step. 11:47:20 INFO - Running pre-action listener: _resource_record_pre_action 11:47:20 INFO - Running main action method: read_buildbot_config 11:47:20 INFO - Using buildbot properties: 11:47:20 INFO - { 11:47:20 INFO - "project": "", 11:47:20 INFO - "product": "firefox", 11:47:20 INFO - "who": "ffxbld@noreply.mozilla.org", 11:47:20 INFO - "installer_path": "public/build/target.tar.bz2", 11:47:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:47:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:47:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:47:20 INFO - "stage_platform": "linux64", 11:47:20 INFO - "basedir": "/builds/slave/test", 11:47:20 INFO - "buildnumber": 1183, 11:47:20 INFO - "platform": "ubuntu64_hw", 11:47:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:47:20 INFO - "slavebuilddir": "test", 11:47:20 INFO - "taskId": "D9a8gTSlRqm1N06WaeOyVA", 11:47:20 INFO - "branch": "mozilla-central", 11:47:20 INFO - "script_repo_revision": "production", 11:47:20 INFO - "revision": "9be05b2177667ed8221f9da4fdcc200dbdf3de62", 11:47:20 INFO - "slavename": "talos-linux64-ix-045", 11:47:20 INFO - "repo_path": "mozilla-central" 11:47:20 INFO - } 11:47:20 INFO - Finding installer, test and symbols from parent task. 11:47:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D9a8gTSlRqm1N06WaeOyVA'}, attempt #1 11:47:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D9a8gTSlRqm1N06WaeOyVA'}, attempt #1 11:47:21 INFO - Task dependencies: Y228oe7GQHKzMAnD1Lmgzg 11:47:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg'}, attempt #1 11:47:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.tar.bz2 11:47:21 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.test_packages.json 11:47:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.crashreporter-symbols.zip 11:47:21 INFO - Running post-action listener: _resource_record_post_action 11:47:21 INFO - [mozharness: 2017-10-03 18:47:21.600347Z] Finished read-buildbot-config step (success) 11:47:21 INFO - [mozharness: 2017-10-03 18:47:21.600566Z] Running download-and-extract step. 11:47:21 INFO - Running pre-action listener: _resource_record_pre_action 11:47:21 INFO - Running main action method: download_and_extract 11:47:21 INFO - mkdir: /builds/slave/test/build/tests 11:47:21 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')]} 11:47:21 INFO - trying https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.test_packages.json 11:47:21 INFO - Downloading https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:47:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:47:22 INFO - Downloaded 989 bytes. 11:47:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:47:22 INFO - Using the following test package requirements: 11:47:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:47:22 INFO - u'common': [u'target.common.tests.zip'], 11:47:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:47:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:47:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:47:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:47:22 INFO - u'mozbase': [u'target.common.tests.zip'], 11:47:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:47:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:47:22 INFO - u'web-platform': [u'target.common.tests.zip', 11:47:22 INFO - u'target.web-platform.tests.tar.gz'], 11:47:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:47:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:47:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.common.tests.zip 11:47:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:47:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.common.tests.zip into memory 11:47:23 INFO - Content-Length response header: 55304212 11:47:23 INFO - Bytes received: 55304212 11:47:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.talos.tests.zip 11:47:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:47:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.talos.tests.zip into memory 11:47:35 INFO - Content-Length response header: 14522427 11:47:35 INFO - Bytes received: 14522427 11:47:36 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')]} 11:47:36 INFO - trying https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.tar.bz2 11:47:36 INFO - Downloading https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:47:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:47:37 INFO - Downloaded 61575035 bytes. 11:47:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.tar.bz2 11:47:37 INFO - mkdir: /builds/slave/test/properties 11:47:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:47:37 INFO - Writing to file /builds/slave/test/properties/build_url 11:47:37 INFO - Contents: 11:47:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.tar.bz2 11:47:37 INFO - Running post-action listener: _resource_record_post_action 11:47:37 INFO - Running post-action listener: find_tests_for_verification 11:47:37 INFO - Running post-action listener: set_extra_try_arguments 11:47:37 INFO - [mozharness: 2017-10-03 18:47:37.934101Z] Finished download-and-extract step (success) 11:47:37 INFO - [mozharness: 2017-10-03 18:47:37.934199Z] Running populate-webroot step. 11:47:37 INFO - Running pre-action listener: _resource_record_pre_action 11:47:37 INFO - Running main action method: populate_webroot 11:47:37 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:47:37 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['tresize', 11:47:37 INFO - 'tcanvasmark']}, 11:47:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:47:37 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['dromaeo_css', 11:47:37 INFO - 'kraken']}, 11:47:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:47:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:47:37 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:47:37 INFO - 'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:47:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:47:37 INFO - 'tests': ['damp', 'tps']}, 11:47:37 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:47:37 INFO - 'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['damp', 'tps']}, 11:47:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:47:37 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['dromaeo_dom']}, 11:47:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:47:37 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['basic_compositor_video', 11:47:37 INFO - 'glvideo']}, 11:47:37 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:47:37 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:47:37 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:47:37 INFO - 'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['ts_paint_webext', 11:47:37 INFO - 'tp5o_webext']}, 11:47:37 INFO - 'other-e10s': {'tests': ['a11yr', 11:47:37 INFO - 'ts_paint', 11:47:37 INFO - 'tpaint', 11:47:37 INFO - 'sessionrestore', 11:47:37 INFO - 'sessionrestore_many_windows', 11:47:37 INFO - 'sessionrestore_no_auto_restore', 11:47:37 INFO - 'tabpaint', 11:47:37 INFO - 'cpstartup']}, 11:47:37 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['a11yr', 11:47:37 INFO - 'ts_paint', 11:47:37 INFO - 'tpaint', 11:47:37 INFO - 'sessionrestore', 11:47:37 INFO - 'sessionrestore_many_windows', 11:47:37 INFO - 'sessionrestore_no_auto_restore', 11:47:37 INFO - 'tabpaint', 11:47:37 INFO - 'cpstartup']}, 11:47:37 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 11:47:37 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:47:37 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['perf_reftest_singletons']}, 11:47:37 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['perf_reftest']}, 11:47:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:47:37 INFO - 'tsvgr_opacity', 11:47:37 INFO - 'tart', 11:47:37 INFO - 'tscrollx', 11:47:37 INFO - 'tsvg_static']}, 11:47:37 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['tsvgx', 11:47:37 INFO - 'tsvgr_opacity', 11:47:37 INFO - 'tart', 11:47:37 INFO - 'tscrollx', 11:47:37 INFO - 'tsvg_static']}, 11:47:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:47:37 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:47:37 INFO - 'talos_options': ['--disable-stylo'], 11:47:37 INFO - 'tests': ['tp5o']}, 11:47:37 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:47:37 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:47:37 INFO - 'talos_options': ['--mitmproxy', 11:47:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:47:37 INFO - '--firstNonBlankPaint'], 11:47:37 INFO - 'tests': ['tp6_google', 11:47:37 INFO - 'tp6_youtube', 11:47:37 INFO - 'tp6_amazon', 11:47:37 INFO - 'tp6_facebook']}, 11:47:37 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:47:37 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:47:37 INFO - 'talos_options': ['--disable-stylo', 11:47:37 INFO - '--mitmproxy', 11:47:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:47:37 INFO - '--firstNonBlankPaint'], 11:47:37 INFO - 'tests': ['tp6_google', 11:47:37 INFO - 'tp6_youtube', 11:47:37 INFO - 'tp6_amazon', 11:47:37 INFO - 'tp6_facebook']}, 11:47:37 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:47:37 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:47:37 INFO - 'talos_options': ['--stylo-threads=1', 11:47:37 INFO - '--mitmproxy', 11:47:37 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:47:37 INFO - '--firstNonBlankPaint'], 11:47:37 INFO - 'tests': ['tp6_google', 11:47:37 INFO - 'tp6_youtube', 11:47:37 INFO - 'tp6_amazon', 11:47:37 INFO - 'tp6_facebook']}, 11:47:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:47:37 INFO - 'talos_options': ['--xperf_path', 11:47:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:47:37 INFO - 'tests': ['tp5n']}, 11:47:37 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:47:37 INFO - 'talos_options': ['--disable-stylo', 11:47:37 INFO - '--xperf_path', 11:47:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:47:37 INFO - 'tests': ['tp5n']}}} 11:47:37 INFO - Running post-action listener: _resource_record_post_action 11:47:37 INFO - [mozharness: 2017-10-03 18:47:37.950264Z] Finished populate-webroot step (success) 11:47:37 INFO - [mozharness: 2017-10-03 18:47:37.950354Z] Running create-virtualenv step. 11:47:37 INFO - Running pre-action listener: _resource_record_pre_action 11:47:37 INFO - Running main action method: create_virtualenv 11:47:37 INFO - Creating virtualenv /builds/slave/test/build/venv 11:47:37 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:47:37 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:47:37 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:47:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:47:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:47:38 INFO - Installing distribute.............................................................................................................................................................................................done. 11:47:38 INFO - Installing pip...............done. 11:47:38 INFO - Return code: 0 11:47:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:47:38 INFO - Reading from file tmpfile_stdout 11:47:38 INFO - Output received: 11:47:38 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:47:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:47: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')]} 11:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47: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')]} 11:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc0d60>, '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 0x1cc1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc1ef0>, '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': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', '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 11:47: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', 'pip>=1.5'] in /builds/slave/test/build 11:47: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 pip>=1.5 11:47:38 INFO - Using env: {'DISPLAY': ':0', 11:47:38 INFO - 'HOME': '/home/cltbld', 11:47:38 INFO - 'LANG': 'en_US.UTF-8', 11:47:38 INFO - 'LANGUAGE': 'en_US:en', 11:47:38 INFO - 'LOGNAME': 'cltbld', 11:47:38 INFO - 'MAIL': '/var/mail/cltbld', 11:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:38 INFO - 'MOZ_NO_REMOTE': '1', 11:47:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:47:38 INFO - 'NO_EM_RESTART': '1', 11:47:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:38 INFO - 'PWD': '/builds/slave/test', 11:47:38 INFO - 'SHELL': '/bin/bash', 11:47:38 INFO - 'SHLVL': '1', 11:47:38 INFO - 'TERM': 'linux', 11:47:38 INFO - 'TMOUT': '86400', 11:47:38 INFO - 'USER': 'cltbld', 11:47:38 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:38 INFO - '_': '/tools/buildbot/bin/python'} 11:47:40 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:47:40 INFO - Downloading/unpacking pip>=1.5 11:47:40 INFO - Running setup.py egg_info for package pip 11:47:40 INFO - warning: no files found matching 'pip/cacert.pem' 11:47:40 INFO - warning: no files found matching '*.html' under directory 'docs' 11:47:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:47:40 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:47:40 INFO - Installing collected packages: pip 11:47:40 INFO - Found existing installation: pip 1.1 11:47:40 INFO - Uninstalling pip: 11:47:40 INFO - Successfully uninstalled pip 11:47:40 INFO - Running setup.py install for pip 11:47:40 INFO - warning: no files found matching 'pip/cacert.pem' 11:47:40 INFO - warning: no files found matching '*.html' under directory 'docs' 11:47:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:47:40 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:47:40 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:47:40 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:47:40 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:47:40 INFO - Successfully installed pip 11:47:40 INFO - Cleaning up... 11:47:40 INFO - Return code: 0 11:47:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:47: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')]} 11:47:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47: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')]} 11:47:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47: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', '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 0x19561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc0d60>, '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 0x1cc1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc1ef0>, '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': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', '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 11:47: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:47: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 psutil>=3.1.1 11:47:40 INFO - Using env: {'DISPLAY': ':0', 11:47:40 INFO - 'HOME': '/home/cltbld', 11:47:40 INFO - 'LANG': 'en_US.UTF-8', 11:47:40 INFO - 'LANGUAGE': 'en_US:en', 11:47:40 INFO - 'LOGNAME': 'cltbld', 11:47:40 INFO - 'MAIL': '/var/mail/cltbld', 11:47:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:40 INFO - 'MOZ_NO_REMOTE': '1', 11:47:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:47:40 INFO - 'NO_EM_RESTART': '1', 11:47:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:47:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:40 INFO - 'PWD': '/builds/slave/test', 11:47:40 INFO - 'SHELL': '/bin/bash', 11:47:40 INFO - 'SHLVL': '1', 11:47:40 INFO - 'TERM': 'linux', 11:47:40 INFO - 'TMOUT': '86400', 11:47:40 INFO - 'USER': 'cltbld', 11:47:40 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:47:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:40 INFO - '_': '/tools/buildbot/bin/python'} 11:47:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:40 INFO - Downloading/unpacking psutil>=3.1.1 11:47: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 11:47: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 11:47: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 11:47: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 11:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:47:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:47:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:47:41 INFO - Installing collected packages: psutil 11:47:41 INFO - Running setup.py install for psutil 11:47:41 INFO - building 'psutil._psutil_linux' extension 11:47:41 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 11:47:41 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 11:47:41 INFO - building 'psutil._psutil_posix' extension 11:47:41 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 11:47:42 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 11:47:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:47:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:47:42 INFO - Successfully installed psutil 11:47:42 INFO - Cleaning up... 11:47:42 INFO - Return code: 0 11:47:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:47:42 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')]} 11:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:42 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')]} 11:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:42 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 0x19561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc0d60>, '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 0x1cc1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc1ef0>, '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': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', '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 11:47:42 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 11:47:42 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 11:47:42 INFO - Using env: {'DISPLAY': ':0', 11:47:42 INFO - 'HOME': '/home/cltbld', 11:47:42 INFO - 'LANG': 'en_US.UTF-8', 11:47:42 INFO - 'LANGUAGE': 'en_US:en', 11:47:42 INFO - 'LOGNAME': 'cltbld', 11:47:42 INFO - 'MAIL': '/var/mail/cltbld', 11:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:42 INFO - 'MOZ_NO_REMOTE': '1', 11:47:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:47:42 INFO - 'NO_EM_RESTART': '1', 11:47:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:42 INFO - 'PWD': '/builds/slave/test', 11:47:42 INFO - 'SHELL': '/bin/bash', 11:47:42 INFO - 'SHLVL': '1', 11:47:42 INFO - 'TERM': 'linux', 11:47:42 INFO - 'TMOUT': '86400', 11:47:42 INFO - 'USER': 'cltbld', 11:47:42 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:42 INFO - '_': '/tools/buildbot/bin/python'} 11:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:47: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 11:47: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 11:47: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 11:47: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 11:47:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:47:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:47:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:47:43 INFO - Installing collected packages: mozsystemmonitor 11:47:43 INFO - Running setup.py install for mozsystemmonitor 11:47:43 INFO - Successfully installed mozsystemmonitor 11:47:43 INFO - Cleaning up... 11:47:45 INFO - Return code: 0 11:47:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc0d60>, '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 0x1cc1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc1ef0>, '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': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', '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 11:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:47:45 INFO - Using env: {'DISPLAY': ':0', 11:47:45 INFO - 'HOME': '/home/cltbld', 11:47:45 INFO - 'LANG': 'en_US.UTF-8', 11:47:45 INFO - 'LANGUAGE': 'en_US:en', 11:47:45 INFO - 'LOGNAME': 'cltbld', 11:47:45 INFO - 'MAIL': '/var/mail/cltbld', 11:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:45 INFO - 'MOZ_NO_REMOTE': '1', 11:47:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:47:45 INFO - 'NO_EM_RESTART': '1', 11:47:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:45 INFO - 'PWD': '/builds/slave/test', 11:47:45 INFO - 'SHELL': '/bin/bash', 11:47:45 INFO - 'SHLVL': '1', 11:47:45 INFO - 'TERM': 'linux', 11:47:45 INFO - 'TMOUT': '86400', 11:47:45 INFO - 'USER': 'cltbld', 11:47:45 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:45 INFO - '_': '/tools/buildbot/bin/python'} 11:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:45 INFO - Downloading/unpacking jsonschema==2.5.1 11:47:45 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 11:47:45 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 11:47:45 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 11:47:45 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 11:47:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:47:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:47:46 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 11:47:46 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 11:47:46 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 11:47:46 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 11:47:46 INFO - Downloading functools32-3.2.3-2.tar.gz 11:47:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:47:46 INFO - warning: no files found matching '*.txt' 11:47:46 INFO - no previously-included directories found matching 'build' 11:47:46 INFO - no previously-included directories found matching 'dist' 11:47:46 INFO - no previously-included directories found matching '.git*' 11:47:46 INFO - Installing collected packages: jsonschema, functools32 11:47:47 INFO - Running setup.py install for functools32 11:47:47 INFO - warning: no files found matching '*.txt' 11:47:47 INFO - no previously-included directories found matching 'build' 11:47:47 INFO - no previously-included directories found matching 'dist' 11:47:47 INFO - no previously-included directories found matching '.git*' 11:47:47 INFO - Successfully installed jsonschema functools32 11:47:47 INFO - Cleaning up... 11:47:47 INFO - Return code: 0 11:47:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc0d60>, '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 0x1cc1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc1ef0>, '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': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', '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 11:47:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:47:47 INFO - Using env: {'DISPLAY': ':0', 11:47:47 INFO - 'HOME': '/home/cltbld', 11:47:47 INFO - 'LANG': 'en_US.UTF-8', 11:47:47 INFO - 'LANGUAGE': 'en_US:en', 11:47:47 INFO - 'LOGNAME': 'cltbld', 11:47:47 INFO - 'MAIL': '/var/mail/cltbld', 11:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:47 INFO - 'MOZ_NO_REMOTE': '1', 11:47:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:47:47 INFO - 'NO_EM_RESTART': '1', 11:47:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:47 INFO - 'PWD': '/builds/slave/test', 11:47:47 INFO - 'SHELL': '/bin/bash', 11:47:47 INFO - 'SHLVL': '1', 11:47:47 INFO - 'TERM': 'linux', 11:47:47 INFO - 'TMOUT': '86400', 11:47:47 INFO - 'USER': 'cltbld', 11:47:47 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:47 INFO - '_': '/tools/buildbot/bin/python'} 11:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:47:47 INFO - Cleaning up... 11:47:47 INFO - Return code: 0 11:47:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc0d60>, '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 0x1cc1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc1ef0>, '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': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', '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 11:47:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:47:47 INFO - Using env: {'DISPLAY': ':0', 11:47:47 INFO - 'HOME': '/home/cltbld', 11:47:47 INFO - 'LANG': 'en_US.UTF-8', 11:47:47 INFO - 'LANGUAGE': 'en_US:en', 11:47:47 INFO - 'LOGNAME': 'cltbld', 11:47:47 INFO - 'MAIL': '/var/mail/cltbld', 11:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:47 INFO - 'MOZ_NO_REMOTE': '1', 11:47:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:47:47 INFO - 'NO_EM_RESTART': '1', 11:47:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:47 INFO - 'PWD': '/builds/slave/test', 11:47:47 INFO - 'SHELL': '/bin/bash', 11:47:47 INFO - 'SHLVL': '1', 11:47:47 INFO - 'TERM': 'linux', 11:47:47 INFO - 'TMOUT': '86400', 11:47:47 INFO - 'USER': 'cltbld', 11:47:47 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:47 INFO - '_': '/tools/buildbot/bin/python'} 11:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:47 INFO - Downloading/unpacking blobuploader==1.2.4 11:47: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 11:47: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 11:47: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 11:47: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 11:47:51 INFO - Downloading blobuploader-1.2.4.tar.gz 11:47:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:47:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:47:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:47:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:47:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:47:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:47:52 INFO - Downloading docopt-0.6.1.tar.gz 11:47:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:47:52 INFO - Installing collected packages: blobuploader, requests, docopt 11:47:52 INFO - Running setup.py install for blobuploader 11:47:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:47:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:47:52 INFO - Running setup.py install for requests 11:47:52 INFO - Running setup.py install for docopt 11:47:53 INFO - Successfully installed blobuploader requests docopt 11:47:53 INFO - Cleaning up... 11:47:53 INFO - Return code: 0 11:47:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:47:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:53 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 0x19561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc0d60>, '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 0x1cc1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc1ef0>, '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': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', '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 11:47:53 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 11:47:53 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 11:47:53 INFO - Using env: {'DISPLAY': ':0', 11:47:53 INFO - 'HOME': '/home/cltbld', 11:47:53 INFO - 'LANG': 'en_US.UTF-8', 11:47:53 INFO - 'LANGUAGE': 'en_US:en', 11:47:53 INFO - 'LOGNAME': 'cltbld', 11:47:53 INFO - 'MAIL': '/var/mail/cltbld', 11:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:53 INFO - 'MOZ_NO_REMOTE': '1', 11:47:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:47:53 INFO - 'NO_EM_RESTART': '1', 11:47:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:53 INFO - 'PWD': '/builds/slave/test', 11:47:53 INFO - 'SHELL': '/bin/bash', 11:47:53 INFO - 'SHLVL': '1', 11:47:53 INFO - 'TERM': 'linux', 11:47:53 INFO - 'TMOUT': '86400', 11:47:53 INFO - 'USER': 'cltbld', 11:47:53 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:53 INFO - '_': '/tools/buildbot/bin/python'} 11:47:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:47:53 INFO - Running setup.py (path:/tmp/pip-4jusdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:47:53 INFO - Running setup.py (path:/tmp/pip-9QGG5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:47:53 INFO - Running setup.py (path:/tmp/pip-5DRApS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:47:53 INFO - Running setup.py (path:/tmp/pip-IQEasf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:47:53 INFO - Running setup.py (path:/tmp/pip-P5Xdu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:47:53 INFO - Running setup.py (path:/tmp/pip-HVSu13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:47:53 INFO - Running setup.py (path:/tmp/pip-cYWNgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:47:53 INFO - Running setup.py (path:/tmp/pip-fXDsRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:47:53 INFO - Running setup.py (path:/tmp/pip-1dxVzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:47:53 INFO - Running setup.py (path:/tmp/pip-DwpEND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:47:54 INFO - Running setup.py (path:/tmp/pip-TqDDCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:47:54 INFO - Running setup.py (path:/tmp/pip-oCrugB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:47:54 INFO - Running setup.py (path:/tmp/pip-TWDu4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:47:54 INFO - Running setup.py (path:/tmp/pip-aLkrtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:47:54 INFO - Running setup.py (path:/tmp/pip-v39vzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:47:54 INFO - Running setup.py (path:/tmp/pip-wPvUZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:47:54 INFO - Running setup.py (path:/tmp/pip-rutCq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:47:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:47:54 INFO - Running setup.py install for manifestparser 11:47:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:47:54 INFO - Running setup.py install for mozcrash 11:47:54 INFO - Running setup.py install for mozdebug 11:47:54 INFO - Running setup.py install for mozdevice 11:47:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:47:54 INFO - Running setup.py install for mozfile 11:47:55 INFO - Running setup.py install for mozhttpd 11:47:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Running setup.py install for mozinfo 11:47:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Running setup.py install for mozInstall 11:47:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Running setup.py install for mozleak 11:47:55 INFO - Running setup.py install for mozlog 11:47:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Running setup.py install for moznetwork 11:47:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Running setup.py install for mozprocess 11:47:55 INFO - Running setup.py install for mozprofile 11:47:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Running setup.py install for mozrunner 11:47:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:47:55 INFO - Running setup.py install for mozscreenshot 11:47:56 INFO - Running setup.py install for moztest 11:47:56 INFO - Running setup.py install for mozversion 11:47:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:47:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:47:56 INFO - Cleaning up... 11:47:56 INFO - Return code: 0 11:47:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:56 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 0x19561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc0d60>, '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 0x1cc1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc1ef0>, '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': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', '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 11:47:56 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 11:47:56 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 11:47:56 INFO - Using env: {'DISPLAY': ':0', 11:47:56 INFO - 'HOME': '/home/cltbld', 11:47:56 INFO - 'LANG': 'en_US.UTF-8', 11:47:56 INFO - 'LANGUAGE': 'en_US:en', 11:47:56 INFO - 'LOGNAME': 'cltbld', 11:47:56 INFO - 'MAIL': '/var/mail/cltbld', 11:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:56 INFO - 'MOZ_NO_REMOTE': '1', 11:47:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:47:56 INFO - 'NO_EM_RESTART': '1', 11:47:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:56 INFO - 'PWD': '/builds/slave/test', 11:47:56 INFO - 'SHELL': '/bin/bash', 11:47:56 INFO - 'SHLVL': '1', 11:47:56 INFO - 'TERM': 'linux', 11:47:56 INFO - 'TMOUT': '86400', 11:47:56 INFO - 'USER': 'cltbld', 11:47:56 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:56 INFO - '_': '/tools/buildbot/bin/python'} 11:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:47:56 INFO - Running setup.py (path:/tmp/pip-GIDBRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:47:56 INFO - Running setup.py (path:/tmp/pip-YXut1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:47:56 INFO - Running setup.py (path:/tmp/pip-wj8q7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:47:56 INFO - Running setup.py (path:/tmp/pip-PcTfpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:47:56 INFO - Running setup.py (path:/tmp/pip-gfLKj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:47:56 INFO - Running setup.py (path:/tmp/pip-GpRm7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:47:56 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)) 11:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:47:56 INFO - Running setup.py (path:/tmp/pip-j6HC_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:47:57 INFO - Running setup.py (path:/tmp/pip-UX4PyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:47:57 INFO - Running setup.py (path:/tmp/pip-twiNLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:47:57 INFO - Running setup.py (path:/tmp/pip-uEwTwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:47:57 INFO - Running setup.py (path:/tmp/pip-0hqnSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:47:57 INFO - Running setup.py (path:/tmp/pip-7YzeVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:47:57 INFO - Running setup.py (path:/tmp/pip-jqgJCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:47:57 INFO - Running setup.py (path:/tmp/pip-BOGHqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:47:57 INFO - Running setup.py (path:/tmp/pip-pQRWyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:47:57 INFO - Running setup.py (path:/tmp/pip-56h1HE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:47:57 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)) 11:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:47:57 INFO - Running setup.py (path:/tmp/pip-3sRPAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:47:57 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)) 11:47:57 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)) 11:47:57 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)) 11:47:57 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)) 11:47:57 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)) 11:47:57 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)) 11:47:57 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)) 11:47:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:47:57 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 11:47:57 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 11:47:57 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 11:47:57 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 11:47:58 INFO - Downloading blessings-1.6.tar.gz 11:47:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:47:58 INFO - Installing collected packages: blessings 11:47:58 INFO - Running setup.py install for blessings 11:47:59 INFO - Successfully installed blessings 11:47:59 INFO - Cleaning up... 11:47:59 INFO - Return code: 0 11:47:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:47:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:47:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:47:59 INFO - Reading from file tmpfile_stdout 11:47:59 INFO - Current package versions: 11:47:59 INFO - argparse == 1.2.1 11:47:59 INFO - blessings == 1.6 11:47:59 INFO - blobuploader == 1.2.4 11:47:59 INFO - docopt == 0.6.1 11:47:59 INFO - functools32 == 3.2.3-2 11:47:59 INFO - jsonschema == 2.5.1 11:47:59 INFO - manifestparser == 1.1 11:47:59 INFO - mozInstall == 1.13 11:47:59 INFO - mozcrash == 1.0 11:47:59 INFO - mozdebug == 0.1 11:47:59 INFO - mozdevice == 0.51 11:47:59 INFO - mozfile == 1.2 11:47:59 INFO - mozhttpd == 0.7 11:47:59 INFO - mozinfo == 0.10 11:47:59 INFO - mozleak == 0.1 11:47:59 INFO - mozlog == 3.5 11:47:59 INFO - moznetwork == 0.27 11:47:59 INFO - mozprocess == 0.25 11:47:59 INFO - mozprofile == 0.28 11:47:59 INFO - mozrunner == 6.13 11:47:59 INFO - mozscreenshot == 0.1 11:47:59 INFO - mozsystemmonitor == 0.3 11:47:59 INFO - moztest == 0.8 11:47:59 INFO - mozversion == 1.4 11:47:59 INFO - psutil == 3.1.1 11:47:59 INFO - requests == 1.2.3 11:47:59 INFO - wsgiref == 0.1.2 11:47:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:47:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:47:59 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 0x19561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc0d60>, '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 0x1cc1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc1ef0>, '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': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', '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 11:47:59 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 11:47:59 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 11:47:59 INFO - Using env: {'DISPLAY': ':0', 11:47:59 INFO - 'HOME': '/home/cltbld', 11:47:59 INFO - 'LANG': 'en_US.UTF-8', 11:47:59 INFO - 'LANGUAGE': 'en_US:en', 11:47:59 INFO - 'LOGNAME': 'cltbld', 11:47:59 INFO - 'MAIL': '/var/mail/cltbld', 11:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:47:59 INFO - 'MOZ_NO_REMOTE': '1', 11:47:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:47:59 INFO - 'NO_EM_RESTART': '1', 11:47:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:47:59 INFO - 'PWD': '/builds/slave/test', 11:47:59 INFO - 'SHELL': '/bin/bash', 11:47:59 INFO - 'SHLVL': '1', 11:47:59 INFO - 'TERM': 'linux', 11:47:59 INFO - 'TMOUT': '86400', 11:47:59 INFO - 'USER': 'cltbld', 11:47:59 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:47:59 INFO - '_': '/tools/buildbot/bin/python'} 11:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:47:59 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)) 11:47:59 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)) 11:47:59 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)) 11:47:59 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)) 11:47:59 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)) 11:47:59 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)) 11:47:59 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)) 11:47:59 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)) 11:47:59 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)) 11:47:59 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:47:59 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 11:47:59 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 11:47:59 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 11:47:59 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 11:48:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:48:00 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)) 11:48:00 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)) 11:48:00 INFO - Installing collected packages: simplejson 11:48:00 INFO - Running setup.py install for simplejson 11:48:00 INFO - building 'simplejson._speedups' extension 11:48:00 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 11:48:01 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 11:48:01 INFO - Successfully installed simplejson 11:48:01 INFO - Cleaning up... 11:48:01 INFO - Return code: 0 11:48:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:48:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b1eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cc39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc0d60>, '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 0x1cc1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc1ef0>, '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': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', '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 11:48:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:48:01 INFO - Using env: {'DISPLAY': ':0', 11:48:01 INFO - 'HOME': '/home/cltbld', 11:48:01 INFO - 'LANG': 'en_US.UTF-8', 11:48:01 INFO - 'LANGUAGE': 'en_US:en', 11:48:01 INFO - 'LOGNAME': 'cltbld', 11:48:01 INFO - 'MAIL': '/var/mail/cltbld', 11:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:01 INFO - 'MOZ_NO_REMOTE': '1', 11:48:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:01 INFO - 'NO_EM_RESTART': '1', 11:48:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:01 INFO - 'PWD': '/builds/slave/test', 11:48:01 INFO - 'SHELL': '/bin/bash', 11:48:01 INFO - 'SHLVL': '1', 11:48:01 INFO - 'TERM': 'linux', 11:48:01 INFO - 'TMOUT': '86400', 11:48:01 INFO - 'USER': 'cltbld', 11:48:01 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:01 INFO - '_': '/tools/buildbot/bin/python'} 11:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:48:01 INFO - Cleaning up... 11:48:01 INFO - Return code: 0 11:48:01 INFO - Running post-action listener: _resource_record_post_action 11:48:01 INFO - Running post-action listener: _start_resource_monitoring 11:48:02 INFO - Starting resource monitoring. 11:48:02 INFO - [mozharness: 2017-10-03 18:48:02.021131Z] Finished create-virtualenv step (success) 11:48:02 INFO - [mozharness: 2017-10-03 18:48:02.021663Z] Running install step. 11:48:02 INFO - Running pre-action listener: _resource_record_pre_action 11:48:02 INFO - Running main action method: install 11:48:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:48:02 INFO - Reading from file tmpfile_stdout 11:48:02 INFO - Detecting whether we're running mozinstall >=1.0... 11:48:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:48:02 INFO - Reading from file tmpfile_stdout 11:48:02 INFO - Output received: 11:48:02 INFO - Usage: mozinstall [options] installer 11:48:02 INFO - Options: 11:48:02 INFO - -h, --help show this help message and exit 11:48:02 INFO - -d DEST, --destination=DEST 11:48:02 INFO - Directory to install application into. [default: 11:48:02 INFO - "/builds/slave/test"] 11:48:02 INFO - --app=APP Application being installed. [default: firefox] 11:48:02 INFO - mkdir: /builds/slave/test/build/application 11:48:02 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'] 11:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:48:20 INFO - Reading from file tmpfile_stdout 11:48:20 INFO - Output received: 11:48:20 INFO - /builds/slave/test/build/application/firefox/firefox 11:48:20 INFO - Running post-action listener: _resource_record_post_action 11:48:20 INFO - [mozharness: 2017-10-03 18:48:20.196340Z] Finished install step (success) 11:48:20 INFO - [mozharness: 2017-10-03 18:48:20.196593Z] Running setup-mitmproxy step. 11:48:20 INFO - Running pre-action listener: _resource_record_pre_action 11:48:20 INFO - Running main action method: setup_mitmproxy 11:48:20 INFO - Skipping: mitmproxy is not required 11:48:20 INFO - Running post-action listener: _resource_record_post_action 11:48:20 INFO - [mozharness: 2017-10-03 18:48:20.197796Z] Finished setup-mitmproxy step (success) 11:48:20 INFO - [mozharness: 2017-10-03 18:48:20.198033Z] Running run-tests step. 11:48:20 INFO - Running pre-action listener: _resource_record_pre_action 11:48:20 INFO - Running pre-action listener: _set_gcov_prefix 11:48:20 INFO - Running main action method: run_tests 11:48:20 WARNING - Try message not found. 11:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:48:20 INFO - Python 2.7.3 11:48:20 INFO - Return code: 0 11:48:20 INFO - grabbing minidump binary from tooltool 11:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:48:20 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 0x1cc0d60>, '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 0x1cc1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cc1ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:48:20 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 11:48:20 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 11:48:20 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 11:48:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:48:20 INFO - Return code: 0 11:48:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:48:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:48:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:48:20 INFO - ENV: RUST_BACKTRACE is now full 11:48:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:48:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:48:20 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/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 11:48:20 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/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 11:48:20 INFO - Using env: {'DISPLAY': ':0', 11:48:20 INFO - 'HOME': '/home/cltbld', 11:48:20 INFO - 'LANG': 'en_US.UTF-8', 11:48:20 INFO - 'LANGUAGE': 'en_US:en', 11:48:20 INFO - 'LOGNAME': 'cltbld', 11:48:20 INFO - 'MAIL': '/var/mail/cltbld', 11:48:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:48:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:20 INFO - 'MOZ_NO_REMOTE': '1', 11:48:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:48:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:20 INFO - 'NO_EM_RESTART': '1', 11:48:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:20 INFO - 'PWD': '/builds/slave/test', 11:48:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:48:20 INFO - 'RUST_BACKTRACE': 'full', 11:48:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:48:20 INFO - 'SHELL': '/bin/bash', 11:48:20 INFO - 'SHLVL': '1', 11:48:20 INFO - 'TERM': 'linux', 11:48:20 INFO - 'TMOUT': '86400', 11:48:20 INFO - 'USER': 'cltbld', 11:48:20 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:20 INFO - '_': '/tools/buildbot/bin/python'} 11:48:20 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/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 11:48:20 INFO - mozversion application_buildid: 20171003175736 11:48:20 INFO - mozversion application_changeset: 9be05b2177667ed8221f9da4fdcc200dbdf3de62 11:48:20 INFO - mozversion application_display_name: Nightly 11:48:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:48:20 INFO - mozversion application_name: Firefox 11:48:20 INFO - mozversion application_remotingname: firefox 11:48:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:48:20 INFO - mozversion application_vendor: Mozilla 11:48:20 INFO - mozversion application_version: 58.0a1 11:48:20 INFO - mozversion platform_buildid: 20171003175736 11:48:20 INFO - mozversion platform_changeset: 9be05b2177667ed8221f9da4fdcc200dbdf3de62 11:48:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:48:20 INFO - mozversion platform_version: 58.0a1 11:48:20 INFO - using testdate: 1507056500 11:48:20 INFO - actual date: 1507056500 11:48:20 INFO - starting webserver on 'localhost:40992' 11:48:20 INFO - SUITE-START | Running 2 tests 11:48:20 INFO - TEST-START | dromaeo_css 11:48:20 INFO - Initialising browser for dromaeo_css test... 11:48:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40992/getInfo.html -profile /tmp/tmp7SN9kL/profile 11:48:20 INFO - TEST-INFO | started process 16371 (/builds/slave/test/build/application/firefox/firefox http://localhost:40992/getInfo.html) 11:48:26 INFO - TEST-INFO | 16371: exit 0 11:48:26 INFO - Browser initialized. 11:48:26 INFO - Running cycle 1/1 for dromaeo_css test... 11:48:26 INFO - Using env: {'DISPLAY': ':0', 11:48:26 INFO - 'HOME': '/home/cltbld', 11:48:26 INFO - 'JSGC_DISABLE_POISONING': '1', 11:48:26 INFO - 'LANG': 'en_US.UTF-8', 11:48:26 INFO - 'LANGUAGE': 'en_US:en', 11:48:26 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:48:26 INFO - 'LOGNAME': 'cltbld', 11:48:26 INFO - 'MAIL': '/var/mail/cltbld', 11:48:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:48:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:48:26 INFO - 'MOZ_CRASHREPORTER': '1', 11:48:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:48:26 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:48:26 INFO - 'MOZ_NO_REMOTE': '1', 11:48:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:48:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:48:26 INFO - 'NO_EM_RESTART': '1', 11:48:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:48:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:48:26 INFO - 'PWD': '/builds/slave/test', 11:48:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:48:26 INFO - 'RUST_BACKTRACE': 'full', 11:48:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:48:26 INFO - 'SHELL': '/bin/bash', 11:48:26 INFO - 'SHLVL': '1', 11:48:26 INFO - 'TERM': 'linux', 11:48:26 INFO - 'TMOUT': '86400', 11:48:26 INFO - 'USER': 'cltbld', 11:48:26 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 11:48:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:48:26 INFO - '_': '/tools/buildbot/bin/python'} 11:48:26 INFO - TEST-INFO | started process 16645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7SN9kL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:48:27 INFO - PID 16645 | 11:48:27 INFO - PID 16645 | (/builds/slave/test/build/application/firefox/firefox:16694): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:48:27 INFO - PID 16645 | 11:48:37 INFO - PID 16645 | RSS: Main: 152227840 11:48:37 INFO - PID 16645 | 11:51:33 INFO - PID 16645 | Cycle 1(1): loaded http://localhost:40992/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40992/tests/dromaeo/cssquery-ext.html) 11:51:34 INFO - PID 16645 | RSS: Main: 150016000 11:51:34 INFO - PID 16645 | 11:54:36 INFO - PID 16645 | Cycle 1(1): loaded http://localhost:40992/tests/dromaeo/cssquery-ext.html (next: http://localhost:40992/tests/dromaeo/cssquery-jquery.html) 11:54:36 INFO - PID 16645 | RSS: Main: 150568960 11:54:36 INFO - PID 16645 | 11:57:38 INFO - PID 16645 | Cycle 1(1): loaded http://localhost:40992/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40992/tests/dromaeo/cssquery-mootools.html) 11:57:39 INFO - PID 16645 | RSS: Main: 152711168 11:57:39 INFO - PID 16645 | 12:00:40 INFO - PID 16645 | Cycle 1(1): loaded http://localhost:40992/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40992/tests/dromaeo/cssquery-prototype.html) 12:00:41 INFO - PID 16645 | RSS: Main: 152416256 12:00:41 INFO - PID 16645 | 12:03:44 INFO - PID 16645 | Cycle 1(1): loaded http://localhost:40992/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40992/tests/dromaeo/cssquery-yui.html) 12:03:45 INFO - PID 16645 | RSS: Main: 154988544 12:03:45 INFO - PID 16645 | 12:06:47 INFO - PID 16645 | Cycle 1(1): loaded http://localhost:40992/tests/dromaeo/cssquery-yui.html (next: http://localhost:40992/tests/dromaeo/cssquery-dojo.html) 12:06:47 INFO - PID 16645 | RSS: Main: 154316800 12:06:47 INFO - PID 16645 | 12:06:47 INFO - PID 16645 | __start_tp_report 12:06:47 INFO - PID 16645 | _x_x_mozilla_page_load 12:06:47 INFO - PID 16645 | _x_x_mozilla_page_load_details 12:06:47 INFO - PID 16645 | |i|pagename|runs| 12:06:47 INFO - PID 16645 | |0;dojo.html;3097.0553981035787;3130.671279515651;3158.0999415166666;3173.4129186100586;3181.2206009527667;6198.380161983805;6246.531510136747;6323.715432805523;6324.1462402575635;6336.398042185997;6089.573729838913;6162.630242185461;6184.536159788023;6290.21372328459;6381.840453988652;5758.42415758425;5759.395263497336;5841;5848.67832269224;5848.970755146218;746.0340033896405;5696.943030569682;5785.8264252072295;5844.824655260349;5892.528597712194;5751.654900705971;5754.827355179351;5766.423357664242;5815.912761308584;5816.651000939936;5727.31272247333;5744.281964754406;5804.448577385142;5811.15738217956;5840.561957853136;5729;5730.570207234474;5767;5809.564282678773;5820.708964551755;6238.688065596702;6246.843828904268;6262.499000079983;6271.749130034838;6344.524160687967;6450.225972883237;6550.574212676209;6762.289959554225;6815.114035175445;6851.48613853963;6734.8316292092595;6846.8972965405555;6854.897176542356;6856.897146542806;6859.142607174103;6246.500279977591;6367.458766004896;6368.617882926993;6371.044343348489;6383.840403989891;6949.270326615733;7012.333828286253;7022.6488675566015;7030.191527974246;7042.718291268292;6543.869122617616;6633.9336606633315;6665.466762658975;6705.2624211336715;6721.899171512431;6579.539432239793;6628.966855165693;6694.933050669431;6720.092787473726;6730.125083739181;6638.535302528873;6725.932740672629;6730.764423245163;6736.124303840567;6743.831404214934;6542.901856472156;6640.003999400032;6768.221654509696;6800.6939687714175;6811.795646130624;4886.144924638174;4958.776855041532;4978.128827455181;4978.551930326288;4978.6763860348965;4357.760323182192;4392.38506609075;4418.9337159942625;4421.712588681726;4424.203643344164;5726.055200891807;5760.596758226968;5820.330661973842;5834.649921004753;5893.587448878623;6827.897581536281;6833.658317084225;6838.14523184602;6840.965795170992;6844.555103918229;5839.649621022752;5871.089981052944;5884.293884733859;5884.705764711747;5890.528757699374;5797.420257974169;5805.187273781674;5872;5878.970605146947;5896.970515147397;6508.934910650928;6556.704948277244;6563.310852360433;6641.037049627874;6642.236142843538;6783.219929708048;6789.2871248518195;6797.422219111431;6798.150231220999;6806.42145417645;6386.457151142019;6558.049082883051;6558.213014438298;6561.507886908596;6561.671916404256;6623.6688165592495;6623.933760662524;6700.994850772423;6715.93284067153;6722.865542689021;6719.193696756421;6755.662216889037;6823.38589526945;6824.522283440111;6842.8289292766085;6601.834954126185;6617.139771829631;6650.235222949423;6651.966740166268;6659.567128136656;6448.193975753031;6544.5418820684;6546.0508226307875;6635.469162466895;6643.701033453603;6323.557350985387;6439.67801609927;6477.740890364333;6566.868662626625;6566.967165164144;6602.075709400592;6695.397414232742;6711.060451536887;6715.328467153441;6740.12378390806;6340.4293613575;6407.487401008001;6431.935680643133;6437.324080971431;6439 12:06:47 INFO - PID 16645 | |1;ext.html;870.3907264914559;896.6054935828231;898.1243287794397;898.7124120281509;900.2752784008875;41321.17357652849;45169.548304516946;45364.54635453645;45382.319265211045;45394.546054539445;41203.17593648125;41224.38163427551;41311.79344103275;41344;41448.99999999993;7311.0861142357335;7439.293267139613;7453.14288856783;8020.839583208332;8243.175682431769;8070.9192908071045;8171.18288171184;8172.591370431484;8176.754697359089;8179.79550511236;8128.512289262663;8197;8207.712730054449;8233;8238.794030149234;8182.509049457051;8188.2630563249295;8203.384746144004;8221.465604735673;8224.136465671078;8098.3926205534235;8137.226963438464;8150.429469937107;8163.469374490639;8171.71399001038;38203;38841.417378739345;39169;39191.60808391937;39195.412068818994;3475.14858859581;3524.7708898921487;3525.6474352564796;3536.1159710072484;3537.2748586539956;32526;32540.186495337806;32574.674253257403;32797.83601082003;32834.17914552155;5152.742362881879;5205.947940520584;5279.366476022902;5309.389420216686;5313.707746073965;204058;204667;204804;205708;205789;44727;45443.54556454459;50503;51490.74254628703;51583.226251606255;55504;56222.43777562172;56321.7183914086;56542;56577.71711141418;16829.242684079247;16961.321547138225;16972.83027169737;16986.32054717822;17010.234539445755;13833;13930.651733706536;13945.16329020262;13945.65135871591;13953.511627093207;14386.928065359816;14702.529747025426;14832.703345933021;14897.404103835725;14933.253337333308;5785.971070144622;5786.826395208151;5810.94189058104;5880.882382352329;5971.820285595726;984.13396211335;994.418265314799;1010.8685870836745;1018.6434747838198;1020.0615433800773;23099.422514436937;23176.3047108587;23289.883550582137;23451.17920872761;23604.29187124389;1116.7096554895786;1116.7431490757172;1118.189312748251;1121.7476067884825;1126.6732647532124;12173.2696038238;12318.75362492745;12352.320622365676;12457.128001040022;12568.748625027449;232571;234719;234767;235019;235384;55653.44346556483;56034.71982640142;56201.15698264529;56234;56422.15366769502;1432.0906224547487;1454.7604967474783;1457.9271036448347;1492.2986196487634;1499.4751836857013;22688.546229075655;22814.429639258487;22833.42916427036;23176.884115579312;23205.303840884124;39680;39753.60246397499;39761.60238397579;39801;39884.202315952934;198628;199514;200027;200367;200575;35576;37180.44229336508;37337.81331093327;37599.81200093982;37626;46875.53124468712;47875.93054590399;48050;48325;48692.75653621709;33249.83375083206;33657.326853462306;33727;33758.662413375554;34085.82957085298;33776.49335260022;34016.319673605896;34039.149021274665;34465.48301775526;35029;37288.254234915694;37621;37701;37701.05747356338;37822.243555129295;33298.500522492664;33539.496907545916;33567;33712.66287337095;33922;24204.51590968206;24292.51414971726;24476;24554.877225614473;24760.25719228426 12:06:47 INFO - PID 16645 | |2;jquery.html;578.178985840107;583.5477504933673;584.9824505264841;595.5295316699807;621.6363427394974;22014.229501967435;22598.096076156933;22623.66064509034;22660.43348916278;22693.77306226937;22835.08659653612;23715.762842371572;23824.166154184608;23857.64213536794;25135;6664.133662623864;6774.932250677504;6858.725650973955;6860.279670634586;6863.450923926074;6495.805125846232;6741.8314542136595;6750.358715922004;6835.624040677761;6853.109095817536;6767.796966091;6797.524173307846;6804.149481314836;6806.829829254283;6817.977303404504;6811.352921472452;6831.829204269909;6833.180018397799;7075.504714669975;7395.149557800814;7176.210616832144;7232.1321441426835;7247.528910620846;7251.564906105597;7259.092613423322;30998.38003239945;31550.211244718837;31599;31694.841525792224;31796.682033179604;2100.789921007887;2110.1770309579124;2117.4494631395796;2123.076461739133;2123.8088572028437;20140.194392224294;21353.78646213549;21396.251131210334;21524.46188845276;21538.461538461506;2640.960385594198;2650.946981060368;2715.484058028988;2717.1305182341657;2728.0724553651853;125676;131160;145358;149529;156999.43000569963;20547.589048218953;21055.473613159487;21817.454563636034;22393.776062239496;22930.885345573166;42564;42901;42913;42955.14089718188;43000.56999429966;11920.46357913896;11949.581764638197;12256.342589815991;12370.257784532956;12395.814062789066;10137.594496220068;10149.797004059878;10206.948965255126;10245.334053286513;10337.586496540205;11161.720956975978;11378.943105284421;11429.714257143638;11799.407080044255;11821.290722556674;3531.3643544162296;3564.8752293669595;3940.408938659167;3985.121945130909;4191.479157507647;957.2485598804905;968.864358989742;978.9804203916025;986.4919566423298;987.2546227598117;20040;20064.89967550182;20160.39518814438;20591.485212869797;20642.690359644617;1036.9170466362673;1037.8754549454113;1043.2697112021615;1051.0435503691604;1058.0953284941418;11060.234941264684;11344.149188810707;11359.54561817518;11361.659150225507;11481.770364592661;475457;480044;484328;492533;493740;111129.44435277772;111631.44184279027;113566.43216784028;114301;114883;1060.3584831177027;1106.3748981825242;1176.582313278791;1195.5217912834762;1241.615099319232;21194.682079768507;21369.14523419046;21402.25092121768;21626.567468650224;21802.672959905933;55775.721121394134;55949.44050559442;57307.713461432424;57661;58652.12021819591;70590.64704676444;70871.64564177147;71129.64435177791;71529;72029.27970720225;20186.596268074263;20744.170233190503;20772.480687982923;20968.370948871558;20987.580248394643;23681.289561313188;23883;23888.28335149948;24068.51862962766;24084.51830963336;17836.46490605284;18021.459356219333;18178.72731908996;18188.90905545464;18256.634867302313;16873.578160545592;16908.830911690726;16920.492385228463;17518.474445766646;17686;17781.46655600334;18234.36179733703;18327.450176494727;18396.908015459838;18405.723914141032;19105.617887642446;19188.232470701016;19226.134823932676;19261.22955081781;19899.104540295433;18080.367187148386;18102.90948545249;18224.357468166778;18354.357597484024;18407.53981150482 12:06:47 INFO - PID 16645 | |3;mootools.html;1171.3557543351158;1356.3896246688992;1359.735446035187;1384.473899918031;1397.2245403800894;14981.176035318067;15571.688566228682;15613.453529126482;15623.140727259995;15648.765268520952;15151.848481515182;15213.92393038036;15323.770143447857;15343.3095510702;15349.079055256692;2629.592413175951;2652.4297276085636;2695.353115252345;2703.4728227995547;2712.9864350678317;2669.9733002670014;2680.8793604287853;2684.8254863433913;2687.7984151188643;2690.730926907313;2607.38726399296;2652.761251487366;2653.2173008962304;2656.043824223278;2660.76053155216;2533.8226324157317;2599.6620439342764;2601.193629974714;2602.531544322021;2605.517979173849;2521.9873900630378;2530.671012768347;2544.9363765905814;2553.374423266293;2555.642210090589;14254.928725356307;14563.854361456355;14593.27033648324;14609.853901460956;14620.926895365455;7571.924280757177;7630.4658673892845;7649.617519124022;7656.081270247606;7915.802104947365;4985.52637499437;5081.3140226069745;5148.536631703165;5204.869878253036;5348.759305831247;1841.8066103059255;1848.8890666560048;1921.1258877210976;1935.7096435534643;1958.6082783443226;24305;25120.623190652153;27551.173464795887;27943.16170514888;27978.72021279802;7171.89242161368;7183.92816071843;7243.422847082703;7350.228226036189;7351.117865856131;20863.895680521804;20882.58234835295;21005.89497052505;21016.36950891476;21073.26243581467;8014.839703205903;8126.75619731409;8126.984126984127;8127.309178719756;8146.633401496947;6970.372666460043;7202.603856787822;7240.891386629205;7306.342429181399;7323.670434830446;5507.366652834895;5656.660600363992;5660.5471562274915;5684.77777277879;5711.800086997018;1753.4301352060631;1770.9557261068576;1784.0633667324757;1808.6021075363399;1825.7261410788487;902.4224496322358;905.791667916383;909.5452273863068;920.8204400142042;923.385948344357;7502.062242219723;7550.735724249625;7556.357709594746;7567.508111972704;7579.393648508217;717.2540557819809;733.4059411876291;733.8642351164945;742.4913933955258;753.3784627682185;4714.88212794676;4740.19416699162;4795.448523419782;4803.975980120077;4840.540148685869;20259.8987005067;20761.896190519252;21792.128314867547;21804.127834886425;21864.23475178361;21270.680939785914;21272;21298.25456109029;21440;21453.249136280156;10286.485675716185;11344.716382090348;11356.886431135748;11404.087672986141;11415;8194.631241594261;8280.50316980983;8327.791805204919;8382.287505561852;8392.286655634096;17783.555411114827;18063.548411289823;18087.638247235245;18280.90859545694;18460.169292382143;18308.359207427675;18329.90835045816;18593.90703046476;18758.249670013047;19006.524836879187;12167.026637869127;12280.81578776337;12282.324472154118;12393.690157746129;12439.564615238423;13809.309534523434;13896.930515347358;14024.368903399576;14030.719385612434;14059.437622495394;11241.269317494336;11347.943260283646;11398;11577.131715121232;11662.12534059932;11052.834207486734;11091.72270693239;11102.167337449886;11222.494987725735;11461.312321260752;9725.708228752866;9756.658516951873;9870.555824987749;9909.108180263811;9922.503874806374;7229.963850180716;7231.674574644258;7287.234840341688;7359.595222262827;7377.926220737724;7549.773506794805;7554.131274903237;7632.961835190788;7637.579933103732;7649.349805266615 12:06:47 INFO - PID 16645 | |4;prototype.html;510.8161062017673;510.9335786347774;529;532.0396683985407;552.4392741367512;6116.614520337066;6138.539609529281;6146.784862529812;6150.863059839486;6179.81460556184;5964.940350596493;5989.550783691229;6050.515958723303;6064.151018857364;6071.969640151804;4866.270059491087;5123.795048198086;5133.897322053556;5150.609335479418;5151.484851514856;4973.154563724175;5010.498950104997;5017.397912250535;5032.798688052492;5041.899162016757;4932.482089380617;4941.332920055783;4984.93125352978;4992.450830408652;4997.700137991714;4955.306257124005;4963.950360496385;4984.177610694267;4999.675021123651;5016.122178618763;4516.864494065183;4528.298113792334;4532.914859164788;4546.909061818778;4547.45430548333;6068.908966365508;6106.328303886569;6166.81499555014;6177.166082578884;6208.596441231305;5612.719364031782;5636.182753500761;5719.885602287973;5725.770969161229;5726.4273572642405;6497.252815926135;6530.738770449177;6530.869382612369;6546.476281897437;6546.70539825709;4884.218525036013;4914.852554423373;4932.112219800434;4933.851984440473;4935.185694360426;6757.189137303506;6783.694733736994;6822.488313376517;6828.726850925957;6873.621950792754;5006.349174607278;5036.697798132123;5037.5214354636255;5049.84850454487;5060.493950604984;5083.186690129562;5108.974455127701;5115.462876397999;5127.615428842777;5139.460356662571;5617.494425501724;5618.70769722966;5695.943040569624;5707.229524014288;5721.456461636138;6226.657533835682;6288.748450061947;6355.3009168991375;6458.418742313214;6548.508861835381;1720.6042610199474;1730.2041061111777;1743.938962136319;1769.5222289981632;1774.8047714751367;1060.8090543702053;1214.5688280660415;1226.7546490701895;1240.7642547915866;1250.5185503581006;5608.551315894719;5718.1136923776885;5729.5702822288495;5771.88456230873;5782.537397008229;6696.46428285736;6709.396154346132;6722.159730033746;6732.697028633724;6805.421539169127;5740.770369185269;5743.54051675865;5757.424257574209;5776.884462310731;5779.479846813723;3548.822558872046;3557.21741216937;3557.4841647961416;3576.8211589420425;3607.7294202934886;6468.288488266287;6480.870382592322;6545.101920443019;6565.146530950946;6567.704453299613;6785.694643741043;6804.931950680529;6805;6837.38463538274;6892.448604111759;6390.009548519987;6445.711772320758;6455.580387274812;6473.119461839437;6483.2544257411;6465.967670161619;6562.245341785756;6566.573172743756;6621.205455345197;6650.46796256308;6520.478361731145;6586.670666466562;6591.1771172718745;6607.372299631527;6617.470602351704;6329.892268853025;6376.915924292789;6444.484441244783;6482.481401487776;6499.415052645285;4959.355283813081;4970.353853998957;5002.474740152232;5017.37282839645;5026.346574945233;6526.184226971629;6547.901781473377;6672.299408562225;6682.498812588978;6699.564528305645;6339;6341.4292713656;6359.791639588463;6441.323661015525;6451.516136289703;6337.746490140527;6399.7440102395385;6477.8704425912165;6480.740770369132;6544.770933017321;6304.684765761602;6353.174087368612;6367.777127800505;6399.936000639934;6457.383865035595;6297.842553936005;6364.186206931003;6390;6449.774257900951;6454;5890.410958904075;6217.906731398942;6257.843553911007;6262.749490020348;6262.968685156545 12:06:47 INFO - PID 16645 | |5;yui.html;892.9419587726801;920.3971398733829;928.3455164109304;928.3687092776913;950.1638558068898;19033.238670453182;20016.59966800663;20019.799802001977;20037.699434508475;20051.09770060349;17715;18209.27162913482;18495.537611559717;18555.721664175013;18795.53011174721;995.0149352141382;995.7809281957958;1002.2633364477103;1003.1673710820038;1005.5776573839005;990.5245482168564;1004.6182450668736;1008.1229330482454;1013.6806905824678;1022.0954455307069;1068.0441005300252;1068.2895874243618;1068.6740544134036;1068.7221322456178;1070.9089727373182;1019.0726438940528;1060.390275591538;1062.2723434447428;1065.2969040433316;1065.9253852230347;745.3440971944653;747.3460721868365;750.6509473094983;752.2176935986568;752.559752544762;20039.1984320627;20166.092525836226;20173.697394538947;20184.69722954157;20220.39338819838;21553.24563640265;21889;22369.55260894789;22543.098276068937;22617.54764904709;17343.13284335778;17789.110544472853;17928.372506962194;17957.910210448863;18114.637707245914;3703.981480092609;3709.387950988111;3740.4202348636004;3746.063484128968;3747.0445036515785;141705.99999999898;142216;142681;143302;143662;17210.225539850737;17237;17533.29866805339;17540.47378578619;17541.64916701659;30267.243318917204;30813.22966925845;31467.213319666735;31600;31684.207894802814;8965.865512017324;9228.7692807679;9263.536823158815;9347.626094956126;9398.389104708172;4228.112096459717;4338;4365.9126817463475;4366.978165109154;4373.846915357947;5662.348829884616;5713.4000929902595;5756.30924289088;5765.4811067004175;5768.3366412862215;986.541258314887;1012.7822518158631;1018.5060245780791;1021.6015753856002;1031.9484025798681;699.7306037175724;707.9433645308363;714.3749219432996;714.4598514361327;721.90976127984;11662.358570278546;12054.276743395425;12087.274763514217;12453.315067671183;12816.102872799001;661.147120214927;662.1722846441947;664.538145988543;672.7342699633617;686.3925425997928;5559.27729395176;5588.776448942078;5616.382197958222;5733.827985160452;5765.3369862465515;125991;130302.34848825885;130756.3462182702;131657;135284.32357838345;104506;107015.46492267644;109576;112810.43594782137;113012.43493782479;95295;95853.52073239729;98980.50509747406;99747.50126249467;101393;10516.26386152962;10532.157427405942;10630;10787.029167374974;10798.838017429902;45411;46910.29634555416;47100.7644961773;47646.04707905892;48144.51855481401;57039.429605703415;58750;58785.41214587799;59148;59449.405505944385;14147.787783183054;14272.14367137975;14365.569032929028;14401.42394304216;14566.927165364104;16045.919770401073;16162.030278183347;16261.75607365914;16400.589985250463;16593.253303601126;15748;15990.040597564184;16089.356425742839;16108.919455403116;16178.433754818525;15361.692766144837;15376.923115384352;15490.767638485657;15607.219639017776;15607.45373911953;14377.712445751236;14549.054311469721;14562.344694488986;14663.340149693502;14665.120092794466;12029.879701203226;12092.395380230777;12155;12277.754444910874;12326.534693061603;11613.941930290295;11667.59163429276;11879;11980.520779168735;12240.87759122433 12:06:47 INFO - PID 16645 | __end_tp_report 12:06:47 INFO - PID 16645 | __start_cc_report 12:06:47 INFO - PID 16645 | _x_x_mozilla_cycle_collect,271 12:06:47 INFO - PID 16645 | __end_cc_report 12:06:47 INFO - PID 16645 | __startTimestamp1507057607276__endTimestamp 12:06:47 INFO - PID 16645 | 12:06:47 INFO - PID 16645 | ------- Summary: start ------- 12:06:47 INFO - PID 16645 | Number of tests: 6 12:06:47 INFO - PID 16645 | 12:06:47 INFO - PID 16645 | [#0] dojo.html Cycles:165 Average:6162.98 Median:6448.19 stddev:887.89 (13.8%) stddev-sans-first:857.42 12:06:47 INFO - PID 16645 | Values: 3097.1 3130.7 3158.1 3173.4 3181.2 6198.4 6246.5 6323.7 6324.1 6336.4 6089.6 6162.6 6184.5 6290.2 6381.8 5758.4 5759.4 5841.0 5848.7 5849.0 746.0 5696.9 5785.8 5844.8 5892.5 5751.7 5754.8 5766.4 5815.9 5816.7 5727.3 5744.3 5804.4 5811.2 5840.6 5729.0 5730.6 5767.0 5809.6 5820.7 6238.7 6246.8 6262.5 6271.7 6344.5 6450.2 6550.6 6762.3 6815.1 6851.5 6734.8 6846.9 6854.9 6856.9 6859.1 6246.5 6367.5 6368.6 6371.0 6383.8 6949.3 7012.3 7022.6 7030.2 7042.7 6543.9 6633.9 6665.5 6705.3 6721.9 6579.5 6629.0 6694.9 6720.1 6730.1 6638.5 6725.9 6730.8 6736.1 6743.8 6542.9 6640.0 6768.2 6800.7 6811.8 4886.1 4958.8 4978.1 4978.6 4978.7 4357.8 4392.4 4418.9 4421.7 4424.2 5726.1 5760.6 5820.3 5834.6 5893.6 6827.9 6833.7 6838.1 6841.0 6844.6 5839.6 5871.1 5884.3 5884.7 5890.5 5797.4 5805.2 5872.0 5879.0 5897.0 6508.9 6556.7 6563.3 6641.0 6642.2 6783.2 6789.3 6797.4 6798.2 6806.4 6386.5 6558.0 6558.2 6561.5 6561.7 6623.7 6623.9 6701.0 6715.9 6722.9 6719.2 6755.7 6823.4 6824.5 6842.8 6601.8 6617.1 6650.2 6652.0 6659.6 6448.2 6544.5 6546.1 6635.5 6643.7 6323.6 6439.7 6477.7 6566.9 6567.0 6602.1 6695.4 6711.1 6715.3 6740.1 6340.4 6407.5 6431.9 6437.3 6439.0 12:06:47 INFO - PID 16645 | 12:06:47 INFO - PID 16645 | [#1] ext.html Cycles:180 Average:39167.90 Median:37313.03 stddev:55361.73 (148.4%) stddev-sans-first:55442.35 12:06:47 INFO - PID 16645 | Values: 870.4 896.6 898.1 898.7 900.3 41321.2 45169.5 45364.5 45382.3 45394.5 41203.2 41224.4 41311.8 41344.0 41449.0 7311.1 7439.3 7453.1 8020.8 8243.2 8070.9 8171.2 8172.6 8176.8 8179.8 8128.5 8197.0 8207.7 8233.0 8238.8 8182.5 8188.3 8203.4 8221.5 8224.1 8098.4 8137.2 8150.4 8163.5 8171.7 38203.0 38841.4 39169.0 39191.6 39195.4 3475.1 3524.8 3525.6 3536.1 3537.3 32526.0 32540.2 32574.7 32797.8 32834.2 5152.7 5205.9 5279.4 5309.4 5313.7 204058.0 204667.0 204804.0 205708.0 205789.0 44727.0 45443.5 50503.0 51490.7 51583.2 55504.0 56222.4 56321.7 56542.0 56577.7 16829.2 16961.3 16972.8 16986.3 17010.2 13833.0 13930.7 13945.2 13945.7 13953.5 14386.9 14702.5 14832.7 14897.4 14933.3 5786.0 5786.8 5810.9 5880.9 5971.8 984.1 994.4 1010.9 1018.6 1020.1 23099.4 23176.3 23289.9 23451.2 23604.3 1116.7 1116.7 1118.2 1121.7 1126.7 12173.3 12318.8 12352.3 12457.1 12568.7 232571.0 234719.0 234767.0 235019.0 235384.0 55653.4 56034.7 56201.2 56234.0 56422.2 1432.1 1454.8 1457.9 1492.3 1499.5 22688.5 22814.4 22833.4 23176.9 23205.3 39680.0 39753.6 39761.6 39801.0 39884.2 198628.0 199514.0 200027.0 200367.0 200575.0 35576.0 37180.4 37337.8 37599.8 37626.0 46875.5 47875.9 48050.0 48325.0 48692.8 33249.8 33657.3 33727.0 33758.7 34085.8 33776.5 34016.3 34039.1 34465.5 35029.0 37288.3 37621.0 37701.0 37701.1 37822.2 33298.5 33539.5 33567.0 33712.7 33922.0 24204.5 24292.5 24476.0 24554.9 24760.3 12:06:47 INFO - PID 16645 | 12:06:47 INFO - PID 16645 | [#2] jquery.html Cycles:180 Average:36161.51 Median:21714.62 stddev:81692.80 (376.2%) stddev-sans-first:81878.29 12:06:47 INFO - PID 16645 | Values: 578.2 583.5 585.0 595.5 621.6 22014.2 22598.1 22623.7 22660.4 22693.8 22835.1 23715.8 23824.2 23857.6 25135.0 6664.1 6774.9 6858.7 6860.3 6863.5 6495.8 6741.8 6750.4 6835.6 6853.1 6767.8 6797.5 6804.1 6806.8 6818.0 6811.4 6831.8 6833.2 7075.5 7395.1 7176.2 7232.1 7247.5 7251.6 7259.1 30998.4 31550.2 31599.0 31694.8 31796.7 2100.8 2110.2 2117.4 2123.1 2123.8 20140.2 21353.8 21396.3 21524.5 21538.5 2641.0 2650.9 2715.5 2717.1 2728.1 125676.0 131160.0 145358.0 149529.0 156999.4 20547.6 21055.5 21817.5 22393.8 22930.9 42564.0 42901.0 42913.0 42955.1 43000.6 11920.5 11949.6 12256.3 12370.3 12395.8 10137.6 10149.8 10206.9 10245.3 10337.6 11161.7 11378.9 11429.7 11799.4 11821.3 3531.4 3564.9 3940.4 3985.1 4191.5 957.2 968.9 979.0 986.5 987.3 20040.0 20064.9 20160.4 20591.5 20642.7 1036.9 1037.9 1043.3 1051.0 1058.1 11060.2 11344.1 11359.5 11361.7 11481.8 475457.0 480044.0 484328.0 492533.0 493740.0 111129.4 111631.4 113566.4 114301.0 114883.0 1060.4 1106.4 1176.6 1195.5 1241.6 21194.7 21369.1 21402.3 21626.6 21802.7 55775.7 55949.4 57307.7 57661.0 58652.1 70590.6 70871.6 71129.6 71529.0 72029.3 20186.6 20744.2 20772.5 20968.4 20987.6 23681.3 23883.0 23888.3 24068.5 24084.5 17836.5 18021.5 18178.7 18188.9 18256.6 16873.6 16908.8 16920.5 17518.5 17686.0 17781.5 18234.4 18327.5 18396.9 18405.7 19105.6 19188.2 19226.1 19261.2 19899.1 18080.4 18102.9 18224.4 18354.4 18407.5 12:06:47 INFO - PID 16645 | 12:06:47 INFO - PID 16645 | [#3] mootools.html Cycles:180 Average:9403.96 Median:2604.02 stddev:6790.67 (260.8%) stddev-sans-first:6781.54 12:06:47 INFO - PID 16645 | Values: 1171.4 1356.4 1359.7 1384.5 1397.2 14981.2 15571.7 15613.5 15623.1 15648.8 15151.8 15213.9 15323.8 15343.3 15349.1 2629.6 2652.4 2695.4 2703.5 2713.0 2670.0 2680.9 2684.8 2687.8 2690.7 2607.4 2652.8 2653.2 2656.0 2660.8 2533.8 2599.7 2601.2 2602.5 2605.5 2522.0 2530.7 2544.9 2553.4 2555.6 14254.9 14563.9 14593.3 14609.9 14620.9 7571.9 7630.5 7649.6 7656.1 7915.8 4985.5 5081.3 5148.5 5204.9 5348.8 1841.8 1848.9 1921.1 1935.7 1958.6 24305.0 25120.6 27551.2 27943.2 27978.7 7171.9 7183.9 7243.4 7350.2 7351.1 20863.9 20882.6 21005.9 21016.4 21073.3 8014.8 8126.8 8127.0 8127.3 8146.6 6970.4 7202.6 7240.9 7306.3 7323.7 5507.4 5656.7 5660.5 5684.8 5711.8 1753.4 1771.0 1784.1 1808.6 1825.7 902.4 905.8 909.5 920.8 923.4 7502.1 7550.7 7556.4 7567.5 7579.4 717.3 733.4 733.9 742.5 753.4 4714.9 4740.2 4795.4 4804.0 4840.5 20259.9 20761.9 21792.1 21804.1 21864.2 21270.7 21272.0 21298.3 21440.0 21453.2 10286.5 11344.7 11356.9 11404.1 11415.0 8194.6 8280.5 8327.8 8382.3 8392.3 17783.6 18063.5 18087.6 18280.9 18460.2 18308.4 18329.9 18593.9 18758.2 19006.5 12167.0 12280.8 12282.3 12393.7 12439.6 13809.3 13896.9 14024.4 14030.7 14059.4 11241.3 11347.9 11398.0 11577.1 11662.1 11052.8 11091.7 11102.2 11222.5 11461.3 9725.7 9756.7 9870.6 9909.1 9922.5 7230.0 7231.7 7287.2 7359.6 7377.9 7549.8 7554.1 7633.0 7637.6 7649.3 12:06:47 INFO - PID 16645 | 12:06:47 INFO - PID 16645 | [#4] prototype.html Cycles:180 Average:5475.05 Median:6070.44 stddev:1515.27 (25.0%) stddev-sans-first:1473.00 12:06:47 INFO - PID 16645 | Values: 510.8 510.9 529.0 532.0 552.4 6116.6 6138.5 6146.8 6150.9 6179.8 5964.9 5989.6 6050.5 6064.2 6072.0 4866.3 5123.8 5133.9 5150.6 5151.5 4973.2 5010.5 5017.4 5032.8 5041.9 4932.5 4941.3 4984.9 4992.5 4997.7 4955.3 4964.0 4984.2 4999.7 5016.1 4516.9 4528.3 4532.9 4546.9 4547.5 6068.9 6106.3 6166.8 6177.2 6208.6 5612.7 5636.2 5719.9 5725.8 5726.4 6497.3 6530.7 6530.9 6546.5 6546.7 4884.2 4914.9 4932.1 4933.9 4935.2 6757.2 6783.7 6822.5 6828.7 6873.6 5006.3 5036.7 5037.5 5049.8 5060.5 5083.2 5109.0 5115.5 5127.6 5139.5 5617.5 5618.7 5695.9 5707.2 5721.5 6226.7 6288.7 6355.3 6458.4 6548.5 1720.6 1730.2 1743.9 1769.5 1774.8 1060.8 1214.6 1226.8 1240.8 1250.5 5608.6 5718.1 5729.6 5771.9 5782.5 6696.5 6709.4 6722.2 6732.7 6805.4 5740.8 5743.5 5757.4 5776.9 5779.5 3548.8 3557.2 3557.5 3576.8 3607.7 6468.3 6480.9 6545.1 6565.1 6567.7 6785.7 6804.9 6805.0 6837.4 6892.4 6390.0 6445.7 6455.6 6473.1 6483.3 6466.0 6562.2 6566.6 6621.2 6650.5 6520.5 6586.7 6591.2 6607.4 6617.5 6329.9 6376.9 6444.5 6482.5 6499.4 4959.4 4970.4 5002.5 5017.4 5026.3 6526.2 6547.9 6672.3 6682.5 6699.6 6339.0 6341.4 6359.8 6441.3 6451.5 6337.7 6399.7 6477.9 6480.7 6544.8 6304.7 6353.2 6367.8 6399.9 6457.4 6297.8 6364.2 6390.0 6449.8 6454.0 5890.4 6217.9 6257.8 6262.7 6263.0 12:06:47 INFO - PID 16645 | 12:06:47 INFO - PID 16645 | [#5] yui.html Cycles:180 Average:24841.66 Median:17943.14 stddev:36502.70 (203.4%) stddev-sans-first:36560.81 12:06:47 INFO - PID 16645 | Values: 892.9 920.4 928.3 928.4 950.2 19033.2 20016.6 20019.8 20037.7 20051.1 17715.0 18209.3 18495.5 18555.7 18795.5 995.0 995.8 1002.3 1003.2 1005.6 990.5 1004.6 1008.1 1013.7 1022.1 1068.0 1068.3 1068.7 1068.7 1070.9 1019.1 1060.4 1062.3 1065.3 1065.9 745.3 747.3 750.7 752.2 752.6 20039.2 20166.1 20173.7 20184.7 20220.4 21553.2 21889.0 22369.6 22543.1 22617.5 17343.1 17789.1 17928.4 17957.9 18114.6 3704.0 3709.4 3740.4 3746.1 3747.0 141706.0 142216.0 142681.0 143302.0 143662.0 17210.2 17237.0 17533.3 17540.5 17541.6 30267.2 30813.2 31467.2 31600.0 31684.2 8965.9 9228.8 9263.5 9347.6 9398.4 4228.1 4338.0 4365.9 4367.0 4373.8 5662.3 5713.4 5756.3 5765.5 5768.3 986.5 1012.8 1018.5 1021.6 1031.9 699.7 707.9 714.4 714.5 721.9 11662.4 12054.3 12087.3 12453.3 12816.1 661.1 662.2 664.5 672.7 686.4 5559.3 5588.8 5616.4 5733.8 5765.3 125991.0 130302.3 130756.3 131657.0 135284.3 104506.0 107015.5 109576.0 112810.4 113012.4 95295.0 95853.5 98980.5 99747.5 101393.0 10516.3 10532.2 10630.0 10787.0 10798.8 45411.0 46910.3 47100.8 47646.0 48144.5 57039.4 58750.0 58785.4 59148.0 59449.4 14147.8 14272.1 14365.6 14401.4 14566.9 16045.9 16162.0 16261.8 16400.6 16593.3 15748.0 15990.0 16089.4 16108.9 16178.4 15361.7 15376.9 15490.8 15607.2 15607.5 14377.7 14549.1 14562.3 14663.3 14665.1 12029.9 12092.4 12155.0 12277.8 12326.5 11613.9 11667.6 11879.0 11980.5 12240.9 12:06:47 INFO - PID 16645 | -------- Summary: end -------- 12:06:47 INFO - PID 16645 | 12:06:47 INFO - TEST-INFO | 16645: exit 0 12:06:48 INFO - TEST-OK | dromaeo_css | took 1107444ms 12:06:48 INFO - TEST-START | kraken 12:06:48 INFO - Initialising browser for kraken test... 12:06:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40992/getInfo.html -profile /tmp/tmp6zGsvF/profile 12:06:48 INFO - TEST-INFO | started process 17051 (/builds/slave/test/build/application/firefox/firefox http://localhost:40992/getInfo.html) 12:06:53 INFO - TEST-INFO | 17051: exit 0 12:06:54 INFO - Browser initialized. 12:06:54 INFO - Running cycle 1/1 for kraken test... 12:06:54 INFO - Using env: {'DISPLAY': ':0', 12:06:54 INFO - 'HOME': '/home/cltbld', 12:06:54 INFO - 'JSGC_DISABLE_POISONING': '1', 12:06:54 INFO - 'LANG': 'en_US.UTF-8', 12:06:54 INFO - 'LANGUAGE': 'en_US:en', 12:06:54 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:06:54 INFO - 'LOGNAME': 'cltbld', 12:06:54 INFO - 'MAIL': '/var/mail/cltbld', 12:06:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:06:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:06:54 INFO - 'MOZ_CRASHREPORTER': '1', 12:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:54 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:06:54 INFO - 'MOZ_NO_REMOTE': '1', 12:06:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:54 INFO - 'NO_EM_RESTART': '1', 12:06:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:54 INFO - 'PWD': '/builds/slave/test', 12:06:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:06:54 INFO - 'RUST_BACKTRACE': 'full', 12:06:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:06:54 INFO - 'SHELL': '/bin/bash', 12:06:54 INFO - 'SHLVL': '1', 12:06:54 INFO - 'TERM': 'linux', 12:06:54 INFO - 'TMOUT': '86400', 12:06:54 INFO - 'USER': 'cltbld', 12:06:54 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1507056436.139313-1322947943', 12:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:54 INFO - '_': '/tools/buildbot/bin/python'} 12:06:54 INFO - TEST-INFO | started process 17325 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6zGsvF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:06:54 INFO - PID 17325 | 12:06:54 INFO - PID 17325 | (/builds/slave/test/build/application/firefox/firefox:17374): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:06:54 INFO - PID 17325 | 12:07:05 INFO - PID 17325 | RSS: Main: 152461312 12:07:05 INFO - PID 17325 | 12:07:07 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40992/tests/kraken/driver.html?testName=audio-beat-detection) 12:07:08 INFO - PID 17325 | RSS: Main: 159563776 12:07:08 INFO - PID 17325 | 12:07:11 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40992/tests/kraken/driver.html?testName=audio-dft) 12:07:12 INFO - PID 17325 | RSS: Main: 159432704 12:07:12 INFO - PID 17325 | 12:07:15 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40992/tests/kraken/driver.html?testName=audio-fft) 12:07:15 INFO - PID 17325 | RSS: Main: 155254784 12:07:15 INFO - PID 17325 | 12:07:17 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40992/tests/kraken/driver.html?testName=audio-oscillator) 12:07:18 INFO - PID 17325 | RSS: Main: 155484160 12:07:18 INFO - PID 17325 | 12:07:20 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40992/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:07:21 INFO - PID 17325 | RSS: Main: 155574272 12:07:21 INFO - PID 17325 | 12:07:24 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40992/tests/kraken/driver.html?testName=imaging-darkroom) 12:07:24 INFO - PID 17325 | RSS: Main: 157659136 12:07:24 INFO - PID 17325 | 12:07:28 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40992/tests/kraken/driver.html?testName=imaging-desaturate) 12:07:29 INFO - PID 17325 | RSS: Main: 159014912 12:07:29 INFO - PID 17325 | 12:07:32 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40992/tests/kraken/driver.html?testName=json-parse-financial) 12:07:32 INFO - PID 17325 | RSS: Main: 158789632 12:07:32 INFO - PID 17325 | 12:07:34 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40992/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:07:35 INFO - PID 17325 | RSS: Main: 158691328 12:07:35 INFO - PID 17325 | 12:07:36 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40992/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:07:37 INFO - PID 17325 | RSS: Main: 158527488 12:07:37 INFO - PID 17325 | 12:07:39 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40992/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:07:40 INFO - PID 17325 | RSS: Main: 155176960 12:07:40 INFO - PID 17325 | 12:07:42 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40992/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:07:43 INFO - PID 17325 | RSS: Main: 155287552 12:07:43 INFO - PID 17325 | 12:07:46 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40992/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:07:46 INFO - PID 17325 | RSS: Main: 155947008 12:07:46 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | Cycle 1(1): loaded http://localhost:40992/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40992/tests/kraken/driver.html?testName=ai-astar) 12:07:48 INFO - PID 17325 | RSS: Main: 157577216 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | __start_tp_report 12:07:48 INFO - PID 17325 | _x_x_mozilla_page_load 12:07:48 INFO - PID 17325 | _x_x_mozilla_page_load_details 12:07:48 INFO - PID 17325 | |i|pagename|runs| 12:07:48 INFO - PID 17325 | |0;ai-astar;107;107;106;106;107;109;108;106;107;110 12:07:48 INFO - PID 17325 | |1;audio-beat-detection;116;115;115;116;124;115;116;151;119;121 12:07:48 INFO - PID 17325 | |2;audio-dft;162;162;163;165;162;162;162;163;163;164 12:07:48 INFO - PID 17325 | |3;audio-fft;74;74;73;74;74;80;74;75;74;74 12:07:48 INFO - PID 17325 | |4;audio-oscillator;81;79;80;78;78;78;79;79;79;79 12:07:48 INFO - PID 17325 | |5;imaging-gaussian-blur;110;113;113;110;114;114;114;110;115;113 12:07:48 INFO - PID 17325 | |6;imaging-darkroom;159;148;149;149;148;148;148;148;149;148 12:07:48 INFO - PID 17325 | |7;imaging-desaturate;95;94;94;100;94;94;94;93;94;96 12:07:48 INFO - PID 17325 | |8;json-parse-financial;54;52;53;54;52;53;53;53;59;53 12:07:48 INFO - PID 17325 | |9;json-stringify-tinderbox;37;37;37;37;37;37;37;36;37;38 12:07:48 INFO - PID 17325 | |10;stanford-crypto-aes;74;73;71;72;71;75;76;73;73;74 12:07:48 INFO - PID 17325 | |11;stanford-crypto-ccm;116;108;112;111;119;113;113;113;112;110 12:07:48 INFO - PID 17325 | |12;stanford-crypto-pbkdf2;162;162;156;160;161;160;164;161;159;160 12:07:48 INFO - PID 17325 | |13;stanford-crypto-sha256-iterative;79;63;64;63;67;65;66;62;63;65 12:07:48 INFO - PID 17325 | __end_tp_report 12:07:48 INFO - PID 17325 | __start_cc_report 12:07:48 INFO - PID 17325 | _x_x_mozilla_cycle_collect,774 12:07:48 INFO - PID 17325 | __end_cc_report 12:07:48 INFO - PID 17325 | __startTimestamp1507057668704__endTimestamp 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | ------- Summary: start ------- 12:07:48 INFO - PID 17325 | Number of tests: 14 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#0] ai-astar Cycles:10 Average:107.30 Median:107.00 stddev:1.34 (1.2%) stddev-sans-first:1.41 12:07:48 INFO - PID 17325 | Values: 107.0 107.0 106.0 106.0 107.0 109.0 108.0 106.0 107.0 110.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#1] audio-beat-detection Cycles:10 Average:120.80 Median:117.50 stddev:11.03 (9.4%) stddev-sans-first:11.57 12:07:48 INFO - PID 17325 | Values: 116.0 115.0 115.0 116.0 124.0 115.0 116.0 151.0 119.0 121.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.00 stddev:1.03 (0.6%) stddev-sans-first:1.05 12:07:48 INFO - PID 17325 | Values: 162.0 162.0 163.0 165.0 162.0 162.0 162.0 163.0 163.0 164.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#3] audio-fft Cycles:10 Average:74.60 Median:74.00 stddev:1.96 (2.6%) stddev-sans-first:2.06 12:07:48 INFO - PID 17325 | Values: 74.0 74.0 73.0 74.0 74.0 80.0 74.0 75.0 74.0 74.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:79.00 stddev:0.94 (1.2%) stddev-sans-first:0.67 12:07:48 INFO - PID 17325 | Values: 81.0 79.0 80.0 78.0 78.0 78.0 79.0 79.0 79.0 79.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#5] imaging-gaussian-blur Cycles:10 Average:112.60 Median:113.50 stddev:1.90 (1.7%) stddev-sans-first:1.76 12:07:48 INFO - PID 17325 | Values: 110.0 113.0 113.0 110.0 114.0 114.0 114.0 110.0 115.0 113.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:148.50 stddev:3.41 (2.3%) stddev-sans-first:0.50 12:07:48 INFO - PID 17325 | Values: 159.0 148.0 149.0 149.0 148.0 148.0 148.0 148.0 149.0 148.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.00 stddev:1.99 (2.1%) stddev-sans-first:2.11 12:07:48 INFO - PID 17325 | Values: 95.0 94.0 94.0 100.0 94.0 94.0 94.0 93.0 94.0 96.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:53.00 stddev:2.01 (3.8%) stddev-sans-first:2.13 12:07:48 INFO - PID 17325 | Values: 54.0 52.0 53.0 54.0 52.0 53.0 53.0 53.0 59.0 53.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#9] json-stringify-tinderbox Cycles:10 Average:37.00 Median:37.00 stddev:0.47 (1.3%) stddev-sans-first:0.50 12:07:48 INFO - PID 17325 | Values: 37.0 37.0 37.0 37.0 37.0 37.0 37.0 36.0 37.0 38.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:73.50 stddev:1.62 (2.2%) stddev-sans-first:1.69 12:07:48 INFO - PID 17325 | Values: 74.0 73.0 71.0 72.0 71.0 75.0 76.0 73.0 73.0 74.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#11] stanford-crypto-ccm Cycles:10 Average:112.70 Median:113.00 stddev:3.06 (2.7%) stddev-sans-first:3.00 12:07:48 INFO - PID 17325 | Values: 116.0 108.0 112.0 111.0 119.0 113.0 113.0 113.0 112.0 110.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.50 Median:161.00 stddev:2.12 (1.3%) stddev-sans-first:2.18 12:07:48 INFO - PID 17325 | Values: 162.0 162.0 156.0 160.0 161.0 160.0 164.0 161.0 159.0 160.0 12:07:48 INFO - PID 17325 | 12:07:48 INFO - PID 17325 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:65.00 stddev:4.92 (7.6%) stddev-sans-first:1.64 12:07:48 INFO - PID 17325 | Values: 79.0 63.0 64.0 63.0 67.0 65.0 66.0 62.0 63.0 65.0 12:07:48 INFO - PID 17325 | -------- Summary: end -------- 12:07:48 INFO - PID 17325 | 12:07:49 INFO - PID 17325 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:07:49 INFO - TEST-INFO | 17325: exit 0 12:07:49 INFO - TEST-OK | kraken | took 61370ms 12:07:49 INFO - SUITE-END | took 1168s 12:07:49 INFO - Completed test suite (00:19:29) 12:07:49 INFO - javascript benchmark 12:07:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8693.194652866909, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3097.0553981035787, 3130.671279515651, 3158.0999415166666, 3173.4129186100586, 3181.2206009527667, 6198.380161983805, 6246.531510136747, 6323.715432805523, 6324.1462402575635, 6336.398042185997, 6089.573729838913, 6162.630242185461, 6184.536159788023, 6290.21372328459, 6381.840453988652, 5758.42415758425, 5759.395263497336, 5841.0, 5848.67832269224, 5848.970755146218, 746.0340033896405, 5696.943030569682, 5785.8264252072295, 5844.824655260349, 5892.528597712194, 5751.654900705971, 5754.827355179351, 5766.423357664242, 5815.912761308584, 5816.651000939936, 5727.31272247333, 5744.281964754406, 5804.448577385142, 5811.15738217956, 5840.561957853136, 5729.0, 5730.570207234474, 5767.0, 5809.564282678773, 5820.708964551755, 6238.688065596702, 6246.843828904268, 6262.499000079983, 6271.749130034838, 6344.524160687967, 6450.225972883237, 6550.574212676209, 6762.289959554225, 6815.114035175445, 6851.48613853963, 6734.8316292092595, 6846.8972965405555, 6854.897176542356, 6856.897146542806, 6859.142607174103, 6246.500279977591, 6367.458766004896, 6368.617882926993, 6371.044343348489, 6383.840403989891, 6949.270326615733, 7012.333828286253, 7022.6488675566015, 7030.191527974246, 7042.718291268292, 6543.869122617616, 6633.9336606633315, 6665.466762658975, 6705.2624211336715, 6721.899171512431, 6579.539432239793, 6628.966855165693, 6694.933050669431, 6720.092787473726, 6730.125083739181, 6638.535302528873, 6725.932740672629, 6730.764423245163, 6736.124303840567, 6743.831404214934, 6542.901856472156, 6640.003999400032, 6768.221654509696, 6800.6939687714175, 6811.795646130624, 4886.144924638174, 4958.776855041532, 4978.128827455181, 4978.551930326288, 4978.6763860348965, 4357.760323182192, 4392.38506609075, 4418.9337159942625, 4421.712588681726, 4424.203643344164, 5726.055200891807, 5760.596758226968, 5820.330661973842, 5834.649921004753, 5893.587448878623, 6827.897581536281, 6833.658317084225, 6838.14523184602, 6840.965795170992, 6844.555103918229, 5839.649621022752, 5871.089981052944, 5884.293884733859, 5884.705764711747, 5890.528757699374, 5797.420257974169, 5805.187273781674, 5872.0, 5878.970605146947, 5896.970515147397, 6508.934910650928, 6556.704948277244, 6563.310852360433, 6641.037049627874, 6642.236142843538, 6783.219929708048, 6789.2871248518195, 6797.422219111431, 6798.150231220999, 6806.42145417645, 6386.457151142019, 6558.049082883051, 6558.213014438298, 6561.507886908596, 6561.671916404256, 6623.6688165592495, 6623.933760662524, 6700.994850772423, 6715.93284067153, 6722.865542689021, 6719.193696756421, 6755.662216889037, 6823.38589526945, 6824.522283440111, 6842.8289292766085, 6601.834954126185, 6617.139771829631, 6650.235222949423, 6651.966740166268, 6659.567128136656, 6448.193975753031, 6544.5418820684, 6546.0508226307875, 6635.469162466895, 6643.701033453603, 6323.557350985387, 6439.67801609927, 6477.740890364333, 6566.868662626625, 6566.967165164144, 6602.075709400592, 6695.397414232742, 6711.060451536887, 6715.328467153441, 6740.12378390806, 6340.4293613575, 6407.487401008001, 6431.935680643133, 6437.324080971431, 6439.0], "value": 6095.833415749221, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [870.3907264914559, 896.6054935828231, 898.1243287794397, 898.7124120281509, 900.2752784008875, 41321.17357652849, 45169.548304516946, 45364.54635453645, 45382.319265211045, 45394.546054539445, 41203.17593648125, 41224.38163427551, 41311.79344103275, 41344.0, 41448.99999999993, 7311.0861142357335, 7439.293267139613, 7453.14288856783, 8020.839583208332, 8243.175682431769, 8070.9192908071045, 8171.18288171184, 8172.591370431484, 8176.754697359089, 8179.79550511236, 8128.512289262663, 8197.0, 8207.712730054449, 8233.0, 8238.794030149234, 8182.509049457051, 8188.2630563249295, 8203.384746144004, 8221.465604735673, 8224.136465671078, 8098.3926205534235, 8137.226963438464, 8150.429469937107, 8163.469374490639, 8171.71399001038, 38203.0, 38841.417378739345, 39169.0, 39191.60808391937, 39195.412068818994, 3475.14858859581, 3524.7708898921487, 3525.6474352564796, 3536.1159710072484, 3537.2748586539956, 32526.0, 32540.186495337806, 32574.674253257403, 32797.83601082003, 32834.17914552155, 5152.742362881879, 5205.947940520584, 5279.366476022902, 5309.389420216686, 5313.707746073965, 204058.0, 204667.0, 204804.0, 205708.0, 205789.0, 44727.0, 45443.54556454459, 50503.0, 51490.74254628703, 51583.226251606255, 55504.0, 56222.43777562172, 56321.7183914086, 56542.0, 56577.71711141418, 16829.242684079247, 16961.321547138225, 16972.83027169737, 16986.32054717822, 17010.234539445755, 13833.0, 13930.651733706536, 13945.16329020262, 13945.65135871591, 13953.511627093207, 14386.928065359816, 14702.529747025426, 14832.703345933021, 14897.404103835725, 14933.253337333308, 5785.971070144622, 5786.826395208151, 5810.94189058104, 5880.882382352329, 5971.820285595726, 984.13396211335, 994.418265314799, 1010.8685870836745, 1018.6434747838198, 1020.0615433800773, 23099.422514436937, 23176.3047108587, 23289.883550582137, 23451.17920872761, 23604.29187124389, 1116.7096554895786, 1116.7431490757172, 1118.189312748251, 1121.7476067884825, 1126.6732647532124, 12173.2696038238, 12318.75362492745, 12352.320622365676, 12457.128001040022, 12568.748625027449, 232571.0, 234719.0, 234767.0, 235019.0, 235384.0, 55653.44346556483, 56034.71982640142, 56201.15698264529, 56234.0, 56422.15366769502, 1432.0906224547487, 1454.7604967474783, 1457.9271036448347, 1492.2986196487634, 1499.4751836857013, 22688.546229075655, 22814.429639258487, 22833.42916427036, 23176.884115579312, 23205.303840884124, 39680.0, 39753.60246397499, 39761.60238397579, 39801.0, 39884.202315952934, 198628.0, 199514.0, 200027.0, 200367.0, 200575.0, 35576.0, 37180.44229336508, 37337.81331093327, 37599.81200093982, 37626.0, 46875.53124468712, 47875.93054590399, 48050.0, 48325.0, 48692.75653621709, 33249.83375083206, 33657.326853462306, 33727.0, 33758.662413375554, 34085.82957085298, 33776.49335260022, 34016.319673605896, 34039.149021274665, 34465.48301775526, 35029.0, 37288.254234915694, 37621.0, 37701.0, 37701.05747356338, 37822.243555129295, 33298.500522492664, 33539.496907545916, 33567.0, 33712.66287337095, 33922.0, 24204.51590968206, 24292.51414971726, 24476.0, 24554.877225614473, 24760.25719228426], "value": 17832.771427747175, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.178985840107, 583.5477504933673, 584.9824505264841, 595.5295316699807, 621.6363427394974, 22014.229501967435, 22598.096076156933, 22623.66064509034, 22660.43348916278, 22693.77306226937, 22835.08659653612, 23715.762842371572, 23824.166154184608, 23857.64213536794, 25135.0, 6664.133662623864, 6774.932250677504, 6858.725650973955, 6860.279670634586, 6863.450923926074, 6495.805125846232, 6741.8314542136595, 6750.358715922004, 6835.624040677761, 6853.109095817536, 6767.796966091, 6797.524173307846, 6804.149481314836, 6806.829829254283, 6817.977303404504, 6811.352921472452, 6831.829204269909, 6833.180018397799, 7075.504714669975, 7395.149557800814, 7176.210616832144, 7232.1321441426835, 7247.528910620846, 7251.564906105597, 7259.092613423322, 30998.38003239945, 31550.211244718837, 31599.0, 31694.841525792224, 31796.682033179604, 2100.789921007887, 2110.1770309579124, 2117.4494631395796, 2123.076461739133, 2123.8088572028437, 20140.194392224294, 21353.78646213549, 21396.251131210334, 21524.46188845276, 21538.461538461506, 2640.960385594198, 2650.946981060368, 2715.484058028988, 2717.1305182341657, 2728.0724553651853, 125676.0, 131160.0, 145358.0, 149529.0, 156999.43000569963, 20547.589048218953, 21055.473613159487, 21817.454563636034, 22393.776062239496, 22930.885345573166, 42564.0, 42901.0, 42913.0, 42955.14089718188, 43000.56999429966, 11920.46357913896, 11949.581764638197, 12256.342589815991, 12370.257784532956, 12395.814062789066, 10137.594496220068, 10149.797004059878, 10206.948965255126, 10245.334053286513, 10337.586496540205, 11161.720956975978, 11378.943105284421, 11429.714257143638, 11799.407080044255, 11821.290722556674, 3531.3643544162296, 3564.8752293669595, 3940.408938659167, 3985.121945130909, 4191.479157507647, 957.2485598804905, 968.864358989742, 978.9804203916025, 986.4919566423298, 987.2546227598117, 20040.0, 20064.89967550182, 20160.39518814438, 20591.485212869797, 20642.690359644617, 1036.9170466362673, 1037.8754549454113, 1043.2697112021615, 1051.0435503691604, 1058.0953284941418, 11060.234941264684, 11344.149188810707, 11359.54561817518, 11361.659150225507, 11481.770364592661, 475457.0, 480044.0, 484328.0, 492533.0, 493740.0, 111129.44435277772, 111631.44184279027, 113566.43216784028, 114301.0, 114883.0, 1060.3584831177027, 1106.3748981825242, 1176.582313278791, 1195.5217912834762, 1241.615099319232, 21194.682079768507, 21369.14523419046, 21402.25092121768, 21626.567468650224, 21802.672959905933, 55775.721121394134, 55949.44050559442, 57307.713461432424, 57661.0, 58652.12021819591, 70590.64704676444, 70871.64564177147, 71129.64435177791, 71529.0, 72029.27970720225, 20186.596268074263, 20744.170233190503, 20772.480687982923, 20968.370948871558, 20987.580248394643, 23681.289561313188, 23883.0, 23888.28335149948, 24068.51862962766, 24084.51830963336, 17836.46490605284, 18021.459356219333, 18178.72731908996, 18188.90905545464, 18256.634867302313, 16873.578160545592, 16908.830911690726, 16920.492385228463, 17518.474445766646, 17686.0, 17781.46655600334, 18234.36179733703, 18327.450176494727, 18396.908015459838, 18405.723914141032, 19105.617887642446, 19188.232470701016, 19226.134823932676, 19261.22955081781, 19899.104540295433, 18080.367187148386, 18102.90948545249, 18224.357468166778, 18354.357597484024, 18407.53981150482], "value": 13127.275135175101, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1171.3557543351158, 1356.3896246688992, 1359.735446035187, 1384.473899918031, 1397.2245403800894, 14981.176035318067, 15571.688566228682, 15613.453529126482, 15623.140727259995, 15648.765268520952, 15151.848481515182, 15213.92393038036, 15323.770143447857, 15343.3095510702, 15349.079055256692, 2629.592413175951, 2652.4297276085636, 2695.353115252345, 2703.4728227995547, 2712.9864350678317, 2669.9733002670014, 2680.8793604287853, 2684.8254863433913, 2687.7984151188643, 2690.730926907313, 2607.38726399296, 2652.761251487366, 2653.2173008962304, 2656.043824223278, 2660.76053155216, 2533.8226324157317, 2599.6620439342764, 2601.193629974714, 2602.531544322021, 2605.517979173849, 2521.9873900630378, 2530.671012768347, 2544.9363765905814, 2553.374423266293, 2555.642210090589, 14254.928725356307, 14563.854361456355, 14593.27033648324, 14609.853901460956, 14620.926895365455, 7571.924280757177, 7630.4658673892845, 7649.617519124022, 7656.081270247606, 7915.802104947365, 4985.52637499437, 5081.3140226069745, 5148.536631703165, 5204.869878253036, 5348.759305831247, 1841.8066103059255, 1848.8890666560048, 1921.1258877210976, 1935.7096435534643, 1958.6082783443226, 24305.0, 25120.623190652153, 27551.173464795887, 27943.16170514888, 27978.72021279802, 7171.89242161368, 7183.92816071843, 7243.422847082703, 7350.228226036189, 7351.117865856131, 20863.895680521804, 20882.58234835295, 21005.89497052505, 21016.36950891476, 21073.26243581467, 8014.839703205903, 8126.75619731409, 8126.984126984127, 8127.309178719756, 8146.633401496947, 6970.372666460043, 7202.603856787822, 7240.891386629205, 7306.342429181399, 7323.670434830446, 5507.366652834895, 5656.660600363992, 5660.5471562274915, 5684.77777277879, 5711.800086997018, 1753.4301352060631, 1770.9557261068576, 1784.0633667324757, 1808.6021075363399, 1825.7261410788487, 902.4224496322358, 905.791667916383, 909.5452273863068, 920.8204400142042, 923.385948344357, 7502.062242219723, 7550.735724249625, 7556.357709594746, 7567.508111972704, 7579.393648508217, 717.2540557819809, 733.4059411876291, 733.8642351164945, 742.4913933955258, 753.3784627682185, 4714.88212794676, 4740.19416699162, 4795.448523419782, 4803.975980120077, 4840.540148685869, 20259.8987005067, 20761.896190519252, 21792.128314867547, 21804.127834886425, 21864.23475178361, 21270.680939785914, 21272.0, 21298.25456109029, 21440.0, 21453.249136280156, 10286.485675716185, 11344.716382090348, 11356.886431135748, 11404.087672986141, 11415.0, 8194.631241594261, 8280.50316980983, 8327.791805204919, 8382.287505561852, 8392.286655634096, 17783.555411114827, 18063.548411289823, 18087.638247235245, 18280.90859545694, 18460.169292382143, 18308.359207427675, 18329.90835045816, 18593.90703046476, 18758.249670013047, 19006.524836879187, 12167.026637869127, 12280.81578776337, 12282.324472154118, 12393.690157746129, 12439.564615238423, 13809.309534523434, 13896.930515347358, 14024.368903399576, 14030.719385612434, 14059.437622495394, 11241.269317494336, 11347.943260283646, 11398.0, 11577.131715121232, 11662.12534059932, 11052.834207486734, 11091.72270693239, 11102.167337449886, 11222.494987725735, 11461.312321260752, 9725.708228752866, 9756.658516951873, 9870.555824987749, 9909.108180263811, 9922.503874806374, 7229.963850180716, 7231.674574644258, 7287.234840341688, 7359.595222262827, 7377.926220737724, 7549.773506794805, 7554.131274903237, 7632.961835190788, 7637.579933103732, 7649.349805266615], "value": 6654.573576708978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.8161062017673, 510.9335786347774, 529.0, 532.0396683985407, 552.4392741367512, 6116.614520337066, 6138.539609529281, 6146.784862529812, 6150.863059839486, 6179.81460556184, 5964.940350596493, 5989.550783691229, 6050.515958723303, 6064.151018857364, 6071.969640151804, 4866.270059491087, 5123.795048198086, 5133.897322053556, 5150.609335479418, 5151.484851514856, 4973.154563724175, 5010.498950104997, 5017.397912250535, 5032.798688052492, 5041.899162016757, 4932.482089380617, 4941.332920055783, 4984.93125352978, 4992.450830408652, 4997.700137991714, 4955.306257124005, 4963.950360496385, 4984.177610694267, 4999.675021123651, 5016.122178618763, 4516.864494065183, 4528.298113792334, 4532.914859164788, 4546.909061818778, 4547.45430548333, 6068.908966365508, 6106.328303886569, 6166.81499555014, 6177.166082578884, 6208.596441231305, 5612.719364031782, 5636.182753500761, 5719.885602287973, 5725.770969161229, 5726.4273572642405, 6497.252815926135, 6530.738770449177, 6530.869382612369, 6546.476281897437, 6546.70539825709, 4884.218525036013, 4914.852554423373, 4932.112219800434, 4933.851984440473, 4935.185694360426, 6757.189137303506, 6783.694733736994, 6822.488313376517, 6828.726850925957, 6873.621950792754, 5006.349174607278, 5036.697798132123, 5037.5214354636255, 5049.84850454487, 5060.493950604984, 5083.186690129562, 5108.974455127701, 5115.462876397999, 5127.615428842777, 5139.460356662571, 5617.494425501724, 5618.70769722966, 5695.943040569624, 5707.229524014288, 5721.456461636138, 6226.657533835682, 6288.748450061947, 6355.3009168991375, 6458.418742313214, 6548.508861835381, 1720.6042610199474, 1730.2041061111777, 1743.938962136319, 1769.5222289981632, 1774.8047714751367, 1060.8090543702053, 1214.5688280660415, 1226.7546490701895, 1240.7642547915866, 1250.5185503581006, 5608.551315894719, 5718.1136923776885, 5729.5702822288495, 5771.88456230873, 5782.537397008229, 6696.46428285736, 6709.396154346132, 6722.159730033746, 6732.697028633724, 6805.421539169127, 5740.770369185269, 5743.54051675865, 5757.424257574209, 5776.884462310731, 5779.479846813723, 3548.822558872046, 3557.21741216937, 3557.4841647961416, 3576.8211589420425, 3607.7294202934886, 6468.288488266287, 6480.870382592322, 6545.101920443019, 6565.146530950946, 6567.704453299613, 6785.694643741043, 6804.931950680529, 6805.0, 6837.38463538274, 6892.448604111759, 6390.009548519987, 6445.711772320758, 6455.580387274812, 6473.119461839437, 6483.2544257411, 6465.967670161619, 6562.245341785756, 6566.573172743756, 6621.205455345197, 6650.46796256308, 6520.478361731145, 6586.670666466562, 6591.1771172718745, 6607.372299631527, 6617.470602351704, 6329.892268853025, 6376.915924292789, 6444.484441244783, 6482.481401487776, 6499.415052645285, 4959.355283813081, 4970.353853998957, 5002.474740152232, 5017.37282839645, 5026.346574945233, 6526.184226971629, 6547.901781473377, 6672.299408562225, 6682.498812588978, 6699.564528305645, 6339.0, 6341.4292713656, 6359.791639588463, 6441.323661015525, 6451.516136289703, 6337.746490140527, 6399.7440102395385, 6477.8704425912165, 6480.740770369132, 6544.770933017321, 6304.684765761602, 6353.174087368612, 6367.777127800505, 6399.936000639934, 6457.383865035595, 6297.842553936005, 6364.186206931003, 6390.0, 6449.774257900951, 6454.0, 5890.410958904075, 6217.906731398942, 6257.843553911007, 6262.749490020348, 6262.968685156545], "value": 5030.966443320617, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.9419587726801, 920.3971398733829, 928.3455164109304, 928.3687092776913, 950.1638558068898, 19033.238670453182, 20016.59966800663, 20019.799802001977, 20037.699434508475, 20051.09770060349, 17715.0, 18209.27162913482, 18495.537611559717, 18555.721664175013, 18795.53011174721, 995.0149352141382, 995.7809281957958, 1002.2633364477103, 1003.1673710820038, 1005.5776573839005, 990.5245482168564, 1004.6182450668736, 1008.1229330482454, 1013.6806905824678, 1022.0954455307069, 1068.0441005300252, 1068.2895874243618, 1068.6740544134036, 1068.7221322456178, 1070.9089727373182, 1019.0726438940528, 1060.390275591538, 1062.2723434447428, 1065.2969040433316, 1065.9253852230347, 745.3440971944653, 747.3460721868365, 750.6509473094983, 752.2176935986568, 752.559752544762, 20039.1984320627, 20166.092525836226, 20173.697394538947, 20184.69722954157, 20220.39338819838, 21553.24563640265, 21889.0, 22369.55260894789, 22543.098276068937, 22617.54764904709, 17343.13284335778, 17789.110544472853, 17928.372506962194, 17957.910210448863, 18114.637707245914, 3703.981480092609, 3709.387950988111, 3740.4202348636004, 3746.063484128968, 3747.0445036515785, 141705.99999999898, 142216.0, 142681.0, 143302.0, 143662.0, 17210.225539850737, 17237.0, 17533.29866805339, 17540.47378578619, 17541.64916701659, 30267.243318917204, 30813.22966925845, 31467.213319666735, 31600.0, 31684.207894802814, 8965.865512017324, 9228.7692807679, 9263.536823158815, 9347.626094956126, 9398.389104708172, 4228.112096459717, 4338.0, 4365.9126817463475, 4366.978165109154, 4373.846915357947, 5662.348829884616, 5713.4000929902595, 5756.30924289088, 5765.4811067004175, 5768.3366412862215, 986.541258314887, 1012.7822518158631, 1018.5060245780791, 1021.6015753856002, 1031.9484025798681, 699.7306037175724, 707.9433645308363, 714.3749219432996, 714.4598514361327, 721.90976127984, 11662.358570278546, 12054.276743395425, 12087.274763514217, 12453.315067671183, 12816.102872799001, 661.147120214927, 662.1722846441947, 664.538145988543, 672.7342699633617, 686.3925425997928, 5559.27729395176, 5588.776448942078, 5616.382197958222, 5733.827985160452, 5765.3369862465515, 125991.0, 130302.34848825885, 130756.3462182702, 131657.0, 135284.32357838345, 104506.0, 107015.46492267644, 109576.0, 112810.43594782137, 113012.43493782479, 95295.0, 95853.52073239729, 98980.50509747406, 99747.50126249467, 101393.0, 10516.26386152962, 10532.157427405942, 10630.0, 10787.029167374974, 10798.838017429902, 45411.0, 46910.29634555416, 47100.7644961773, 47646.04707905892, 48144.51855481401, 57039.429605703415, 58750.0, 58785.41214587799, 59148.0, 59449.405505944385, 14147.787783183054, 14272.14367137975, 14365.569032929028, 14401.42394304216, 14566.927165364104, 16045.919770401073, 16162.030278183347, 16261.75607365914, 16400.589985250463, 16593.253303601126, 15748.0, 15990.040597564184, 16089.356425742839, 16108.919455403116, 16178.433754818525, 15361.692766144837, 15376.923115384352, 15490.767638485657, 15607.219639017776, 15607.45373911953, 14377.712445751236, 14549.054311469721, 14562.344694488986, 14663.340149693502, 14665.120092794466, 12029.879701203226, 12092.395380230777, 12155.0, 12277.754444910874, 12326.534693061603, 11613.941930290295, 11667.59163429276, 11879.0, 11980.520779168735, 12240.87759122433], "value": 9033.367147640802, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1404.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 106.0, 106.0, 107.0, 109.0, 108.0, 106.0, 107.0, 110.0], "value": 107.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 115.0, 116.0, 124.0, 115.0, 116.0, 151.0, 119.0, 121.0], "value": 120.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 165.0, 162.0, 162.0, 162.0, 163.0, 163.0, 164.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 74.0, 74.0, 80.0, 74.0, 75.0, 74.0, 74.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 80.0, 78.0, 78.0, 78.0, 79.0, 79.0, 79.0, 79.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 113.0, 110.0, 114.0, 114.0, 114.0, 110.0, 115.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 148.0, 149.0, 149.0, 148.0, 148.0, 148.0, 148.0, 149.0, 148.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 100.0, 94.0, 94.0, 94.0, 93.0, 94.0, 96.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 54.0, 52.0, 53.0, 53.0, 53.0, 59.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 36.0, 37.0, 38.0], "value": 37.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 71.0, 72.0, 71.0, 75.0, 76.0, 73.0, 73.0, 74.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 108.0, 112.0, 111.0, 119.0, 113.0, 113.0, 113.0, 112.0, 110.0], "value": 112.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 156.0, 160.0, 161.0, 160.0, 164.0, 161.0, 159.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 63.0, 64.0, 63.0, 67.0, 65.0, 66.0, 62.0, 63.0, 65.0], "value": 65.7, "unit": "score"}]}]} 12:07:49 INFO - Return code: 0 12:07:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:07:49 INFO - # TBPL SUCCESS # 12:07:49 INFO - Running post-action listener: _package_coverage_data 12:07:49 INFO - Running post-action listener: _resource_record_post_action 12:07:49 INFO - [mozharness: 2017-10-03 19:07:49.987000Z] Finished run-tests step (success) 12:07:49 INFO - Running post-run listener: _resource_record_post_run 12:07:50 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 339398656; Read time: 8; Write time: 206980 12:07:50 INFO - TinderboxPrint: CPU usage
13.1% 12:07:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 12:07:50 INFO - TinderboxPrint: I/O write bytes / time
339,398,656 / 206,980 12:07:50 INFO - TinderboxPrint: CPU idle
8,221.3 (86.9%) 12:07:50 INFO - TinderboxPrint: CPU user
1,191.4 (12.6%) 12:07:50 INFO - TinderboxPrint: Swap in / out
0 / 0 12:07:50 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 19079168; Read time: 0; Write time: 2624 12:07:50 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:07:50 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 320315392; Read time: 0; Write time: 204176 12:07:50 INFO - Running post-run listener: _upload_blobber_files 12:07:50 INFO - Blob upload gear active. 12:07:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:07:50 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:07:50 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:07:50 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:07:51 INFO - (blobuploader) - INFO - Open directory for files ... 12:07:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:07:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:07:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:07:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:07:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:07:52 INFO - (blobuploader) - INFO - Done attempting. 12:07:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:07:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:07:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:07:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:07:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:07:59 INFO - (blobuploader) - INFO - Done attempting. 12:07:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:07:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:07:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:08:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:08:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:08:00 INFO - (blobuploader) - INFO - Done attempting. 12:08:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:08:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:08:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:08:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:08:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:08:01 INFO - (blobuploader) - INFO - Done attempting. 12:08:01 INFO - (blobuploader) - INFO - Iteration through files over. 12:08:01 INFO - Return code: 0 12:08:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:08:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:08:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/404bb43ce02d6f71ac171a68d4f95a60bd77b19e8fbeee0dbf1960cf2491e464b772abb7bf6eb005708827bb93dca99ade086317bd984b2220849d1b59874f85", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f14b2671887592eb39beafc7038af47f123cbca49117e5f0b43e2858fe67800ec50f04a1e58bcbf991a5cb2b6b64fef67222182f16a4c6778e17b76314f55a5f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec26876dccf5f3f1d498fe9c4418d205e8009db2bd96295b5f17741ae392c2dabf58ea5071eff413935abffa5cf0b8f95e2cdab5e0c6274def8ec01d9e8a78c9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac8921d40ba152969d991741b64eaa6f7873dcfa5ff14d7263915d7fdd791641dc5d23271e2b107ab0742ab5073890c88c192b3c918c2561481d91d51f440e69"} 12:08:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:08:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:08:01 INFO - Contents: 12:08:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/404bb43ce02d6f71ac171a68d4f95a60bd77b19e8fbeee0dbf1960cf2491e464b772abb7bf6eb005708827bb93dca99ade086317bd984b2220849d1b59874f85", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f14b2671887592eb39beafc7038af47f123cbca49117e5f0b43e2858fe67800ec50f04a1e58bcbf991a5cb2b6b64fef67222182f16a4c6778e17b76314f55a5f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec26876dccf5f3f1d498fe9c4418d205e8009db2bd96295b5f17741ae392c2dabf58ea5071eff413935abffa5cf0b8f95e2cdab5e0c6274def8ec01d9e8a78c9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac8921d40ba152969d991741b64eaa6f7873dcfa5ff14d7263915d7fdd791641dc5d23271e2b107ab0742ab5073890c88c192b3c918c2561481d91d51f440e69"} 12:08:01 INFO - Running post-run listener: copy_logs_to_upload_dir 12:08:01 INFO - Copying logs to upload dir... 12:08:01 INFO - mkdir: /builds/slave/test/build/upload/logs 12:08:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.142515 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-10-03 12:08:01.731990) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:01.735390) ========= 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=4300f881736b422476174874000001bc-1507056436.139313-1322947943 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/404bb43ce02d6f71ac171a68d4f95a60bd77b19e8fbeee0dbf1960cf2491e464b772abb7bf6eb005708827bb93dca99ade086317bd984b2220849d1b59874f85", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f14b2671887592eb39beafc7038af47f123cbca49117e5f0b43e2858fe67800ec50f04a1e58bcbf991a5cb2b6b64fef67222182f16a4c6778e17b76314f55a5f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec26876dccf5f3f1d498fe9c4418d205e8009db2bd96295b5f17741ae392c2dabf58ea5071eff413935abffa5cf0b8f95e2cdab5e0c6274def8ec01d9e8a78c9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac8921d40ba152969d991741b64eaa6f7873dcfa5ff14d7263915d7fdd791641dc5d23271e2b107ab0742ab5073890c88c192b3c918c2561481d91d51f440e69"} build_url:https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011383 build_url: 'https://queue.taskcluster.net/v1/task/Y228oe7GQHKzMAnD1Lmgzg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/404bb43ce02d6f71ac171a68d4f95a60bd77b19e8fbeee0dbf1960cf2491e464b772abb7bf6eb005708827bb93dca99ade086317bd984b2220849d1b59874f85", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f14b2671887592eb39beafc7038af47f123cbca49117e5f0b43e2858fe67800ec50f04a1e58bcbf991a5cb2b6b64fef67222182f16a4c6778e17b76314f55a5f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ec26876dccf5f3f1d498fe9c4418d205e8009db2bd96295b5f17741ae392c2dabf58ea5071eff413935abffa5cf0b8f95e2cdab5e0c6274def8ec01d9e8a78c9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ac8921d40ba152969d991741b64eaa6f7873dcfa5ff14d7263915d7fdd791641dc5d23271e2b107ab0742ab5073890c88c192b3c918c2561481d91d51f440e69"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:01.767093) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:01.767505) ========= 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=4300f881736b422476174874000001bc-1507056436.139313-1322947943 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004449 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 12:08:01.849335) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 12:08:01.849691) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 12:08:01.850031) ========= ========= Total master_lag: 0.22 =========