builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-069 starttime: 1504520104.33 results: success (0) revision: 632e42dca494ec3d90b70325d9c359f80cb3f38a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:04.332968) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:04.333438) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:04.333723) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:04.351514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:04.351923) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-04 03:15:04-- 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% 47.0M=0s 2017-09-04 03:15:04 (47.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.179829 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:04.570354) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:04.570756) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016791 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:04.620802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:04.621184) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 632e42dca494ec3d90b70325d9c359f80cb3f38a --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 632e42dca494ec3d90b70325d9c359f80cb3f38a --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=05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-04 03:15:04,698 truncating revision to first 12 chars 2017-09-04 03:15:04,698 Setting DEBUG logging. 2017-09-04 03:15:04,698 attempt 1/10 2017-09-04 03:15:04,698 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/632e42dca494?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-04 03:15:05,242 unpacking tar archive at: mozilla-central-632e42dca494/testing/mozharness/ program finished with exit code 0 elapsedTime=0.956312 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:05.598112) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:05.598446) ========= script_repo_revision: 632e42dca494ec3d90b70325d9c359f80cb3f38a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:05.598849) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:05.599116) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:15:05.612378) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-09-04 03:15:05.612766) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:15:05 INFO - MultiFileLogger online at 20170904 03:15:05 in /builds/slave/test 03:15:05 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 03:15:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:15:05 INFO - {'append_to_log': False, 03:15:05 INFO - 'base_work_dir': '/builds/slave/test', 03:15:05 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:15:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:15:05 INFO - 'branch': 'Firefox-Non-PGO', 03:15:05 INFO - 'buildbot_json_path': 'buildprops.json', 03:15:05 INFO - 'code_coverage': False, 03:15:05 INFO - 'config_files': ('talos/linux_config.py',), 03:15:05 INFO - 'default_actions': ('clobber', 03:15:05 INFO - 'read-buildbot-config', 03:15:05 INFO - 'download-and-extract', 03:15:05 INFO - 'populate-webroot', 03:15:05 INFO - 'create-virtualenv', 03:15:05 INFO - 'install', 03:15:05 INFO - 'setup-mitmproxy', 03:15:05 INFO - 'run-tests'), 03:15:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:15:05 INFO - 'disable_ccov_upload': False, 03:15:05 INFO - 'disable_stylo': False, 03:15:05 INFO - 'download_minidump_stackwalk': True, 03:15:05 INFO - 'download_symbols': 'ondemand', 03:15:05 INFO - 'enable_stylo': False, 03:15:05 INFO - 'enable_webrender': False, 03:15:05 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:15:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:15:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:15:05 INFO - 'gecko_profile': False, 03:15:05 INFO - 'gecko_profile_interval': 0, 03:15:05 INFO - 'installer_path': 'installer.exe', 03:15:05 INFO - 'log_level': 'info', 03:15:05 INFO - 'log_name': 'talos', 03:15:05 INFO - 'log_to_console': True, 03:15:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:15:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:15:05 INFO - 'opt_config_files': (), 03:15:05 INFO - 'pip_index': False, 03:15:05 INFO - 'suite': 'dromaeojs-e10s', 03:15:05 INFO - 'system_bits': '32', 03:15:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:15:05 INFO - 'title': 'talos-linux64-ix-069', 03:15:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:15:05 INFO - 'use_talos_json': True, 03:15:05 INFO - 'verify': 'False', 03:15:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:15:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:15:05 INFO - 'work_dir': 'build'} 03:15:05 INFO - [mozharness: 2017-09-04 10:15:05.947640Z] Running clobber step. 03:15:05 INFO - Running pre-action listener: _resource_record_pre_action 03:15:05 INFO - Running main action method: clobber 03:15:05 INFO - rmtree: /builds/slave/test/build 03:15:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:15:06 INFO - Running post-action listener: _resource_record_post_action 03:15:06 INFO - [mozharness: 2017-09-04 10:15:06.924954Z] Finished clobber step (success) 03:15:06 INFO - [mozharness: 2017-09-04 10:15:06.925052Z] Running read-buildbot-config step. 03:15:06 INFO - Running pre-action listener: _resource_record_pre_action 03:15:06 INFO - Running main action method: read_buildbot_config 03:15:06 INFO - Using buildbot properties: 03:15:06 INFO - { 03:15:06 INFO - "project": "", 03:15:06 INFO - "product": "firefox", 03:15:06 INFO - "who": "archaeopteryx@coole-files.de", 03:15:06 INFO - "installer_path": "public/build/target.tar.bz2", 03:15:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:15:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:15:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:15:06 INFO - "stage_platform": "linux64", 03:15:06 INFO - "basedir": "/builds/slave/test", 03:15:06 INFO - "buildnumber": 808, 03:15:06 INFO - "platform": "ubuntu64_hw", 03:15:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:15:06 INFO - "slavebuilddir": "test", 03:15:06 INFO - "taskId": "PREOOBOWTailwRSWd482gQ", 03:15:06 INFO - "branch": "mozilla-central", 03:15:06 INFO - "script_repo_revision": "production", 03:15:06 INFO - "revision": "632e42dca494ec3d90b70325d9c359f80cb3f38a", 03:15:06 INFO - "slavename": "talos-linux64-ix-069", 03:15:06 INFO - "repo_path": "mozilla-central" 03:15:06 INFO - } 03:15:06 INFO - Finding installer, test and symbols from parent task. 03:15:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PREOOBOWTailwRSWd482gQ'}, attempt #1 03:15:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PREOOBOWTailwRSWd482gQ'}, attempt #1 03:15:07 INFO - Task dependencies: ZH0GnAa4QqyzRlNalh5SXw 03:15:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw'}, attempt #1 03:15:08 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:15:08 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json 03:15:08 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip 03:15:08 INFO - Running post-action listener: _resource_record_post_action 03:15:08 INFO - [mozharness: 2017-09-04 10:15:08.422164Z] Finished read-buildbot-config step (success) 03:15:08 INFO - [mozharness: 2017-09-04 10:15:08.422381Z] Running download-and-extract step. 03:15:08 INFO - Running pre-action listener: _resource_record_pre_action 03:15:08 INFO - Running main action method: download_and_extract 03:15:08 INFO - mkdir: /builds/slave/test/build/tests 03:15:08 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')]} 03:15:08 INFO - trying https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json 03:15:08 INFO - Downloading https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:15:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:15:08 INFO - Downloaded 989 bytes. 03:15:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:15:08 INFO - Using the following test package requirements: 03:15:08 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:15:08 INFO - u'common': [u'target.common.tests.zip'], 03:15:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:15:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:15:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:15:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:15:08 INFO - u'mozbase': [u'target.common.tests.zip'], 03:15:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:15:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:15:08 INFO - u'web-platform': [u'target.common.tests.zip', 03:15:08 INFO - u'target.web-platform.tests.tar.gz'], 03:15:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:15:08 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:15:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip 03:15:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:15:08 INFO - Fetch https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip into memory 03:15:10 INFO - Content-Length response header: 55022446 03:15:10 INFO - Bytes received: 55022446 03:15:22 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:15:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip 03:15:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:15:22 INFO - Fetch https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.common.tests.zip into memory 03:15:23 INFO - Content-Length response header: 55022446 03:15:23 INFO - Bytes received: 55022446 03:15:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.talos.tests.zip 03:15:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:15:36 INFO - Fetch https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.talos.tests.zip into memory 03:15:36 INFO - Content-Length response header: 14496650 03:15:36 INFO - Bytes received: 14496650 03:15:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:37 INFO - trying https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:15:37 INFO - Downloading https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:15:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:15:38 INFO - Downloaded 62725116 bytes. 03:15:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:15:38 INFO - mkdir: /builds/slave/test/properties 03:15:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:15:38 INFO - Writing to file /builds/slave/test/properties/build_url 03:15:38 INFO - Contents: 03:15:38 INFO - build_url:https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 03:15:38 INFO - Running post-action listener: _resource_record_post_action 03:15:38 INFO - Running post-action listener: find_tests_for_verification 03:15:38 INFO - Running post-action listener: set_extra_try_arguments 03:15:38 INFO - [mozharness: 2017-09-04 10:15:38.344086Z] Finished download-and-extract step (success) 03:15:38 INFO - [mozharness: 2017-09-04 10:15:38.344192Z] Running populate-webroot step. 03:15:38 INFO - Running pre-action listener: _resource_record_pre_action 03:15:38 INFO - Running main action method: populate_webroot 03:15:38 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:15:38 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:15:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:15:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:15:38 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:15:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:15:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:15:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:38 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:15:38 INFO - 'talos_options': ['--enable-stylo'], 03:15:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:15:38 INFO - 'tests': ['damp', 'tps']}, 03:15:38 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:15:38 INFO - 'talos_options': ['--enable-stylo'], 03:15:38 INFO - 'tests': ['damp', 'tps']}, 03:15:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:15:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:15:38 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:15:38 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:15:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:15:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:15:38 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:15:38 INFO - 'talos_options': ['--enable-stylo'], 03:15:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:15:38 INFO - 'other-e10s': {'tests': ['a11yr', 03:15:38 INFO - 'ts_paint', 03:15:38 INFO - 'tpaint', 03:15:38 INFO - 'sessionrestore', 03:15:38 INFO - 'sessionrestore_many_windows', 03:15:38 INFO - 'sessionrestore_no_auto_restore', 03:15:38 INFO - 'tabpaint']}, 03:15:38 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:15:38 INFO - 'tests': ['a11yr', 03:15:38 INFO - 'ts_paint', 03:15:38 INFO - 'tpaint', 03:15:38 INFO - 'sessionrestore', 03:15:38 INFO - 'sessionrestore_many_windows', 03:15:38 INFO - 'sessionrestore_no_auto_restore', 03:15:38 INFO - 'tabpaint']}, 03:15:38 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:15:38 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:15:38 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:15:38 INFO - 'tests': ['bloom_basic_singleton']}, 03:15:38 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:15:38 INFO - 'tests': ['bloom_basic']}, 03:15:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:15:38 INFO - 'tsvgr_opacity', 03:15:38 INFO - 'tart', 03:15:38 INFO - 'tscrollx', 03:15:38 INFO - 'tsvg_static']}, 03:15:38 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:15:38 INFO - 'tests': ['tsvgx', 03:15:38 INFO - 'tsvgr_opacity', 03:15:38 INFO - 'tart', 03:15:38 INFO - 'tscrollx', 03:15:38 INFO - 'tsvg_static']}, 03:15:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:15:38 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:15:38 INFO - 'talos_options': ['--enable-stylo'], 03:15:38 INFO - 'tests': ['tp5o']}, 03:15:38 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:15:38 INFO - 'talos_options': ['--mitmproxy', 03:15:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:15:38 INFO - '--firstNonBlankPaint'], 03:15:38 INFO - 'tests': ['tp6_google', 03:15:38 INFO - 'tp6_youtube', 03:15:38 INFO - 'tp6_amazon', 03:15:38 INFO - 'tp6_facebook']}, 03:15:38 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:15:38 INFO - 'talos_options': ['--enable-stylo', 03:15:38 INFO - '--mitmproxy', 03:15:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:15:38 INFO - '--firstNonBlankPaint'], 03:15:38 INFO - 'tests': ['tp6_google', 03:15:38 INFO - 'tp6_youtube', 03:15:38 INFO - 'tp6_amazon', 03:15:38 INFO - 'tp6_facebook']}, 03:15:38 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:15:38 INFO - 'talos_options': ['--enable-stylo', 03:15:38 INFO - '--stylo-threads=1', 03:15:38 INFO - '--mitmproxy', 03:15:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:15:38 INFO - '--firstNonBlankPaint'], 03:15:38 INFO - 'tests': ['tp6_google', 03:15:38 INFO - 'tp6_youtube', 03:15:38 INFO - 'tp6_amazon', 03:15:38 INFO - 'tp6_facebook']}, 03:15:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:15:38 INFO - 'talos_options': ['--xperf_path', 03:15:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:38 INFO - 'tests': ['tp5n']}, 03:15:38 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:15:38 INFO - 'talos_options': ['--enable-stylo', 03:15:38 INFO - '--xperf_path', 03:15:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:38 INFO - 'tests': ['tp5n']}}} 03:15:38 INFO - Running post-action listener: _resource_record_post_action 03:15:38 INFO - [mozharness: 2017-09-04 10:15:38.361434Z] Finished populate-webroot step (success) 03:15:38 INFO - [mozharness: 2017-09-04 10:15:38.361522Z] Running create-virtualenv step. 03:15:38 INFO - Running pre-action listener: _resource_record_pre_action 03:15:38 INFO - Running main action method: create_virtualenv 03:15:38 INFO - Creating virtualenv /builds/slave/test/build/venv 03:15:38 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:15:38 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:15:38 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:15:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:15:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:15:41 INFO - Installing distribute.............................................................................................................................................................................................done. 03:15:41 INFO - Installing pip...............done. 03:15:41 INFO - Return code: 0 03:15:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:15:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:15:42 INFO - Reading from file tmpfile_stdout 03:15:42 INFO - Output received: 03:15:42 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:15:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:15: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')]} 03:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15: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')]} 03:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a4090>, '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 0x25a4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259ef90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', '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 03:15: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', 'pip>=1.5'] in /builds/slave/test/build 03:15: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 pip>=1.5 03:15:42 INFO - Using env: {'DISPLAY': ':0', 03:15:42 INFO - 'HOME': '/home/cltbld', 03:15:42 INFO - 'LANG': 'en_US.UTF-8', 03:15:42 INFO - 'LANGUAGE': 'en_US:en', 03:15:42 INFO - 'LOGNAME': 'cltbld', 03:15:42 INFO - 'MAIL': '/var/mail/cltbld', 03:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:42 INFO - 'MOZ_NO_REMOTE': '1', 03:15:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:42 INFO - 'NO_EM_RESTART': '1', 03:15:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:42 INFO - 'PWD': '/builds/slave/test', 03:15:42 INFO - 'SHELL': '/bin/bash', 03:15:42 INFO - 'SHLVL': '1', 03:15:42 INFO - 'TERM': 'linux', 03:15:42 INFO - 'TMOUT': '86400', 03:15:42 INFO - 'USER': 'cltbld', 03:15:42 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:42 INFO - '_': '/tools/buildbot/bin/python'} 03:15:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:15:43 INFO - Downloading/unpacking pip>=1.5 03:15:43 INFO - Running setup.py egg_info for package pip 03:15:43 INFO - warning: no files found matching 'pip/cacert.pem' 03:15:43 INFO - warning: no files found matching '*.html' under directory 'docs' 03:15:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:15:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:15:43 INFO - Installing collected packages: pip 03:15:43 INFO - Found existing installation: pip 1.1 03:15:43 INFO - Uninstalling pip: 03:15:43 INFO - Successfully uninstalled pip 03:15:43 INFO - Running setup.py install for pip 03:15:43 INFO - warning: no files found matching 'pip/cacert.pem' 03:15:43 INFO - warning: no files found matching '*.html' under directory 'docs' 03:15:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:15:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:15:43 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:15:43 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:15:43 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:15:43 INFO - Successfully installed pip 03:15:43 INFO - Cleaning up... 03:15:43 INFO - Return code: 0 03:15:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:43 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 0x22341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a4090>, '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 0x25a4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259ef90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', '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 03:15:43 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 03:15:43 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 03:15:43 INFO - Using env: {'DISPLAY': ':0', 03:15:43 INFO - 'HOME': '/home/cltbld', 03:15:43 INFO - 'LANG': 'en_US.UTF-8', 03:15:43 INFO - 'LANGUAGE': 'en_US:en', 03:15:43 INFO - 'LOGNAME': 'cltbld', 03:15:43 INFO - 'MAIL': '/var/mail/cltbld', 03:15:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:43 INFO - 'MOZ_NO_REMOTE': '1', 03:15:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:43 INFO - 'NO_EM_RESTART': '1', 03:15:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:43 INFO - 'PWD': '/builds/slave/test', 03:15:43 INFO - 'SHELL': '/bin/bash', 03:15:43 INFO - 'SHLVL': '1', 03:15:43 INFO - 'TERM': 'linux', 03:15:43 INFO - 'TMOUT': '86400', 03:15:43 INFO - 'USER': 'cltbld', 03:15:43 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:15:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:43 INFO - '_': '/tools/buildbot/bin/python'} 03:15:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:43 INFO - Downloading/unpacking psutil>=3.1.1 03:15:43 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 03:15:43 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 03:15:43 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 03:15:43 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 03:15:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:15:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:15:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:15:44 INFO - Installing collected packages: psutil 03:15:44 INFO - Running setup.py install for psutil 03:15:44 INFO - building 'psutil._psutil_linux' extension 03:15:44 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 03:15:45 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 03:15:45 INFO - building 'psutil._psutil_posix' extension 03:15:45 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 03:15:45 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 03:15:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:15:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:15:45 INFO - Successfully installed psutil 03:15:45 INFO - Cleaning up... 03:15:45 INFO - Return code: 0 03:15:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:15: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')]} 03:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15: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')]} 03:15:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a4090>, '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 0x25a4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259ef90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', '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 03:15: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:15: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 mozsystemmonitor==0.3 03:15:45 INFO - Using env: {'DISPLAY': ':0', 03:15:45 INFO - 'HOME': '/home/cltbld', 03:15:46 INFO - 'LANG': 'en_US.UTF-8', 03:15:46 INFO - 'LANGUAGE': 'en_US:en', 03:15:46 INFO - 'LOGNAME': 'cltbld', 03:15:46 INFO - 'MAIL': '/var/mail/cltbld', 03:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:46 INFO - 'MOZ_NO_REMOTE': '1', 03:15:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:46 INFO - 'NO_EM_RESTART': '1', 03:15:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:46 INFO - 'PWD': '/builds/slave/test', 03:15:46 INFO - 'SHELL': '/bin/bash', 03:15:46 INFO - 'SHLVL': '1', 03:15:46 INFO - 'TERM': 'linux', 03:15:46 INFO - 'TMOUT': '86400', 03:15:46 INFO - 'USER': 'cltbld', 03:15:46 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:46 INFO - '_': '/tools/buildbot/bin/python'} 03:15:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:15: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 03:15: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 03:15: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 03:15: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 03:15:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:15:47 INFO - Installing collected packages: mozsystemmonitor 03:15:47 INFO - Running setup.py install for mozsystemmonitor 03:15:47 INFO - Successfully installed mozsystemmonitor 03:15:47 INFO - Cleaning up... 03:15:47 INFO - Return code: 0 03:15:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:15: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')]} 03:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15: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')]} 03:15:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15: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', '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 0x22341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a4090>, '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 0x25a4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259ef90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', '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 03:15: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:15: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 jsonschema==2.5.1 03:15:47 INFO - Using env: {'DISPLAY': ':0', 03:15:47 INFO - 'HOME': '/home/cltbld', 03:15:47 INFO - 'LANG': 'en_US.UTF-8', 03:15:47 INFO - 'LANGUAGE': 'en_US:en', 03:15:47 INFO - 'LOGNAME': 'cltbld', 03:15:47 INFO - 'MAIL': '/var/mail/cltbld', 03:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:47 INFO - 'MOZ_NO_REMOTE': '1', 03:15:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:47 INFO - 'NO_EM_RESTART': '1', 03:15:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:47 INFO - 'PWD': '/builds/slave/test', 03:15:47 INFO - 'SHELL': '/bin/bash', 03:15:47 INFO - 'SHLVL': '1', 03:15:47 INFO - 'TERM': 'linux', 03:15:47 INFO - 'TMOUT': '86400', 03:15:47 INFO - 'USER': 'cltbld', 03:15:47 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:47 INFO - '_': '/tools/buildbot/bin/python'} 03:15:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:47 INFO - Downloading/unpacking jsonschema==2.5.1 03:15: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 03:15: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 03:15: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 03:15: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 03:15:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:15:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:15:48 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 03:15:48 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 03:15:48 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 03:15:48 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 03:15:48 INFO - Downloading functools32-3.2.3-2.tar.gz 03:15:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:15:49 INFO - warning: no files found matching '*.txt' 03:15:49 INFO - no previously-included directories found matching 'build' 03:15:49 INFO - no previously-included directories found matching 'dist' 03:15:49 INFO - no previously-included directories found matching '.git*' 03:15:49 INFO - Installing collected packages: jsonschema, functools32 03:15:49 INFO - Running setup.py install for functools32 03:15:49 INFO - warning: no files found matching '*.txt' 03:15:49 INFO - no previously-included directories found matching 'build' 03:15:49 INFO - no previously-included directories found matching 'dist' 03:15:49 INFO - no previously-included directories found matching '.git*' 03:15:49 INFO - Successfully installed jsonschema functools32 03:15:49 INFO - Cleaning up... 03:15:49 INFO - Return code: 0 03:15:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:49 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 0x22341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a4090>, '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 0x25a4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259ef90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', '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 03:15:49 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 03:15:49 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 03:15:49 INFO - Using env: {'DISPLAY': ':0', 03:15:49 INFO - 'HOME': '/home/cltbld', 03:15:49 INFO - 'LANG': 'en_US.UTF-8', 03:15:49 INFO - 'LANGUAGE': 'en_US:en', 03:15:49 INFO - 'LOGNAME': 'cltbld', 03:15:49 INFO - 'MAIL': '/var/mail/cltbld', 03:15:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:49 INFO - 'MOZ_NO_REMOTE': '1', 03:15:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:49 INFO - 'NO_EM_RESTART': '1', 03:15:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:49 INFO - 'PWD': '/builds/slave/test', 03:15:49 INFO - 'SHELL': '/bin/bash', 03:15:49 INFO - 'SHLVL': '1', 03:15:49 INFO - 'TERM': 'linux', 03:15:49 INFO - 'TMOUT': '86400', 03:15:49 INFO - 'USER': 'cltbld', 03:15:49 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:15:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:49 INFO - '_': '/tools/buildbot/bin/python'} 03:15:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:15:49 INFO - Cleaning up... 03:15:49 INFO - Return code: 0 03:15:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:49 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 0x22341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a4090>, '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 0x25a4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259ef90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', '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 03:15:49 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 03:15:49 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 03:15:49 INFO - Using env: {'DISPLAY': ':0', 03:15:49 INFO - 'HOME': '/home/cltbld', 03:15:49 INFO - 'LANG': 'en_US.UTF-8', 03:15:49 INFO - 'LANGUAGE': 'en_US:en', 03:15:49 INFO - 'LOGNAME': 'cltbld', 03:15:49 INFO - 'MAIL': '/var/mail/cltbld', 03:15:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:49 INFO - 'MOZ_NO_REMOTE': '1', 03:15:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:49 INFO - 'NO_EM_RESTART': '1', 03:15:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:49 INFO - 'PWD': '/builds/slave/test', 03:15:49 INFO - 'SHELL': '/bin/bash', 03:15:49 INFO - 'SHLVL': '1', 03:15:49 INFO - 'TERM': 'linux', 03:15:49 INFO - 'TMOUT': '86400', 03:15:49 INFO - 'USER': 'cltbld', 03:15:49 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:15:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:49 INFO - '_': '/tools/buildbot/bin/python'} 03:15:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:49 INFO - Downloading/unpacking blobuploader==1.2.4 03:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:50 INFO - Downloading blobuploader-1.2.4.tar.gz 03:15:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:15:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:15:50 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 03:15:50 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 03:15:50 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 03:15:50 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 03:15:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:15:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:15: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 03:15: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 03:15: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 03:15: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 03:15:51 INFO - Downloading docopt-0.6.1.tar.gz 03:15:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:15:51 INFO - Installing collected packages: blobuploader, requests, docopt 03:15:51 INFO - Running setup.py install for blobuploader 03:15:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:15:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:15:51 INFO - Running setup.py install for requests 03:15:51 INFO - Running setup.py install for docopt 03:15:51 INFO - Successfully installed blobuploader requests docopt 03:15:51 INFO - Cleaning up... 03:15:51 INFO - Return code: 0 03:15:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:51 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 0x22341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a4090>, '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 0x25a4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259ef90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', '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 03:15:51 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 03:15:51 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 03:15:51 INFO - Using env: {'DISPLAY': ':0', 03:15:51 INFO - 'HOME': '/home/cltbld', 03:15:51 INFO - 'LANG': 'en_US.UTF-8', 03:15:51 INFO - 'LANGUAGE': 'en_US:en', 03:15:51 INFO - 'LOGNAME': 'cltbld', 03:15:51 INFO - 'MAIL': '/var/mail/cltbld', 03:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:51 INFO - 'MOZ_NO_REMOTE': '1', 03:15:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:51 INFO - 'NO_EM_RESTART': '1', 03:15:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:51 INFO - 'PWD': '/builds/slave/test', 03:15:51 INFO - 'SHELL': '/bin/bash', 03:15:51 INFO - 'SHLVL': '1', 03:15:51 INFO - 'TERM': 'linux', 03:15:51 INFO - 'TMOUT': '86400', 03:15:51 INFO - 'USER': 'cltbld', 03:15:51 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:51 INFO - '_': '/tools/buildbot/bin/python'} 03:15:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:15:52 INFO - Running setup.py (path:/tmp/pip-T61SAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:15:52 INFO - Running setup.py (path:/tmp/pip-iTOBdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:15:52 INFO - Running setup.py (path:/tmp/pip-AVe8mC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:15:52 INFO - Running setup.py (path:/tmp/pip-cJ3s_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:15:52 INFO - Running setup.py (path:/tmp/pip-9Cs89p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:15:52 INFO - Running setup.py (path:/tmp/pip-tQsrvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:15:52 INFO - Running setup.py (path:/tmp/pip-t3mnrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:15:52 INFO - Running setup.py (path:/tmp/pip-MSlXZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:15:52 INFO - Running setup.py (path:/tmp/pip-369WN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:15:52 INFO - Running setup.py (path:/tmp/pip-yXucx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:15:52 INFO - Running setup.py (path:/tmp/pip-CLeQ_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:15:52 INFO - Running setup.py (path:/tmp/pip-49XLXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:15:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:15:52 INFO - Running setup.py (path:/tmp/pip-9yGeB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:15:53 INFO - Running setup.py (path:/tmp/pip-sVKo4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:53 INFO - Running setup.py (path:/tmp/pip-3BtVWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:15:53 INFO - Running setup.py (path:/tmp/pip-47PQ_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:15:53 INFO - Running setup.py (path:/tmp/pip-7m3RRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:15:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:15:53 INFO - Running setup.py install for manifestparser 03:15:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Running setup.py install for mozcrash 03:15:53 INFO - Running setup.py install for mozdebug 03:15:53 INFO - Running setup.py install for mozdevice 03:15:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Running setup.py install for mozfile 03:15:53 INFO - Running setup.py install for mozhttpd 03:15:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:15:53 INFO - Running setup.py install for mozinfo 03:15:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Running setup.py install for mozInstall 03:15:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Running setup.py install for mozleak 03:15:54 INFO - Running setup.py install for mozlog 03:15:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Running setup.py install for moznetwork 03:15:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Running setup.py install for mozprocess 03:15:54 INFO - Running setup.py install for mozprofile 03:15:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Running setup.py install for mozrunner 03:15:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:15:54 INFO - Running setup.py install for mozscreenshot 03:15:54 INFO - Running setup.py install for moztest 03:15:54 INFO - Running setup.py install for mozversion 03:15:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:15:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:15:55 INFO - Cleaning up... 03:15:55 INFO - Return code: 0 03:15:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:55 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 0x22341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a4090>, '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 0x25a4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259ef90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', '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 03:15:55 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 03:15:55 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 03:15:55 INFO - Using env: {'DISPLAY': ':0', 03:15:55 INFO - 'HOME': '/home/cltbld', 03:15:55 INFO - 'LANG': 'en_US.UTF-8', 03:15:55 INFO - 'LANGUAGE': 'en_US:en', 03:15:55 INFO - 'LOGNAME': 'cltbld', 03:15:55 INFO - 'MAIL': '/var/mail/cltbld', 03:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:55 INFO - 'MOZ_NO_REMOTE': '1', 03:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:55 INFO - 'NO_EM_RESTART': '1', 03:15:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:55 INFO - 'PWD': '/builds/slave/test', 03:15:55 INFO - 'SHELL': '/bin/bash', 03:15:55 INFO - 'SHLVL': '1', 03:15:55 INFO - 'TERM': 'linux', 03:15:55 INFO - 'TMOUT': '86400', 03:15:55 INFO - 'USER': 'cltbld', 03:15:55 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:55 INFO - '_': '/tools/buildbot/bin/python'} 03:15:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:15:55 INFO - Running setup.py (path:/tmp/pip-nbfzoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:15:55 INFO - Running setup.py (path:/tmp/pip-_i3fiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:15:55 INFO - Running setup.py (path:/tmp/pip-dZwSg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:15:55 INFO - Running setup.py (path:/tmp/pip-bZE2fB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:15:55 INFO - Running setup.py (path:/tmp/pip-vtbRX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:15:55 INFO - Running setup.py (path:/tmp/pip-GOVqV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:15:55 INFO - Running setup.py (path:/tmp/pip-B8H9A9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:15:55 INFO - Running setup.py (path:/tmp/pip-mMsYo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:15:55 INFO - Running setup.py (path:/tmp/pip-PVb2hd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:15:55 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)) 03:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:15:56 INFO - Running setup.py (path:/tmp/pip-ctR5W_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:15:56 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)) 03:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:15:56 INFO - Running setup.py (path:/tmp/pip-EgCvaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:15:56 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)) 03:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:15:56 INFO - Running setup.py (path:/tmp/pip-XxFzV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:15:56 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)) 03:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:15:56 INFO - Running setup.py (path:/tmp/pip-V5ayIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:15:56 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)) 03:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:15:56 INFO - Running setup.py (path:/tmp/pip-CUZHFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:15:56 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)) 03:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:56 INFO - Running setup.py (path:/tmp/pip-QhNMNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:15:56 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)) 03:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:15:56 INFO - Running setup.py (path:/tmp/pip-WF9rBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:15:56 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)) 03:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:15:56 INFO - Running setup.py (path:/tmp/pip-cndEX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:15:56 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)) 03:15:56 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)) 03:15:56 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)) 03:15:56 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)) 03:15:56 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)) 03:15:56 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)) 03:15:56 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)) 03:15:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:57 INFO - Downloading blessings-1.6.tar.gz 03:15:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:15:57 INFO - Installing collected packages: blessings 03:15:57 INFO - Running setup.py install for blessings 03:15:57 INFO - Successfully installed blessings 03:15:57 INFO - Cleaning up... 03:15:57 INFO - Return code: 0 03:15:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:15:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:15:58 INFO - Reading from file tmpfile_stdout 03:15:58 INFO - Current package versions: 03:15:58 INFO - argparse == 1.2.1 03:15:58 INFO - blessings == 1.6 03:15:58 INFO - blobuploader == 1.2.4 03:15:58 INFO - docopt == 0.6.1 03:15:58 INFO - functools32 == 3.2.3-2 03:15:58 INFO - jsonschema == 2.5.1 03:15:58 INFO - manifestparser == 1.1 03:15:58 INFO - mozInstall == 1.13 03:15:58 INFO - mozcrash == 1.0 03:15:58 INFO - mozdebug == 0.1 03:15:58 INFO - mozdevice == 0.51 03:15:58 INFO - mozfile == 1.2 03:15:58 INFO - mozhttpd == 0.7 03:15:58 INFO - mozinfo == 0.10 03:15:58 INFO - mozleak == 0.1 03:15:58 INFO - mozlog == 3.5 03:15:58 INFO - moznetwork == 0.27 03:15:58 INFO - mozprocess == 0.25 03:15:58 INFO - mozprofile == 0.28 03:15:58 INFO - mozrunner == 6.13 03:15:58 INFO - mozscreenshot == 0.1 03:15:58 INFO - mozsystemmonitor == 0.3 03:15:58 INFO - moztest == 0.8 03:15:58 INFO - mozversion == 1.4 03:15:58 INFO - psutil == 3.1.1 03:15:58 INFO - requests == 1.2.3 03:15:58 INFO - wsgiref == 0.1.2 03:15:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:15:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:58 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 0x22341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a4090>, '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 0x25a4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259ef90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', '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 03:15:58 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 03:15:58 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 03:15:58 INFO - Using env: {'DISPLAY': ':0', 03:15:58 INFO - 'HOME': '/home/cltbld', 03:15:58 INFO - 'LANG': 'en_US.UTF-8', 03:15:58 INFO - 'LANGUAGE': 'en_US:en', 03:15:58 INFO - 'LOGNAME': 'cltbld', 03:15:58 INFO - 'MAIL': '/var/mail/cltbld', 03:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:58 INFO - 'MOZ_NO_REMOTE': '1', 03:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:58 INFO - 'NO_EM_RESTART': '1', 03:15:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:58 INFO - 'PWD': '/builds/slave/test', 03:15:58 INFO - 'SHELL': '/bin/bash', 03:15:58 INFO - 'SHLVL': '1', 03:15:58 INFO - 'TERM': 'linux', 03:15:58 INFO - 'TMOUT': '86400', 03:15:58 INFO - 'USER': 'cltbld', 03:15:58 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:58 INFO - '_': '/tools/buildbot/bin/python'} 03:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:58 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)) 03:15:58 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)) 03:15:58 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)) 03:15:58 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)) 03:15:58 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)) 03:15:58 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)) 03:15:58 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)) 03:15:58 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)) 03:15:58 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)) 03:15:58 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:15:59 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)) 03:15:59 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)) 03:15:59 INFO - Installing collected packages: simplejson 03:15:59 INFO - Running setup.py install for simplejson 03:15:59 INFO - building 'simplejson._speedups' extension 03:15:59 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 03:16:00 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 03:16:00 INFO - Successfully installed simplejson 03:16:00 INFO - Cleaning up... 03:16:00 INFO - Return code: 0 03:16:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:00 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 0x22341f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23fda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25a0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a4090>, '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 0x25a4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259ef90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', '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 03:16:00 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 03:16:00 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 03:16:00 INFO - Using env: {'DISPLAY': ':0', 03:16:00 INFO - 'HOME': '/home/cltbld', 03:16:00 INFO - 'LANG': 'en_US.UTF-8', 03:16:00 INFO - 'LANGUAGE': 'en_US:en', 03:16:00 INFO - 'LOGNAME': 'cltbld', 03:16:00 INFO - 'MAIL': '/var/mail/cltbld', 03:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:00 INFO - 'MOZ_NO_REMOTE': '1', 03:16:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:00 INFO - 'NO_EM_RESTART': '1', 03:16:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:00 INFO - 'PWD': '/builds/slave/test', 03:16:00 INFO - 'SHELL': '/bin/bash', 03:16:00 INFO - 'SHLVL': '1', 03:16:00 INFO - 'TERM': 'linux', 03:16:00 INFO - 'TMOUT': '86400', 03:16:00 INFO - 'USER': 'cltbld', 03:16:00 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:00 INFO - '_': '/tools/buildbot/bin/python'} 03:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:16:00 INFO - Cleaning up... 03:16:00 INFO - Return code: 0 03:16:00 INFO - Running post-action listener: _resource_record_post_action 03:16:00 INFO - Running post-action listener: _start_resource_monitoring 03:16:00 INFO - Starting resource monitoring. 03:16:00 INFO - [mozharness: 2017-09-04 10:16:00.902841Z] Finished create-virtualenv step (success) 03:16:00 INFO - [mozharness: 2017-09-04 10:16:00.903784Z] Running install step. 03:16:00 INFO - Running pre-action listener: _resource_record_pre_action 03:16:00 INFO - Running main action method: install 03:16:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:01 INFO - Reading from file tmpfile_stdout 03:16:01 INFO - Detecting whether we're running mozinstall >=1.0... 03:16:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:16:01 INFO - Reading from file tmpfile_stdout 03:16:01 INFO - Output received: 03:16:01 INFO - Usage: mozinstall [options] installer 03:16:01 INFO - Options: 03:16:01 INFO - -h, --help show this help message and exit 03:16:01 INFO - -d DEST, --destination=DEST 03:16:01 INFO - Directory to install application into. [default: 03:16:01 INFO - "/builds/slave/test"] 03:16:01 INFO - --app=APP Application being installed. [default: firefox] 03:16:01 INFO - mkdir: /builds/slave/test/build/application 03:16:01 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'] 03:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:16:17 INFO - Reading from file tmpfile_stdout 03:16:17 INFO - Output received: 03:16:17 INFO - /builds/slave/test/build/application/firefox/firefox 03:16:17 INFO - Running post-action listener: _resource_record_post_action 03:16:17 INFO - [mozharness: 2017-09-04 10:16:17.768133Z] Finished install step (success) 03:16:17 INFO - [mozharness: 2017-09-04 10:16:17.768384Z] Running setup-mitmproxy step. 03:16:17 INFO - Running pre-action listener: _resource_record_pre_action 03:16:17 INFO - Running main action method: setup_mitmproxy 03:16:17 INFO - Skipping: mitmproxy is not required 03:16:17 INFO - Running post-action listener: _resource_record_post_action 03:16:17 INFO - [mozharness: 2017-09-04 10:16:17.769570Z] Finished setup-mitmproxy step (success) 03:16:17 INFO - [mozharness: 2017-09-04 10:16:17.769789Z] Running run-tests step. 03:16:17 INFO - Running pre-action listener: _resource_record_pre_action 03:16:17 INFO - Running pre-action listener: _set_gcov_prefix 03:16:17 INFO - Running main action method: run_tests 03:16:17 WARNING - Try message not found. 03:16:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:16:17 INFO - Python 2.7.3 03:16:17 INFO - Return code: 0 03:16:17 INFO - grabbing minidump binary from tooltool 03:16:17 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')]} 03:16:17 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 0x25a4090>, '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 0x25a4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259ef90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:16:17 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 03:16:17 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 03:16:17 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 03:16:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:16:17 INFO - Return code: 0 03:16:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:16:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:16:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:16:17 INFO - ENV: RUST_BACKTRACE is now full 03:16:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:16:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:16:17 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/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 03:16:17 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/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 03:16:17 INFO - Using env: {'DISPLAY': ':0', 03:16:17 INFO - 'HOME': '/home/cltbld', 03:16:17 INFO - 'LANG': 'en_US.UTF-8', 03:16:17 INFO - 'LANGUAGE': 'en_US:en', 03:16:17 INFO - 'LOGNAME': 'cltbld', 03:16:17 INFO - 'MAIL': '/var/mail/cltbld', 03:16:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:16:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:17 INFO - 'MOZ_NO_REMOTE': '1', 03:16:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:17 INFO - 'NO_EM_RESTART': '1', 03:16:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:17 INFO - 'PWD': '/builds/slave/test', 03:16:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:16:17 INFO - 'RUST_BACKTRACE': 'full', 03:16:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:16:17 INFO - 'SHELL': '/bin/bash', 03:16:17 INFO - 'SHLVL': '1', 03:16:17 INFO - 'TERM': 'linux', 03:16:17 INFO - 'TMOUT': '86400', 03:16:17 INFO - 'USER': 'cltbld', 03:16:17 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:17 INFO - '_': '/tools/buildbot/bin/python'} 03:16:17 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/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 03:16:18 INFO - mozversion application_buildid: 20170904091258 03:16:18 INFO - mozversion application_changeset: 632e42dca494ec3d90b70325d9c359f80cb3f38a 03:16:18 INFO - mozversion application_display_name: Nightly 03:16:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:16:18 INFO - mozversion application_name: Firefox 03:16:18 INFO - mozversion application_remotingname: firefox 03:16:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:16:18 INFO - mozversion application_vendor: Mozilla 03:16:18 INFO - mozversion application_version: 57.0a1 03:16:18 INFO - mozversion platform_buildid: 20170904091258 03:16:18 INFO - mozversion platform_changeset: 632e42dca494ec3d90b70325d9c359f80cb3f38a 03:16:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:16:18 INFO - mozversion platform_version: 57.0a1 03:16:18 INFO - using testdate: 1504520178 03:16:18 INFO - actual date: 1504520178 03:16:18 INFO - starting webserver on 'localhost:53962' 03:16:18 INFO - SUITE-START | Running 2 tests 03:16:18 INFO - TEST-START | dromaeo_css 03:16:18 INFO - Initialising browser for dromaeo_css test... 03:16:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53962/getInfo.html -profile /tmp/tmp4CfavI/profile 03:16:18 INFO - TEST-INFO | started process 3522 (/builds/slave/test/build/application/firefox/firefox http://localhost:53962/getInfo.html) 03:16:28 INFO - TEST-INFO | 3522: exit 0 03:16:28 INFO - Browser initialized. 03:16:28 INFO - Running cycle 1/1 for dromaeo_css test... 03:16:28 INFO - Using env: {'DISPLAY': ':0', 03:16:28 INFO - 'HOME': '/home/cltbld', 03:16:28 INFO - 'JSGC_DISABLE_POISONING': '1', 03:16:28 INFO - 'LANG': 'en_US.UTF-8', 03:16:28 INFO - 'LANGUAGE': 'en_US:en', 03:16:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:16:28 INFO - 'LOGNAME': 'cltbld', 03:16:28 INFO - 'MAIL': '/var/mail/cltbld', 03:16:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:16:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:16:28 INFO - 'MOZ_CRASHREPORTER': '1', 03:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:16:28 INFO - 'MOZ_NO_REMOTE': '1', 03:16:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:28 INFO - 'NO_EM_RESTART': '1', 03:16:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:28 INFO - 'PWD': '/builds/slave/test', 03:16:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:16:28 INFO - 'RUST_BACKTRACE': 'full', 03:16:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:16:28 INFO - 'SHELL': '/bin/bash', 03:16:28 INFO - 'SHLVL': '1', 03:16:28 INFO - 'TERM': 'linux', 03:16:28 INFO - 'TMOUT': '86400', 03:16:28 INFO - 'USER': 'cltbld', 03:16:28 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:28 INFO - '_': '/tools/buildbot/bin/python'} 03:16:28 INFO - TEST-INFO | started process 3710 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4CfavI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:16:29 INFO - PID 3710 | 03:16:29 INFO - PID 3710 | (/builds/slave/test/build/application/firefox/firefox:3762): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:16:29 INFO - PID 3710 | 03:16:39 INFO - PID 3710 | RSS: Main: 168452096 03:16:39 INFO - PID 3710 | 03:19:38 INFO - PID 3710 | Cycle 1(1): loaded http://localhost:53962/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53962/tests/dromaeo/cssquery-ext.html) 03:19:40 INFO - PID 3710 | RSS: Main: 170803200 03:19:40 INFO - PID 3710 | 03:22:42 INFO - PID 3710 | Cycle 1(1): loaded http://localhost:53962/tests/dromaeo/cssquery-ext.html (next: http://localhost:53962/tests/dromaeo/cssquery-jquery.html) 03:22:42 INFO - PID 3710 | RSS: Main: 167829504 03:22:42 INFO - PID 3710 | 03:25:44 INFO - PID 3710 | Cycle 1(1): loaded http://localhost:53962/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53962/tests/dromaeo/cssquery-mootools.html) 03:25:45 INFO - PID 3710 | RSS: Main: 170332160 03:25:45 INFO - PID 3710 | 03:28:47 INFO - PID 3710 | Cycle 1(1): loaded http://localhost:53962/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53962/tests/dromaeo/cssquery-prototype.html) 03:28:47 INFO - PID 3710 | RSS: Main: 168833024 03:28:47 INFO - PID 3710 | 03:31:50 INFO - PID 3710 | Cycle 1(1): loaded http://localhost:53962/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53962/tests/dromaeo/cssquery-yui.html) 03:31:51 INFO - PID 3710 | RSS: Main: 170303488 03:31:51 INFO - PID 3710 | 03:34:53 INFO - PID 3710 | Cycle 1(1): loaded http://localhost:53962/tests/dromaeo/cssquery-yui.html (next: http://localhost:53962/tests/dromaeo/cssquery-dojo.html) 03:34:53 INFO - PID 3710 | RSS: Main: 172916736 03:34:53 INFO - PID 3710 | 03:34:53 INFO - PID 3710 | __start_tp_report 03:34:53 INFO - PID 3710 | _x_x_mozilla_page_load 03:34:53 INFO - PID 3710 | _x_x_mozilla_page_load_details 03:34:53 INFO - PID 3710 | |i|pagename|runs| 03:34:53 INFO - PID 3710 | |0;dojo.html;3334.7832390894605;3371.072954937392;3378.577677790276;3386.4243078676623;3420.8631654733813;6355.71399287032;6450.129232553604;6517.087607734921;6520.087187793707;6582.275949645547;6494.707738151783;6569.474442044637;6598.307177746339;6617.503687223453;6635.170603674541;5981.1028345748055;5982.102684597324;5990.131430942511;6012.849678758044;6079.057746049347;5835.649861008353;5892.823215303548;5915.497182739472;5935.258092738408;5999.76000959961;5866.120081987693;5930.406959304057;5937.960856850055;5950.196723442323;5957.493613042897;5879.970600146972;5905.911411328833;5954.583179177459;5967.522598192134;5987.700614969234;5800.506956908668;5882.941170588282;5895.35151133375;5916.674582897939;5931.733072011727;6412.551121421502;6428.646424446653;6435.8391040223905;6571.178602674666;6580.276169621337;6899.241083480813;6996.265392133803;7085.256048114925;7100.715971361139;7116.146062472485;6853.623050732208;7038.824029399255;7083.291670832927;7176.461765367619;7178.676959536833;6392.584482008655;6486.221653401575;6494.675266236669;6580.078788969548;6621.238557565845;6962.408195303355;7222.349988501061;7324.08448943882;7334.853302933911;7347.70609175627;6823.624700641413;6832.214295355998;6856.41720453767;6860.279670634612;6876.587404755731;6980.7905762827195;6989.161300643854;6990.335918087773;6991.860162796817;6997.090378250795;6806.047153398528;6908.827279318058;6922.550034247758;6943.618101004493;6944.131983502062;6939.618320992293;7095.574265544084;7121.430285577141;7122.964385178041;7127.144742630918;5468.343798744176;5502.642328248651;5556.111022236423;5577.9163312550345;5584.274044374205;4669.743164125938;4676.462206846189;4722.716637001791;4725.503822098699;4774.212254977959;6081.969590152021;6100.664463454464;6101.450869421685;6105.572609917263;6123;6927.79216623492;6950.965245173742;7016.719331226694;7023.33278338557;7034.296570343027;5989.401059893976;6035.517158627299;6041.184440100618;6111.35830737775;6120.785772497941;5936.168936348915;5953.642781433128;5968.1942937703725;5971.164037034819;5971.850703732442;6772.11962444879;6855.108835851307;6859.965700171567;6863.553868998499;6865.794026179223;6950.93049069523;6993.860122797414;7003.229644738973;7017.2631873652535;7076.681549330189;6594.208694956636;6714;6727.360900714424;6736.191657001191;6824.112865327475;6850.177978642595;6857.897131543131;6868.038474613458;6876.002979567835;6883.242843287134;6951;6985.720571177096;7023.227444980946;7025.2272250053475;7040.8591828162125;6614.603123812587;6696.229933557704;6696.5647232929705;6712.4294434971625;6758.0876581662815;6764.425023873026;6812.8978065328065;6841.3842754152365;6859.828504287433;6862.073620061228;6711.429528489938;6718.630475323904;6802.8639427212165;6807.829804254933;6812.829679258059;6670.132882725215;6751.426128779109;6785.6946437411425;6814.8637027260165;6839.863202736017;6506.3493650634555;6510.902336464862;6517.250516190698;6588.6046837188005;6623.4038936495945 03:34:53 INFO - PID 3710 | |1;ext.html;763.7326935572551;821.347029111856;853.9231469167773;880.5201165364878;883.9160279773423;38357.232855342874;39058.4141237881;39349.60650393495;39360.21279574407;39409;36117.45823812638;36426.63573364266;36448.635513644855;36458.270834583294;36514.45228321571;6799.830004249896;6910.792676219697;6920.169579650449;6920.688569014406;6929.6881640326055;6805.625690587016;6859.828504287383;6867.656617169122;6884.0362349271145;6886.51794374394;6848.897266540981;7429.145648250439;7694.5383277003275;7732.1108072571815;7732.420068494857;7596.886046709304;7659.502132361378;7660.233976602323;7666.655000524934;7669.348105411047;7347.118345798485;7483.36391406731;7545.245475452466;7556.622168891534;7599.278068583476;36280.27439451223;36493.635063649286;36522.817385913164;36535.63464365349;36606.63393366059;3529.505869178317;3574.857005719768;3581.212133330663;3581.5881173665102;3585.6055833858254;30135.849320753474;30172.8491357544;30266.092017239516;30312.393752124834;30319;5087.949120508784;5104.132297509395;5133.30700355451;5138.511841375063;5146.639735218536;189834;190771;190998;191270;191501.04249478804;46031.5396846034;46143;46159.76920115378;46402.30396543987;46639;47885.52114478811;47908.760456197495;47921.5207847924;47963.520364795906;47981.76009120002;15126.243687815566;15238.238088095773;15330.616734581725;15359.462418815512;15438.845611543966;12511.12422130442;12631.494740210472;12653.177543459646;12725.109242352946;12758;13722.931385343209;13748.587542373745;13768.380422880995;13808;13852.653683657989;5574;5639.746211420496;5686.232358631614;5713.257276554022;5791.594588378773;978.7602037500858;998.9950050249702;1005.1154983614373;1014.2139841622801;1017.8676772019738;22171.334859954226;22172.445688857584;22205.333839984825;22432.551348972927;22492.437689057904;1090.967270981856;1105.8451816745662;1106.5076041161767;1108.0802933565121;1117.8155604325357;11641.185117041896;11875.465604047838;11885.346305953082;11985.760284794429;12038.157328987061;227018;230618;230717;231220;231852;48344.7582762091;48985.75507122442;50275;53131.468685312655;53232.20151697728;1417.3126033873564;1431.4989753586162;1435.7343891380128;1438.173050495948;1441.6467965348347;26184.08355707541;26484.205473835817;26658.06696765604;27427.862860685567;28041.158765237076;46891.531084688715;46896.531034689215;46922.29616555686;46988;47055.117795287806;196479;197854;198576;198650;198798;31630.36739265248;31658.683413165574;31693.366132676758;31932.201694957814;31992.200194995312;40020;40433.39349909695;40540;41194.794026029675;41214.38178427266;29954.25114372158;30165.396692066475;30274.394512109193;30275.848620756755;30790.846045769627;30087.3982520344;30384.5442318361;30465.847670761505;30479.238019048815;30803.383932321678;33277.667223328426;33330.333393331515;33604.49593256152;33884;33974.320513590086;29764.255893602833;29919.55120673148;29924.85037574798;29984;30239.546406803474;22839.20062797794;23180.18869339565;23475.295741127793;23752.524949500566;23946.40133996664 03:34:53 INFO - PID 3710 | |2;jquery.html;553.7729530892333;556.7522452508636;557.9135234038723;558.8658721906738;583.7519054401879;21488.677669834964;21506;22318.88840555797;22328.665070023962;22346.88826555867;21580.244691435804;21726.674099888474;21805.563888722256;21838.23566175184;21992.8900355498;6675.532712710112;6831.077804496404;6831.658417079151;6844.828879278033;6854.451643868479;6381.649009304487;6655.367740064686;6722.193336799566;6724.260331363546;6730.798076057702;6620.801375958729;6638.1370421845095;6638.236602790693;6665.366790154928;6666.566673166253;6655.767048153316;6657.866842663144;6666.866662666719;6669.833254168685;6693.062971184038;6161.322254551996;6161.599496032743;6163.044728067157;6166.599171053867;6173.41352571505;28771.28071798201;29393;30117.247068823235;31270.530942035886;31417.214569635715;2032.542677897476;2078.449210959097;2084.499720067173;2085.1971990783504;2086.5618220173783;20279.695804562944;20497.38507844767;20779.896100519552;20919.477013074644;21003.579928401345;2551.59174532074;2575.188815523104;2640.854752988585;2641.77544908683;2646.259047466693;126932.36533817364;134429;134468;134549.32725336507;134608;19132.904335478514;20540.383788486368;21314.36056918295;21601.56796864054;21966.45083872916;42712.57287427148;42723.35914961278;42787;42822;42849.78575107167;11286.54853805855;11372.147088968195;11506.137039722054;11646.359450230319;11669.416529173508;8095.2714255717265;8453.661853525913;9516.666916658021;9528.761780955532;9712;10543.94728026355;10884.564617415215;11117.27737697047;11501.670739613952;11671.790257679206;3619.2399596085115;3709.536307961505;4014.4781178447197;4187.330027195634;4234.569998301386;938.9530523473935;956.6890760502864;969.7333233360741;971.6842026341467;979.9951000244953;19553.60892782165;19641;19717.704234436496;20078.397648070582;20364;1036.2487196782424;1044.8589440425446;1057.5346847387125;1061.0874647802884;1064.3081996702206;10934.671959841218;11055.778884422267;11089.722756931142;11102.777944441228;11121.55513779456;469568;472069;479615;482401;483496;115808.42095789636;116763;117365;119899;122488.38755806164;1147.0995268714187;1172.1619042384737;1189.5658084798986;1199.196538319328;1200.3878022208562;17490.650186995936;17548.21033053541;17644.47066587953;17848.732269015716;18118.637627247645;53463.73268133635;54025.45974540204;54051.72974135104;54077.34762437991;54955.725221373636;69467;70751.29248707448;72174.63912680613;72741.27258727344;73946;12462.563810266598;13832.377543010387;14659.706805864036;14801.111933284037;14991.400343986119;22578.88710556437;22954.19660311881;23072.653910191024;23128.537429250984;23166.652500212847;18100.547486312422;18153.364632237808;18421.90789046046;18481.815181848546;18491.352802651843;16130.112843793704;16417.83582164163;16510.17449127515;16559.83440165631;16811;18030.45908622691;18472.907635461735;18775;18811.341603043827;18868.52828679294;14251.57245282643;14390.28048597545;14431.134131952116;14434.350454229794;14591.197484138474;18067.548311291797;18181.272749089887;18186.36347727823;18203.271869125616;18204.36284730028 03:34:53 INFO - PID 3710 | |3;mootools.html;1290.9999999999998;1342.697892974081;1347.919124852509;1349.0961056092417;1353.1678018018915;14701.191434471113;15225.086494810319;15559.53321400357;15570.610734731637;15590.844091559082;15981.84018159818;16172.595685107879;16227.188640567954;16240;16279.674406511893;2844.5590933405356;2895.666998295191;2905.3898681276955;2907.752841008517;2908.461934542106;2770.4320614274025;2856.87144078517;2857.057171133521;2858.585505101759;2871.8133321334153;2722.768564672005;2757.1177223288555;2762.074704973826;2762.309422644339;2765.225736793691;2699.0283497940727;2795.9440811183867;2802.075315145997;2802.523570992926;2802.9159125226274;2804.1167032384938;2828.702986186441;2829.221963959907;2829.6180015697823;2838.262051866509;14873.107613543221;15083.849161508355;15282.235888205545;15285.159316237603;15293.541193764206;8196.180381961816;8480.448770829877;8483.533405662685;8489.490630562186;8494.70268540598;5500.80747173851;5722.885542289131;5829.825105246807;5858.443447872445;5919.348871624118;1865.9160337784833;1937.1282922684848;2000.4098790856733;2013.6375452418558;2023.8886861222627;25999.74000259992;26106.216813495626;26217.344566385804;26238.475230495475;26300.21099367022;6857.756726402676;7017.508774385846;7080.716771329192;7149.821254468576;7155.141383034069;15977.360905563879;16095.517134485985;16134.677306453805;16162.676746465006;16169.919150404172;7401.703931842773;8020.5588692621295;8168.632411541376;8193;8196.467229630054;6423.518236132309;6692.86614267712;6696.531242813054;7019.89470157948;7233.638318084075;5271.156614941591;5385.626665200349;5400;5450.236966824647;5453.481919217668;1554.362711288389;1595.369828917571;1610.3558576569467;1614.8546630803285;1623.3117557740054;827.3215962910356;842.6418772021866;853.4068822168533;862.8964524257129;873.0832625742944;6856.725730970805;6876.518643694893;6940.444764418834;6948.791536253921;6995.93004069963;712.9821754456076;714.2999860137095;717.8205448637841;722.5520177489885;728.5519405565539;4249.128928569636;4360.956390436118;4418.60232579063;4820.734859582686;4948.0103979204305;21338.893305533682;21488.925553722253;21702.674459883114;21922.89038554797;22280.66579001316;21353.25263615766;22049.22827701023;22068.668969965463;22100.226492073023;22102.668459973112;10313.174946004301;10714.196435267386;10726.141908647289;10739;10740.838887416696;7879.212078792304;8201.22088401601;8369.62336694838;8468.872966905377;8473.661053558035;18234.72647910309;18508.537286567942;18529.629407411507;18594.25622975066;18667.533311667317;18805.811941880405;18965.810341896955;19008.42974710761;19103.140358683613;19162.52093697613;11940.46267917963;12141.635750927486;12156.75686486283;12163.635090947286;12189.878101219228;13976.301184940916;14022;14050;14149.787753183504;14153.43386264538;10901.672949811518;10982.06652434552;11017.614383496539;11103;11207.327560346404;11173.776524469627;11263.605773797877;11374.488148033193;11489.770204596029;11584.594539191088;9583.137517623447;9731.12419882214;9743.26925480606;9754.85367719499;9804.901950980684;7186.317299856714;7359.043324367798;7384.150822655249;7406.481546291708;7414.369778568882;6896.793096207121;6918.93081069203;7042.7887163385185;7050.506464547638;7064.081669382947 03:34:53 INFO - PID 3710 | |4;prototype.html;517.464424320828;528.8572085536904;535.1517844216917;536.5573401943398;544.2326319888955;6322.462590679792;6328.873422531547;6404.903926441107;6412.487001039917;6461.353864613537;6168.228971378578;6255.249370075598;6302.936970630292;6400.583962042464;6426.678666066701;5292.550133238679;5305.7877684892555;5314.176302673091;5323.00387354742;5347.41178470368;4919.655624106314;5285.814996475124;5291.708956007418;5315.627906046579;5350.662334416396;5097.770600322994;5106.744662766865;5122.8207012754565;5150.768215430296;5156.639035267533;5111.514406131411;5147.7426128693605;5150.480608220584;5157.5616072633875;5169.612279079065;4411.316245981878;4558.202314594932;4593.517680643517;4608.124456353282;4623.583877451046;6231.781887633956;6315.463185629227;6332.588381755171;6333.524985626051;6337.271213810424;5763.636908867345;5838.2702162229725;5859.970700146515;5860.208871802295;5876.882462350729;6779;6780.8304792380095;6798.558093723942;6802.251752307242;6810.523263371566;5005.724685142316;5005.799768009275;5014.824481143142;5036.294918711384;5036.496350364934;6778.457723382117;6941.652917354112;6977.732237022937;7029.156501219886;7121.679524421414;5020.523050310215;5027.84916452507;5058.64589478733;5105.361829771278;5142.665726727751;5054.039732450822;5119.334486516729;5139.563137133386;5161.74191290434;5221.556167725786;5709.543236541067;5725.169850371756;5745.597808153389;5776.342882274201;5836.474717275466;6701.530892837454;6713.832154196087;6720.563163394364;6823.055429202654;6851.828704282333;1786.4640607817603;1795.7485951966735;1802.9819701803076;1815.1378095404577;1816.6911625023777;1174.3541052421135;1195.7787809255315;1203.6389083274983;1232.371490539831;1243.359669770069;5811.912821307684;5900.344844819762;5911.9704401478575;5933.643981361046;5946.464818166386;6766.323367663194;6786.796396108125;6871.278515755874;6880.518363714492;6908.827279318058;5800.158976948319;5855.707214639337;5866.208061911672;5896.941030589639;5950.137230101611;3584.856605735741;3589.6410358963894;3607.5129857469433;3622.2574372253625;3676.9080772980888;6608.4052435281055;6665.600063996176;6695.560454502305;6726.260111387744;6765.424938880152;6533.444657204096;7006.092471624219;7018;7107.8933815992805;7150;6433.453156481752;6532.248791388956;6542.542022058506;6638.634875081821;6680.966595166993;6742.528023038536;6744.291849355747;6776.424003959719;6789.15135608049;6931.75738849158;6714.630695311805;6737;6813.322943515283;6899.48253880971;6911.930880691129;6622.470202383702;6630.602163870183;6660.56706314088;6716.731330746716;6745.5952642841585;4981.128302547112;5067.974660126676;5071.39143302806;5125.7693403796175;5126.564561922668;6601.636909969998;6704.726102040597;6801.387875091265;6963.895541566779;7038.436925045881;6622.966885165543;6635.701393437211;6685.465162787063;6697.933020669731;6748.426383757435;6552.508561857784;6565.409113179837;6713.429358504582;6717.529772915849;6723.596584204964;6510.609363438209;6527.412532872065;6571.704273307617;6591.539441793097;6651.534392592665;6718.596884186964;6747.561408508432;6754.223264324665;6760.86478270422;6790.5925644461495;6454.2900280968115;6513.73945042193;6520.445762110274;6556.3443655635965;6624.271330153583 03:34:53 INFO - PID 3710 | |5;yui.html;887.4542156573709;898.9730308090758;912.4525284829103;917.3028498341258;926.3145272498351;17556.73664895026;18534.165962531686;18626.06869656517;18827.435176944713;19194.136263868124;20333.49166270844;20389.694154587694;21118.683219751714;21343.466413339673;21568.460788480297;911.6216770040442;934.0192797562567;936.063936063936;936.419419959626;936.6721647423416;923.1737594852602;995.6714284286204;1000.5897581991385;1000.9449480278619;1001.3040936549102;1052.0584077250855;1060.5386656804276;1060.6870973062964;1061.6072053340274;1061.7186445591924;1054.520193312041;1054.69413869978;1054.7995880782664;1055.0820785916264;1055.7624534479758;767.5241350362755;768.4774353439659;770.0951382126035;770.8496843115595;770.865098607747;17977.460676179737;18112.366067187584;18152.546186345317;18481.907590462095;19650.50873728154;21306;21612.135514579397;22027.559448810935;22096.447588810246;22116.889415552818;15175.62060948474;15326.540203793904;15394.307256173388;15399.615009624737;15761.842381576267;3724.9627503724887;3772.396416573335;3775.5658099318657;3777.225668737902;3783.3190025795348;122704;125753;126501;127325.36337318347;127449.36275318656;15907.761383579256;16519.66960660804;16587.087710176056;16658.16709164537;16677.249523771217;25330.366740831625;25479.617805732927;26054.478910421687;26059.869700651376;26078;8125.796855078552;8261.958690206511;8265.793355166168;8270.751877443687;8274.462159959583;4227.8097485613225;4318.5033721121845;4319.222539942841;4323.113761678848;4324.394584758136;5144.511271429208;5243.842684719464;5246.108161612535;5307.363116425976;5312.07038768214;958.3339578992673;970.9320347575602;983.6409710455632;987.6247026130025;989.0554520432969;648.3775575447529;673.6631684157921;682.9863402731918;690.7064497588505;691.9965400172966;10530.526126324334;10766.461676916122;10829;10930.781384372269;11014.39420831862;600.0848705723719;607.3501353551658;614.0543562913064;618.1685632823845;622.5953130465143;4999.575036121971;5013.147764879979;5022.74886255693;5030.3963524377305;5052.646314757932;105378.47310763551;107345.8412882468;108071;108737;111863;96343.51828240814;97920;98931;101809;104972;81805.18194818095;83720.16279837246;84787.57606211929;87437.5628121868;87482.56258718665;9281.86077208856;9465.242780577399;11185.72035699114;11205.60780372683;11302.491387887729;31378.495117060822;36934.63065369419;38896.222075557765;39399.212015760095;40539.79730101331;67001;69370.3062969384;70320.94518582123;71929.6403517979;72431.63784181046;16128.435504757277;16562.834371656612;16821.158942052603;17038.914805425895;17086;17981.73027404564;18022.36921707734;18033.72949405786;18045.187966541274;18250.269989200282;16065;16348.918255408647;16532.83467165313;16608.25262863198;16613.252403642105;16376;16485.58786030359;16800.57998549997;16813.495595132164;16913.91543042277;15816.446424375092;16038.518844434686;16157.27292271827;16258.837411625733;16281.59296017609;11822;12060.577879774166;12108.939455302667;12261.816072759095;12683.365831708194;12463.439145238668;12675.936620317209;12728.554500592434;12734.299613521345;12878.871211287767 03:34:53 INFO - PID 3710 | __end_tp_report 03:34:53 INFO - PID 3710 | __start_cc_report 03:34:53 INFO - PID 3710 | _x_x_mozilla_cycle_collect,370 03:34:53 INFO - PID 3710 | __end_cc_report 03:34:53 INFO - PID 3710 | __startTimestamp1504521293402__endTimestamp 03:34:53 INFO - PID 3710 | 03:34:53 INFO - PID 3710 | ------- Summary: start ------- 03:34:53 INFO - PID 3710 | Number of tests: 6 03:34:53 INFO - PID 3710 | 03:34:53 INFO - PID 3710 | [#0] dojo.html Cycles:165 Average:6415.72 Median:6670.13 stddev:768.27 (11.5%) stddev-sans-first:731.62 03:34:53 INFO - PID 3710 | Values: 3334.8 3371.1 3378.6 3386.4 3420.9 6355.7 6450.1 6517.1 6520.1 6582.3 6494.7 6569.5 6598.3 6617.5 6635.2 5981.1 5982.1 5990.1 6012.8 6079.1 5835.6 5892.8 5915.5 5935.3 5999.8 5866.1 5930.4 5938.0 5950.2 5957.5 5880.0 5905.9 5954.6 5967.5 5987.7 5800.5 5882.9 5895.4 5916.7 5931.7 6412.6 6428.6 6435.8 6571.2 6580.3 6899.2 6996.3 7085.3 7100.7 7116.1 6853.6 7038.8 7083.3 7176.5 7178.7 6392.6 6486.2 6494.7 6580.1 6621.2 6962.4 7222.3 7324.1 7334.9 7347.7 6823.6 6832.2 6856.4 6860.3 6876.6 6980.8 6989.2 6990.3 6991.9 6997.1 6806.0 6908.8 6922.6 6943.6 6944.1 6939.6 7095.6 7121.4 7123.0 7127.1 5468.3 5502.6 5556.1 5577.9 5584.3 4669.7 4676.5 4722.7 4725.5 4774.2 6082.0 6100.7 6101.5 6105.6 6123.0 6927.8 6951.0 7016.7 7023.3 7034.3 5989.4 6035.5 6041.2 6111.4 6120.8 5936.2 5953.6 5968.2 5971.2 5971.9 6772.1 6855.1 6860.0 6863.6 6865.8 6950.9 6993.9 7003.2 7017.3 7076.7 6594.2 6714.0 6727.4 6736.2 6824.1 6850.2 6857.9 6868.0 6876.0 6883.2 6951.0 6985.7 7023.2 7025.2 7040.9 6614.6 6696.2 6696.6 6712.4 6758.1 6764.4 6812.9 6841.4 6859.8 6862.1 6711.4 6718.6 6802.9 6807.8 6812.8 6670.1 6751.4 6785.7 6814.9 6839.9 6506.3 6510.9 6517.3 6588.6 6623.4 03:34:53 INFO - PID 3710 | 03:34:53 INFO - PID 3710 | [#1] ext.html Cycles:180 Average:36990.57 Median:33744.25 stddev:53677.11 (159.1%) stddev-sans-first:53758.76 03:34:53 INFO - PID 3710 | Values: 763.7 821.3 853.9 880.5 883.9 38357.2 39058.4 39349.6 39360.2 39409.0 36117.5 36426.6 36448.6 36458.3 36514.5 6799.8 6910.8 6920.2 6920.7 6929.7 6805.6 6859.8 6867.7 6884.0 6886.5 6848.9 7429.1 7694.5 7732.1 7732.4 7596.9 7659.5 7660.2 7666.7 7669.3 7347.1 7483.4 7545.2 7556.6 7599.3 36280.3 36493.6 36522.8 36535.6 36606.6 3529.5 3574.9 3581.2 3581.6 3585.6 30135.8 30172.8 30266.1 30312.4 30319.0 5087.9 5104.1 5133.3 5138.5 5146.6 189834.0 190771.0 190998.0 191270.0 191501.0 46031.5 46143.0 46159.8 46402.3 46639.0 47885.5 47908.8 47921.5 47963.5 47981.8 15126.2 15238.2 15330.6 15359.5 15438.8 12511.1 12631.5 12653.2 12725.1 12758.0 13722.9 13748.6 13768.4 13808.0 13852.7 5574.0 5639.7 5686.2 5713.3 5791.6 978.8 999.0 1005.1 1014.2 1017.9 22171.3 22172.4 22205.3 22432.6 22492.4 1091.0 1105.8 1106.5 1108.1 1117.8 11641.2 11875.5 11885.3 11985.8 12038.2 227018.0 230618.0 230717.0 231220.0 231852.0 48344.8 48985.8 50275.0 53131.5 53232.2 1417.3 1431.5 1435.7 1438.2 1441.6 26184.1 26484.2 26658.1 27427.9 28041.2 46891.5 46896.5 46922.3 46988.0 47055.1 196479.0 197854.0 198576.0 198650.0 198798.0 31630.4 31658.7 31693.4 31932.2 31992.2 40020.0 40433.4 40540.0 41194.8 41214.4 29954.3 30165.4 30274.4 30275.8 30790.8 30087.4 30384.5 30465.8 30479.2 30803.4 33277.7 33330.3 33604.5 33884.0 33974.3 29764.3 29919.6 29924.9 29984.0 30239.5 22839.2 23180.2 23475.3 23752.5 23946.4 03:34:53 INFO - PID 3710 | 03:34:53 INFO - PID 3710 | [#2] jquery.html Cycles:180 Average:35055.70 Median:21590.91 stddev:80460.20 (372.7%) stddev-sans-first:80644.21 03:34:53 INFO - PID 3710 | Values: 553.8 556.8 557.9 558.9 583.8 21488.7 21506.0 22318.9 22328.7 22346.9 21580.2 21726.7 21805.6 21838.2 21992.9 6675.5 6831.1 6831.7 6844.8 6854.5 6381.6 6655.4 6722.2 6724.3 6730.8 6620.8 6638.1 6638.2 6665.4 6666.6 6655.8 6657.9 6666.9 6669.8 6693.1 6161.3 6161.6 6163.0 6166.6 6173.4 28771.3 29393.0 30117.2 31270.5 31417.2 2032.5 2078.4 2084.5 2085.2 2086.6 20279.7 20497.4 20779.9 20919.5 21003.6 2551.6 2575.2 2640.9 2641.8 2646.3 126932.4 134429.0 134468.0 134549.3 134608.0 19132.9 20540.4 21314.4 21601.6 21966.5 42712.6 42723.4 42787.0 42822.0 42849.8 11286.5 11372.1 11506.1 11646.4 11669.4 8095.3 8453.7 9516.7 9528.8 9712.0 10543.9 10884.6 11117.3 11501.7 11671.8 3619.2 3709.5 4014.5 4187.3 4234.6 939.0 956.7 969.7 971.7 980.0 19553.6 19641.0 19717.7 20078.4 20364.0 1036.2 1044.9 1057.5 1061.1 1064.3 10934.7 11055.8 11089.7 11102.8 11121.6 469568.0 472069.0 479615.0 482401.0 483496.0 115808.4 116763.0 117365.0 119899.0 122488.4 1147.1 1172.2 1189.6 1199.2 1200.4 17490.7 17548.2 17644.5 17848.7 18118.6 53463.7 54025.5 54051.7 54077.3 54955.7 69467.0 70751.3 72174.6 72741.3 73946.0 12462.6 13832.4 14659.7 14801.1 14991.4 22578.9 22954.2 23072.7 23128.5 23166.7 18100.5 18153.4 18421.9 18481.8 18491.4 16130.1 16417.8 16510.2 16559.8 16811.0 18030.5 18472.9 18775.0 18811.3 18868.5 14251.6 14390.3 14431.1 14434.4 14591.2 18067.5 18181.3 18186.4 18203.3 18204.4 03:34:53 INFO - PID 3710 | 03:34:53 INFO - PID 3710 | [#3] mootools.html Cycles:180 Average:9312.68 Median:2767.83 stddev:6674.41 (241.1%) stddev-sans-first:6665.92 03:34:53 INFO - PID 3710 | Values: 1291.0 1342.7 1347.9 1349.1 1353.2 14701.2 15225.1 15559.5 15570.6 15590.8 15981.8 16172.6 16227.2 16240.0 16279.7 2844.6 2895.7 2905.4 2907.8 2908.5 2770.4 2856.9 2857.1 2858.6 2871.8 2722.8 2757.1 2762.1 2762.3 2765.2 2699.0 2795.9 2802.1 2802.5 2802.9 2804.1 2828.7 2829.2 2829.6 2838.3 14873.1 15083.8 15282.2 15285.2 15293.5 8196.2 8480.4 8483.5 8489.5 8494.7 5500.8 5722.9 5829.8 5858.4 5919.3 1865.9 1937.1 2000.4 2013.6 2023.9 25999.7 26106.2 26217.3 26238.5 26300.2 6857.8 7017.5 7080.7 7149.8 7155.1 15977.4 16095.5 16134.7 16162.7 16169.9 7401.7 8020.6 8168.6 8193.0 8196.5 6423.5 6692.9 6696.5 7019.9 7233.6 5271.2 5385.6 5400.0 5450.2 5453.5 1554.4 1595.4 1610.4 1614.9 1623.3 827.3 842.6 853.4 862.9 873.1 6856.7 6876.5 6940.4 6948.8 6995.9 713.0 714.3 717.8 722.6 728.6 4249.1 4361.0 4418.6 4820.7 4948.0 21338.9 21488.9 21702.7 21922.9 22280.7 21353.3 22049.2 22068.7 22100.2 22102.7 10313.2 10714.2 10726.1 10739.0 10740.8 7879.2 8201.2 8369.6 8468.9 8473.7 18234.7 18508.5 18529.6 18594.3 18667.5 18805.8 18965.8 19008.4 19103.1 19162.5 11940.5 12141.6 12156.8 12163.6 12189.9 13976.3 14022.0 14050.0 14149.8 14153.4 10901.7 10982.1 11017.6 11103.0 11207.3 11173.8 11263.6 11374.5 11489.8 11584.6 9583.1 9731.1 9743.3 9754.9 9804.9 7186.3 7359.0 7384.2 7406.5 7414.4 6896.8 6918.9 7042.8 7050.5 7064.1 03:34:53 INFO - PID 3710 | 03:34:53 INFO - PID 3710 | [#4] prototype.html Cycles:180 Average:5642.21 Median:6325.67 stddev:1579.41 (25.0%) stddev-sans-first:1536.28 03:34:53 INFO - PID 3710 | Values: 517.5 528.9 535.2 536.6 544.2 6322.5 6328.9 6404.9 6412.5 6461.4 6168.2 6255.2 6302.9 6400.6 6426.7 5292.6 5305.8 5314.2 5323.0 5347.4 4919.7 5285.8 5291.7 5315.6 5350.7 5097.8 5106.7 5122.8 5150.8 5156.6 5111.5 5147.7 5150.5 5157.6 5169.6 4411.3 4558.2 4593.5 4608.1 4623.6 6231.8 6315.5 6332.6 6333.5 6337.3 5763.6 5838.3 5860.0 5860.2 5876.9 6779.0 6780.8 6798.6 6802.3 6810.5 5005.7 5005.8 5014.8 5036.3 5036.5 6778.5 6941.7 6977.7 7029.2 7121.7 5020.5 5027.8 5058.6 5105.4 5142.7 5054.0 5119.3 5139.6 5161.7 5221.6 5709.5 5725.2 5745.6 5776.3 5836.5 6701.5 6713.8 6720.6 6823.1 6851.8 1786.5 1795.7 1803.0 1815.1 1816.7 1174.4 1195.8 1203.6 1232.4 1243.4 5811.9 5900.3 5912.0 5933.6 5946.5 6766.3 6786.8 6871.3 6880.5 6908.8 5800.2 5855.7 5866.2 5896.9 5950.1 3584.9 3589.6 3607.5 3622.3 3676.9 6608.4 6665.6 6695.6 6726.3 6765.4 6533.4 7006.1 7018.0 7107.9 7150.0 6433.5 6532.2 6542.5 6638.6 6681.0 6742.5 6744.3 6776.4 6789.2 6931.8 6714.6 6737.0 6813.3 6899.5 6911.9 6622.5 6630.6 6660.6 6716.7 6745.6 4981.1 5068.0 5071.4 5125.8 5126.6 6601.6 6704.7 6801.4 6963.9 7038.4 6623.0 6635.7 6685.5 6697.9 6748.4 6552.5 6565.4 6713.4 6717.5 6723.6 6510.6 6527.4 6571.7 6591.5 6651.5 6718.6 6747.6 6754.2 6760.9 6790.6 6454.3 6513.7 6520.4 6556.3 6624.3 03:34:53 INFO - PID 3710 | 03:34:53 INFO - PID 3710 | [#5] yui.html Cycles:180 Average:23032.12 Median:21212.34 stddev:32138.50 (151.5%) stddev-sans-first:32185.64 03:34:53 INFO - PID 3710 | Values: 887.5 899.0 912.5 917.3 926.3 17556.7 18534.2 18626.1 18827.4 19194.1 20333.5 20389.7 21118.7 21343.5 21568.5 911.6 934.0 936.1 936.4 936.7 923.2 995.7 1000.6 1000.9 1001.3 1052.1 1060.5 1060.7 1061.6 1061.7 1054.5 1054.7 1054.8 1055.1 1055.8 767.5 768.5 770.1 770.8 770.9 17977.5 18112.4 18152.5 18481.9 19650.5 21306.0 21612.1 22027.6 22096.4 22116.9 15175.6 15326.5 15394.3 15399.6 15761.8 3725.0 3772.4 3775.6 3777.2 3783.3 122704.0 125753.0 126501.0 127325.4 127449.4 15907.8 16519.7 16587.1 16658.2 16677.2 25330.4 25479.6 26054.5 26059.9 26078.0 8125.8 8262.0 8265.8 8270.8 8274.5 4227.8 4318.5 4319.2 4323.1 4324.4 5144.5 5243.8 5246.1 5307.4 5312.1 958.3 970.9 983.6 987.6 989.1 648.4 673.7 683.0 690.7 692.0 10530.5 10766.5 10829.0 10930.8 11014.4 600.1 607.4 614.1 618.2 622.6 4999.6 5013.1 5022.7 5030.4 5052.6 105378.5 107345.8 108071.0 108737.0 111863.0 96343.5 97920.0 98931.0 101809.0 104972.0 81805.2 83720.2 84787.6 87437.6 87482.6 9281.9 9465.2 11185.7 11205.6 11302.5 31378.5 36934.6 38896.2 39399.2 40539.8 67001.0 69370.3 70320.9 71929.6 72431.6 16128.4 16562.8 16821.2 17038.9 17086.0 17981.7 18022.4 18033.7 18045.2 18250.3 16065.0 16348.9 16532.8 16608.3 16613.3 16376.0 16485.6 16800.6 16813.5 16913.9 15816.4 16038.5 16157.3 16258.8 16281.6 11822.0 12060.6 12108.9 12261.8 12683.4 12463.4 12675.9 12728.6 12734.3 12878.9 03:34:53 INFO - PID 3710 | -------- Summary: end -------- 03:34:53 INFO - PID 3710 | 03:34:54 INFO - TEST-INFO | 3710: exit 0 03:34:54 INFO - TEST-OK | dromaeo_css | took 1115871ms 03:34:54 INFO - TEST-START | kraken 03:34:54 INFO - Initialising browser for kraken test... 03:34:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53962/getInfo.html -profile /tmp/tmp1dNgBL/profile 03:34:54 INFO - TEST-INFO | started process 4089 (/builds/slave/test/build/application/firefox/firefox http://localhost:53962/getInfo.html) 03:34:59 INFO - TEST-INFO | 4089: exit 0 03:34:59 INFO - Browser initialized. 03:34:59 INFO - Running cycle 1/1 for kraken test... 03:34:59 INFO - Using env: {'DISPLAY': ':0', 03:34:59 INFO - 'HOME': '/home/cltbld', 03:34:59 INFO - 'JSGC_DISABLE_POISONING': '1', 03:34:59 INFO - 'LANG': 'en_US.UTF-8', 03:34:59 INFO - 'LANGUAGE': 'en_US:en', 03:34:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:34:59 INFO - 'LOGNAME': 'cltbld', 03:34:59 INFO - 'MAIL': '/var/mail/cltbld', 03:34:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:34:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:34:59 INFO - 'MOZ_CRASHREPORTER': '1', 03:34:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:34:59 INFO - 'MOZ_NO_REMOTE': '1', 03:34:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:34:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:59 INFO - 'NO_EM_RESTART': '1', 03:34:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:59 INFO - 'PWD': '/builds/slave/test', 03:34:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:34:59 INFO - 'RUST_BACKTRACE': 'full', 03:34:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:34:59 INFO - 'SHELL': '/bin/bash', 03:34:59 INFO - 'SHLVL': '1', 03:34:59 INFO - 'TERM': 'linux', 03:34:59 INFO - 'TMOUT': '86400', 03:34:59 INFO - 'USER': 'cltbld', 03:34:59 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496', 03:34:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:59 INFO - '_': '/tools/buildbot/bin/python'} 03:34:59 INFO - TEST-INFO | started process 4272 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1dNgBL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:35:00 INFO - PID 4272 | 03:35:00 INFO - PID 4272 | (/builds/slave/test/build/application/firefox/firefox:4324): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:35:00 INFO - PID 4272 | 03:35:10 INFO - PID 4272 | RSS: Main: 169734144 03:35:10 INFO - PID 4272 | 03:35:13 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53962/tests/kraken/driver.html?testName=audio-beat-detection) 03:35:14 INFO - PID 4272 | RSS: Main: 174034944 03:35:14 INFO - PID 4272 | 03:35:16 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53962/tests/kraken/driver.html?testName=audio-dft) 03:35:17 INFO - PID 4272 | RSS: Main: 175341568 03:35:17 INFO - PID 4272 | 03:35:20 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53962/tests/kraken/driver.html?testName=audio-fft) 03:35:20 INFO - PID 4272 | RSS: Main: 169017344 03:35:20 INFO - PID 4272 | 03:35:23 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53962/tests/kraken/driver.html?testName=audio-oscillator) 03:35:23 INFO - PID 4272 | RSS: Main: 169979904 03:35:23 INFO - PID 4272 | 03:35:25 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53962/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:35:26 INFO - PID 4272 | RSS: Main: 170692608 03:35:26 INFO - PID 4272 | 03:35:29 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53962/tests/kraken/driver.html?testName=imaging-darkroom) 03:35:30 INFO - PID 4272 | RSS: Main: 172900352 03:35:30 INFO - PID 4272 | 03:35:33 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53962/tests/kraken/driver.html?testName=imaging-desaturate) 03:35:34 INFO - PID 4272 | RSS: Main: 173035520 03:35:34 INFO - PID 4272 | 03:35:37 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53962/tests/kraken/driver.html?testName=json-parse-financial) 03:35:37 INFO - PID 4272 | RSS: Main: 173010944 03:35:37 INFO - PID 4272 | 03:35:39 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53962/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:35:40 INFO - PID 4272 | RSS: Main: 173412352 03:35:40 INFO - PID 4272 | 03:35:41 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53962/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:35:42 INFO - PID 4272 | RSS: Main: 174149632 03:35:42 INFO - PID 4272 | 03:35:44 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53962/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:35:45 INFO - PID 4272 | RSS: Main: 170373120 03:35:45 INFO - PID 4272 | 03:35:47 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53962/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:35:48 INFO - PID 4272 | RSS: Main: 170725376 03:35:48 INFO - PID 4272 | 03:35:51 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53962/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:35:52 INFO - PID 4272 | RSS: Main: 170549248 03:35:52 INFO - PID 4272 | 03:35:53 INFO - PID 4272 | Cycle 1(1): loaded http://localhost:53962/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53962/tests/kraken/driver.html?testName=ai-astar) 03:35:54 INFO - PID 4272 | RSS: Main: 172404736 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | __start_tp_report 03:35:54 INFO - PID 4272 | _x_x_mozilla_page_load 03:35:54 INFO - PID 4272 | _x_x_mozilla_page_load_details 03:35:54 INFO - PID 4272 | |i|pagename|runs| 03:35:54 INFO - PID 4272 | |0;ai-astar;97;107;102;108;107;111;107;97;109;108 03:35:54 INFO - PID 4272 | |1;audio-beat-detection;145;115;140;171;121;141;142;120;190;120 03:35:54 INFO - PID 4272 | |2;audio-dft;165;161;162;161;160;162;163;162;164;167 03:35:54 INFO - PID 4272 | |3;audio-fft;74;78;76;183;75;76;80;77;149;75 03:35:54 INFO - PID 4272 | |4;audio-oscillator;79;79;138;79;79;80;130;79;79;122 03:35:54 INFO - PID 4272 | |5;imaging-gaussian-blur;114;114;113;115;116;114;114;114;114;114 03:35:54 INFO - PID 4272 | |6;imaging-darkroom;150;148;149;148;150;149;149;148;150;148 03:35:54 INFO - PID 4272 | |7;imaging-desaturate;93;93;94;94;93;94;94;94;95;94 03:35:54 INFO - PID 4272 | |8;json-parse-financial;53;52;58;52;52;52;52;51;52;51 03:35:54 INFO - PID 4272 | |9;json-stringify-tinderbox;39;38;38;38;38;38;45;38;39;39 03:35:54 INFO - PID 4272 | |10;stanford-crypto-aes;76;74;74;75;73;74;76;74;80;76 03:35:54 INFO - PID 4272 | |11;stanford-crypto-ccm;127;129;120;116;122;116;142;122;127;156 03:35:54 INFO - PID 4272 | |12;stanford-crypto-pbkdf2;178;175;168;169;170;170;172;175;164;172 03:35:54 INFO - PID 4272 | |13;stanford-crypto-sha256-iterative;70;66;69;64;65;66;64;65;66;63 03:35:54 INFO - PID 4272 | __end_tp_report 03:35:54 INFO - PID 4272 | __start_cc_report 03:35:54 INFO - PID 4272 | _x_x_mozilla_cycle_collect,948 03:35:54 INFO - PID 4272 | __end_cc_report 03:35:54 INFO - PID 4272 | __startTimestamp1504521354035__endTimestamp 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | ------- Summary: start ------- 03:35:54 INFO - PID 4272 | Number of tests: 14 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#0] ai-astar Cycles:10 Average:105.30 Median:108.50 stddev:4.92 (4.5%) stddev-sans-first:4.21 03:35:54 INFO - PID 4272 | Values: 97.0 107.0 102.0 108.0 107.0 111.0 107.0 97.0 109.0 108.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:141.50 stddev:24.21 (17.1%) stddev-sans-first:25.62 03:35:54 INFO - PID 4272 | Values: 145.0 115.0 140.0 171.0 121.0 141.0 142.0 120.0 190.0 120.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#2] audio-dft Cycles:10 Average:162.70 Median:162.50 stddev:2.11 (1.3%) stddev-sans-first:2.07 03:35:54 INFO - PID 4272 | Values: 165.0 161.0 162.0 161.0 160.0 162.0 163.0 162.0 164.0 167.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#3] audio-fft Cycles:10 Average:94.30 Median:76.00 stddev:38.67 (50.9%) stddev-sans-first:40.31 03:35:54 INFO - PID 4272 | Values: 74.0 78.0 76.0 183.0 75.0 76.0 80.0 77.0 149.0 75.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:79.00 stddev:24.86 (31.5%) stddev-sans-first:25.73 03:35:54 INFO - PID 4272 | Values: 79.0 79.0 138.0 79.0 79.0 80.0 130.0 79.0 79.0 122.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.79 (0.7%) stddev-sans-first:0.83 03:35:54 INFO - PID 4272 | Values: 114.0 114.0 113.0 115.0 116.0 114.0 114.0 114.0 114.0 114.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:0.88 (0.6%) stddev-sans-first:0.83 03:35:54 INFO - PID 4272 | Values: 150.0 148.0 149.0 148.0 150.0 149.0 149.0 148.0 150.0 148.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 03:35:54 INFO - PID 4272 | Values: 93.0 93.0 94.0 94.0 93.0 94.0 94.0 94.0 95.0 94.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.00 stddev:2.01 (3.9%) stddev-sans-first:2.13 03:35:54 INFO - PID 4272 | Values: 53.0 52.0 58.0 52.0 52.0 52.0 52.0 51.0 52.0 51.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#9] json-stringify-tinderbox Cycles:10 Average:39.00 Median:38.50 stddev:2.16 (5.6%) stddev-sans-first:2.29 03:35:54 INFO - PID 4272 | Values: 39.0 38.0 38.0 38.0 38.0 38.0 45.0 38.0 39.0 39.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:75.50 stddev:1.99 (2.6%) stddev-sans-first:2.09 03:35:54 INFO - PID 4272 | Values: 76.0 74.0 74.0 75.0 73.0 74.0 76.0 74.0 80.0 76.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:127.00 stddev:12.50 (9.8%) stddev-sans-first:13.25 03:35:54 INFO - PID 4272 | Values: 127.0 129.0 120.0 116.0 122.0 116.0 142.0 122.0 127.0 156.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:172.00 stddev:4.03 (2.3%) stddev-sans-first:3.47 03:35:54 INFO - PID 4272 | Values: 178.0 175.0 168.0 169.0 170.0 170.0 172.0 175.0 164.0 172.0 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:2.20 (3.3%) stddev-sans-first:1.73 03:35:54 INFO - PID 4272 | Values: 70.0 66.0 69.0 64.0 65.0 66.0 64.0 65.0 66.0 63.0 03:35:54 INFO - PID 4272 | -------- Summary: end -------- 03:35:54 INFO - PID 4272 | 03:35:54 INFO - PID 4272 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:35:54 INFO - TEST-INFO | 4272: exit 0 03:35:54 INFO - TEST-OK | kraken | took 60705ms 03:35:54 INFO - SUITE-END | took 1176s 03:35:55 INFO - Completed test suite (00:19:36) 03:35:55 INFO - javascript benchmark 03:35:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8576.279383435513, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.7832390894605, 3371.072954937392, 3378.577677790276, 3386.4243078676623, 3420.8631654733813, 6355.71399287032, 6450.129232553604, 6517.087607734921, 6520.087187793707, 6582.275949645547, 6494.707738151783, 6569.474442044637, 6598.307177746339, 6617.503687223453, 6635.170603674541, 5981.1028345748055, 5982.102684597324, 5990.131430942511, 6012.849678758044, 6079.057746049347, 5835.649861008353, 5892.823215303548, 5915.497182739472, 5935.258092738408, 5999.76000959961, 5866.120081987693, 5930.406959304057, 5937.960856850055, 5950.196723442323, 5957.493613042897, 5879.970600146972, 5905.911411328833, 5954.583179177459, 5967.522598192134, 5987.700614969234, 5800.506956908668, 5882.941170588282, 5895.35151133375, 5916.674582897939, 5931.733072011727, 6412.551121421502, 6428.646424446653, 6435.8391040223905, 6571.178602674666, 6580.276169621337, 6899.241083480813, 6996.265392133803, 7085.256048114925, 7100.715971361139, 7116.146062472485, 6853.623050732208, 7038.824029399255, 7083.291670832927, 7176.461765367619, 7178.676959536833, 6392.584482008655, 6486.221653401575, 6494.675266236669, 6580.078788969548, 6621.238557565845, 6962.408195303355, 7222.349988501061, 7324.08448943882, 7334.853302933911, 7347.70609175627, 6823.624700641413, 6832.214295355998, 6856.41720453767, 6860.279670634612, 6876.587404755731, 6980.7905762827195, 6989.161300643854, 6990.335918087773, 6991.860162796817, 6997.090378250795, 6806.047153398528, 6908.827279318058, 6922.550034247758, 6943.618101004493, 6944.131983502062, 6939.618320992293, 7095.574265544084, 7121.430285577141, 7122.964385178041, 7127.144742630918, 5468.343798744176, 5502.642328248651, 5556.111022236423, 5577.9163312550345, 5584.274044374205, 4669.743164125938, 4676.462206846189, 4722.716637001791, 4725.503822098699, 4774.212254977959, 6081.969590152021, 6100.664463454464, 6101.450869421685, 6105.572609917263, 6123.0, 6927.79216623492, 6950.965245173742, 7016.719331226694, 7023.33278338557, 7034.296570343027, 5989.401059893976, 6035.517158627299, 6041.184440100618, 6111.35830737775, 6120.785772497941, 5936.168936348915, 5953.642781433128, 5968.1942937703725, 5971.164037034819, 5971.850703732442, 6772.11962444879, 6855.108835851307, 6859.965700171567, 6863.553868998499, 6865.794026179223, 6950.93049069523, 6993.860122797414, 7003.229644738973, 7017.2631873652535, 7076.681549330189, 6594.208694956636, 6714.0, 6727.360900714424, 6736.191657001191, 6824.112865327475, 6850.177978642595, 6857.897131543131, 6868.038474613458, 6876.002979567835, 6883.242843287134, 6951.0, 6985.720571177096, 7023.227444980946, 7025.2272250053475, 7040.8591828162125, 6614.603123812587, 6696.229933557704, 6696.5647232929705, 6712.4294434971625, 6758.0876581662815, 6764.425023873026, 6812.8978065328065, 6841.3842754152365, 6859.828504287433, 6862.073620061228, 6711.429528489938, 6718.630475323904, 6802.8639427212165, 6807.829804254933, 6812.829679258059, 6670.132882725215, 6751.426128779109, 6785.6946437411425, 6814.8637027260165, 6839.863202736017, 6506.3493650634555, 6510.902336464862, 6517.250516190698, 6588.6046837188005, 6623.4038936495945], "value": 6358.404051532104, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.7326935572551, 821.347029111856, 853.9231469167773, 880.5201165364878, 883.9160279773423, 38357.232855342874, 39058.4141237881, 39349.60650393495, 39360.21279574407, 39409.0, 36117.45823812638, 36426.63573364266, 36448.635513644855, 36458.270834583294, 36514.45228321571, 6799.830004249896, 6910.792676219697, 6920.169579650449, 6920.688569014406, 6929.6881640326055, 6805.625690587016, 6859.828504287383, 6867.656617169122, 6884.0362349271145, 6886.51794374394, 6848.897266540981, 7429.145648250439, 7694.5383277003275, 7732.1108072571815, 7732.420068494857, 7596.886046709304, 7659.502132361378, 7660.233976602323, 7666.655000524934, 7669.348105411047, 7347.118345798485, 7483.36391406731, 7545.245475452466, 7556.622168891534, 7599.278068583476, 36280.27439451223, 36493.635063649286, 36522.817385913164, 36535.63464365349, 36606.63393366059, 3529.505869178317, 3574.857005719768, 3581.212133330663, 3581.5881173665102, 3585.6055833858254, 30135.849320753474, 30172.8491357544, 30266.092017239516, 30312.393752124834, 30319.0, 5087.949120508784, 5104.132297509395, 5133.30700355451, 5138.511841375063, 5146.639735218536, 189834.0, 190771.0, 190998.0, 191270.0, 191501.04249478804, 46031.5396846034, 46143.0, 46159.76920115378, 46402.30396543987, 46639.0, 47885.52114478811, 47908.760456197495, 47921.5207847924, 47963.520364795906, 47981.76009120002, 15126.243687815566, 15238.238088095773, 15330.616734581725, 15359.462418815512, 15438.845611543966, 12511.12422130442, 12631.494740210472, 12653.177543459646, 12725.109242352946, 12758.0, 13722.931385343209, 13748.587542373745, 13768.380422880995, 13808.0, 13852.653683657989, 5574.0, 5639.746211420496, 5686.232358631614, 5713.257276554022, 5791.594588378773, 978.7602037500858, 998.9950050249702, 1005.1154983614373, 1014.2139841622801, 1017.8676772019738, 22171.334859954226, 22172.445688857584, 22205.333839984825, 22432.551348972927, 22492.437689057904, 1090.967270981856, 1105.8451816745662, 1106.5076041161767, 1108.0802933565121, 1117.8155604325357, 11641.185117041896, 11875.465604047838, 11885.346305953082, 11985.760284794429, 12038.157328987061, 227018.0, 230618.0, 230717.0, 231220.0, 231852.0, 48344.7582762091, 48985.75507122442, 50275.0, 53131.468685312655, 53232.20151697728, 1417.3126033873564, 1431.4989753586162, 1435.7343891380128, 1438.173050495948, 1441.6467965348347, 26184.08355707541, 26484.205473835817, 26658.06696765604, 27427.862860685567, 28041.158765237076, 46891.531084688715, 46896.531034689215, 46922.29616555686, 46988.0, 47055.117795287806, 196479.0, 197854.0, 198576.0, 198650.0, 198798.0, 31630.36739265248, 31658.683413165574, 31693.366132676758, 31932.201694957814, 31992.200194995312, 40020.0, 40433.39349909695, 40540.0, 41194.794026029675, 41214.38178427266, 29954.25114372158, 30165.396692066475, 30274.394512109193, 30275.848620756755, 30790.846045769627, 30087.3982520344, 30384.5442318361, 30465.847670761505, 30479.238019048815, 30803.383932321678, 33277.667223328426, 33330.333393331515, 33604.49593256152, 33884.0, 33974.320513590086, 29764.255893602833, 29919.55120673148, 29924.85037574798, 29984.0, 30239.546406803474, 22839.20062797794, 23180.18869339565, 23475.295741127793, 23752.524949500566, 23946.40133996664], "value": 16725.233659513335, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.7729530892333, 556.7522452508636, 557.9135234038723, 558.8658721906738, 583.7519054401879, 21488.677669834964, 21506.0, 22318.88840555797, 22328.665070023962, 22346.88826555867, 21580.244691435804, 21726.674099888474, 21805.563888722256, 21838.23566175184, 21992.8900355498, 6675.532712710112, 6831.077804496404, 6831.658417079151, 6844.828879278033, 6854.451643868479, 6381.649009304487, 6655.367740064686, 6722.193336799566, 6724.260331363546, 6730.798076057702, 6620.801375958729, 6638.1370421845095, 6638.236602790693, 6665.366790154928, 6666.566673166253, 6655.767048153316, 6657.866842663144, 6666.866662666719, 6669.833254168685, 6693.062971184038, 6161.322254551996, 6161.599496032743, 6163.044728067157, 6166.599171053867, 6173.41352571505, 28771.28071798201, 29393.0, 30117.247068823235, 31270.530942035886, 31417.214569635715, 2032.542677897476, 2078.449210959097, 2084.499720067173, 2085.1971990783504, 2086.5618220173783, 20279.695804562944, 20497.38507844767, 20779.896100519552, 20919.477013074644, 21003.579928401345, 2551.59174532074, 2575.188815523104, 2640.854752988585, 2641.77544908683, 2646.259047466693, 126932.36533817364, 134429.0, 134468.0, 134549.32725336507, 134608.0, 19132.904335478514, 20540.383788486368, 21314.36056918295, 21601.56796864054, 21966.45083872916, 42712.57287427148, 42723.35914961278, 42787.0, 42822.0, 42849.78575107167, 11286.54853805855, 11372.147088968195, 11506.137039722054, 11646.359450230319, 11669.416529173508, 8095.2714255717265, 8453.661853525913, 9516.666916658021, 9528.761780955532, 9712.0, 10543.94728026355, 10884.564617415215, 11117.27737697047, 11501.670739613952, 11671.790257679206, 3619.2399596085115, 3709.536307961505, 4014.4781178447197, 4187.330027195634, 4234.569998301386, 938.9530523473935, 956.6890760502864, 969.7333233360741, 971.6842026341467, 979.9951000244953, 19553.60892782165, 19641.0, 19717.704234436496, 20078.397648070582, 20364.0, 1036.2487196782424, 1044.8589440425446, 1057.5346847387125, 1061.0874647802884, 1064.3081996702206, 10934.671959841218, 11055.778884422267, 11089.722756931142, 11102.777944441228, 11121.55513779456, 469568.0, 472069.0, 479615.0, 482401.0, 483496.0, 115808.42095789636, 116763.0, 117365.0, 119899.0, 122488.38755806164, 1147.0995268714187, 1172.1619042384737, 1189.5658084798986, 1199.196538319328, 1200.3878022208562, 17490.650186995936, 17548.21033053541, 17644.47066587953, 17848.732269015716, 18118.637627247645, 53463.73268133635, 54025.45974540204, 54051.72974135104, 54077.34762437991, 54955.725221373636, 69467.0, 70751.29248707448, 72174.63912680613, 72741.27258727344, 73946.0, 12462.563810266598, 13832.377543010387, 14659.706805864036, 14801.111933284037, 14991.400343986119, 22578.88710556437, 22954.19660311881, 23072.653910191024, 23128.537429250984, 23166.652500212847, 18100.547486312422, 18153.364632237808, 18421.90789046046, 18481.815181848546, 18491.352802651843, 16130.112843793704, 16417.83582164163, 16510.17449127515, 16559.83440165631, 16811.0, 18030.45908622691, 18472.907635461735, 18775.0, 18811.341603043827, 18868.52828679294, 14251.57245282643, 14390.28048597545, 14431.134131952116, 14434.350454229794, 14591.197484138474, 18067.548311291797, 18181.272749089887, 18186.36347727823, 18203.271869125616, 18204.36284730028], "value": 12494.85907491886, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1290.9999999999998, 1342.697892974081, 1347.919124852509, 1349.0961056092417, 1353.1678018018915, 14701.191434471113, 15225.086494810319, 15559.53321400357, 15570.610734731637, 15590.844091559082, 15981.84018159818, 16172.595685107879, 16227.188640567954, 16240.0, 16279.674406511893, 2844.5590933405356, 2895.666998295191, 2905.3898681276955, 2907.752841008517, 2908.461934542106, 2770.4320614274025, 2856.87144078517, 2857.057171133521, 2858.585505101759, 2871.8133321334153, 2722.768564672005, 2757.1177223288555, 2762.074704973826, 2762.309422644339, 2765.225736793691, 2699.0283497940727, 2795.9440811183867, 2802.075315145997, 2802.523570992926, 2802.9159125226274, 2804.1167032384938, 2828.702986186441, 2829.221963959907, 2829.6180015697823, 2838.262051866509, 14873.107613543221, 15083.849161508355, 15282.235888205545, 15285.159316237603, 15293.541193764206, 8196.180381961816, 8480.448770829877, 8483.533405662685, 8489.490630562186, 8494.70268540598, 5500.80747173851, 5722.885542289131, 5829.825105246807, 5858.443447872445, 5919.348871624118, 1865.9160337784833, 1937.1282922684848, 2000.4098790856733, 2013.6375452418558, 2023.8886861222627, 25999.74000259992, 26106.216813495626, 26217.344566385804, 26238.475230495475, 26300.21099367022, 6857.756726402676, 7017.508774385846, 7080.716771329192, 7149.821254468576, 7155.141383034069, 15977.360905563879, 16095.517134485985, 16134.677306453805, 16162.676746465006, 16169.919150404172, 7401.703931842773, 8020.5588692621295, 8168.632411541376, 8193.0, 8196.467229630054, 6423.518236132309, 6692.86614267712, 6696.531242813054, 7019.89470157948, 7233.638318084075, 5271.156614941591, 5385.626665200349, 5400.0, 5450.236966824647, 5453.481919217668, 1554.362711288389, 1595.369828917571, 1610.3558576569467, 1614.8546630803285, 1623.3117557740054, 827.3215962910356, 842.6418772021866, 853.4068822168533, 862.8964524257129, 873.0832625742944, 6856.725730970805, 6876.518643694893, 6940.444764418834, 6948.791536253921, 6995.93004069963, 712.9821754456076, 714.2999860137095, 717.8205448637841, 722.5520177489885, 728.5519405565539, 4249.128928569636, 4360.956390436118, 4418.60232579063, 4820.734859582686, 4948.0103979204305, 21338.893305533682, 21488.925553722253, 21702.674459883114, 21922.89038554797, 22280.66579001316, 21353.25263615766, 22049.22827701023, 22068.668969965463, 22100.226492073023, 22102.668459973112, 10313.174946004301, 10714.196435267386, 10726.141908647289, 10739.0, 10740.838887416696, 7879.212078792304, 8201.22088401601, 8369.62336694838, 8468.872966905377, 8473.661053558035, 18234.72647910309, 18508.537286567942, 18529.629407411507, 18594.25622975066, 18667.533311667317, 18805.811941880405, 18965.810341896955, 19008.42974710761, 19103.140358683613, 19162.52093697613, 11940.46267917963, 12141.635750927486, 12156.75686486283, 12163.635090947286, 12189.878101219228, 13976.301184940916, 14022.0, 14050.0, 14149.787753183504, 14153.43386264538, 10901.672949811518, 10982.06652434552, 11017.614383496539, 11103.0, 11207.327560346404, 11173.776524469627, 11263.605773797877, 11374.488148033193, 11489.770204596029, 11584.594539191088, 9583.137517623447, 9731.12419882214, 9743.26925480606, 9754.85367719499, 9804.901950980684, 7186.317299856714, 7359.043324367798, 7384.150822655249, 7406.481546291708, 7414.369778568882, 6896.793096207121, 6918.93081069203, 7042.7887163385185, 7050.506464547638, 7064.081669382947], "value": 6626.112418729129, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.464424320828, 528.8572085536904, 535.1517844216917, 536.5573401943398, 544.2326319888955, 6322.462590679792, 6328.873422531547, 6404.903926441107, 6412.487001039917, 6461.353864613537, 6168.228971378578, 6255.249370075598, 6302.936970630292, 6400.583962042464, 6426.678666066701, 5292.550133238679, 5305.7877684892555, 5314.176302673091, 5323.00387354742, 5347.41178470368, 4919.655624106314, 5285.814996475124, 5291.708956007418, 5315.627906046579, 5350.662334416396, 5097.770600322994, 5106.744662766865, 5122.8207012754565, 5150.768215430296, 5156.639035267533, 5111.514406131411, 5147.7426128693605, 5150.480608220584, 5157.5616072633875, 5169.612279079065, 4411.316245981878, 4558.202314594932, 4593.517680643517, 4608.124456353282, 4623.583877451046, 6231.781887633956, 6315.463185629227, 6332.588381755171, 6333.524985626051, 6337.271213810424, 5763.636908867345, 5838.2702162229725, 5859.970700146515, 5860.208871802295, 5876.882462350729, 6779.0, 6780.8304792380095, 6798.558093723942, 6802.251752307242, 6810.523263371566, 5005.724685142316, 5005.799768009275, 5014.824481143142, 5036.294918711384, 5036.496350364934, 6778.457723382117, 6941.652917354112, 6977.732237022937, 7029.156501219886, 7121.679524421414, 5020.523050310215, 5027.84916452507, 5058.64589478733, 5105.361829771278, 5142.665726727751, 5054.039732450822, 5119.334486516729, 5139.563137133386, 5161.74191290434, 5221.556167725786, 5709.543236541067, 5725.169850371756, 5745.597808153389, 5776.342882274201, 5836.474717275466, 6701.530892837454, 6713.832154196087, 6720.563163394364, 6823.055429202654, 6851.828704282333, 1786.4640607817603, 1795.7485951966735, 1802.9819701803076, 1815.1378095404577, 1816.6911625023777, 1174.3541052421135, 1195.7787809255315, 1203.6389083274983, 1232.371490539831, 1243.359669770069, 5811.912821307684, 5900.344844819762, 5911.9704401478575, 5933.643981361046, 5946.464818166386, 6766.323367663194, 6786.796396108125, 6871.278515755874, 6880.518363714492, 6908.827279318058, 5800.158976948319, 5855.707214639337, 5866.208061911672, 5896.941030589639, 5950.137230101611, 3584.856605735741, 3589.6410358963894, 3607.5129857469433, 3622.2574372253625, 3676.9080772980888, 6608.4052435281055, 6665.600063996176, 6695.560454502305, 6726.260111387744, 6765.424938880152, 6533.444657204096, 7006.092471624219, 7018.0, 7107.8933815992805, 7150.0, 6433.453156481752, 6532.248791388956, 6542.542022058506, 6638.634875081821, 6680.966595166993, 6742.528023038536, 6744.291849355747, 6776.424003959719, 6789.15135608049, 6931.75738849158, 6714.630695311805, 6737.0, 6813.322943515283, 6899.48253880971, 6911.930880691129, 6622.470202383702, 6630.602163870183, 6660.56706314088, 6716.731330746716, 6745.5952642841585, 4981.128302547112, 5067.974660126676, 5071.39143302806, 5125.7693403796175, 5126.564561922668, 6601.636909969998, 6704.726102040597, 6801.387875091265, 6963.895541566779, 7038.436925045881, 6622.966885165543, 6635.701393437211, 6685.465162787063, 6697.933020669731, 6748.426383757435, 6552.508561857784, 6565.409113179837, 6713.429358504582, 6717.529772915849, 6723.596584204964, 6510.609363438209, 6527.412532872065, 6571.704273307617, 6591.539441793097, 6651.534392592665, 6718.596884186964, 6747.561408508432, 6754.223264324665, 6760.86478270422, 6790.5925644461495, 6454.2900280968115, 6513.73945042193, 6520.445762110274, 6556.3443655635965, 6624.271330153583], "value": 5175.889140082001, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [887.4542156573709, 898.9730308090758, 912.4525284829103, 917.3028498341258, 926.3145272498351, 17556.73664895026, 18534.165962531686, 18626.06869656517, 18827.435176944713, 19194.136263868124, 20333.49166270844, 20389.694154587694, 21118.683219751714, 21343.466413339673, 21568.460788480297, 911.6216770040442, 934.0192797562567, 936.063936063936, 936.419419959626, 936.6721647423416, 923.1737594852602, 995.6714284286204, 1000.5897581991385, 1000.9449480278619, 1001.3040936549102, 1052.0584077250855, 1060.5386656804276, 1060.6870973062964, 1061.6072053340274, 1061.7186445591924, 1054.520193312041, 1054.69413869978, 1054.7995880782664, 1055.0820785916264, 1055.7624534479758, 767.5241350362755, 768.4774353439659, 770.0951382126035, 770.8496843115595, 770.865098607747, 17977.460676179737, 18112.366067187584, 18152.546186345317, 18481.907590462095, 19650.50873728154, 21306.0, 21612.135514579397, 22027.559448810935, 22096.447588810246, 22116.889415552818, 15175.62060948474, 15326.540203793904, 15394.307256173388, 15399.615009624737, 15761.842381576267, 3724.9627503724887, 3772.396416573335, 3775.5658099318657, 3777.225668737902, 3783.3190025795348, 122704.0, 125753.0, 126501.0, 127325.36337318347, 127449.36275318656, 15907.761383579256, 16519.66960660804, 16587.087710176056, 16658.16709164537, 16677.249523771217, 25330.366740831625, 25479.617805732927, 26054.478910421687, 26059.869700651376, 26078.0, 8125.796855078552, 8261.958690206511, 8265.793355166168, 8270.751877443687, 8274.462159959583, 4227.8097485613225, 4318.5033721121845, 4319.222539942841, 4323.113761678848, 4324.394584758136, 5144.511271429208, 5243.842684719464, 5246.108161612535, 5307.363116425976, 5312.07038768214, 958.3339578992673, 970.9320347575602, 983.6409710455632, 987.6247026130025, 989.0554520432969, 648.3775575447529, 673.6631684157921, 682.9863402731918, 690.7064497588505, 691.9965400172966, 10530.526126324334, 10766.461676916122, 10829.0, 10930.781384372269, 11014.39420831862, 600.0848705723719, 607.3501353551658, 614.0543562913064, 618.1685632823845, 622.5953130465143, 4999.575036121971, 5013.147764879979, 5022.74886255693, 5030.3963524377305, 5052.646314757932, 105378.47310763551, 107345.8412882468, 108071.0, 108737.0, 111863.0, 96343.51828240814, 97920.0, 98931.0, 101809.0, 104972.0, 81805.18194818095, 83720.16279837246, 84787.57606211929, 87437.5628121868, 87482.56258718665, 9281.86077208856, 9465.242780577399, 11185.72035699114, 11205.60780372683, 11302.491387887729, 31378.495117060822, 36934.63065369419, 38896.222075557765, 39399.212015760095, 40539.79730101331, 67001.0, 69370.3062969384, 70320.94518582123, 71929.6403517979, 72431.63784181046, 16128.435504757277, 16562.834371656612, 16821.158942052603, 17038.914805425895, 17086.0, 17981.73027404564, 18022.36921707734, 18033.72949405786, 18045.187966541274, 18250.269989200282, 16065.0, 16348.918255408647, 16532.83467165313, 16608.25262863198, 16613.252403642105, 16376.0, 16485.58786030359, 16800.57998549997, 16813.495595132164, 16913.91543042277, 15816.446424375092, 16038.518844434686, 16157.27292271827, 16258.837411625733, 16281.59296017609, 11822.0, 12060.577879774166, 12108.939455302667, 12261.816072759095, 12683.365831708194, 12463.439145238668, 12675.936620317209, 12728.554500592434, 12734.299613521345, 12878.871211287767], "value": 8731.19525485791, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1485.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 102.0, 108.0, 107.0, 111.0, 107.0, 97.0, 109.0, 108.0], "value": 105.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 140.0, 171.0, 121.0, 141.0, 142.0, 120.0, 190.0, 120.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 161.0, 162.0, 161.0, 160.0, 162.0, 163.0, 162.0, 164.0, 167.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 76.0, 183.0, 75.0, 76.0, 80.0, 77.0, 149.0, 75.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 138.0, 79.0, 79.0, 80.0, 130.0, 79.0, 79.0, 122.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 115.0, 116.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 149.0, 148.0, 150.0, 149.0, 149.0, 148.0, 150.0, 148.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 58.0, 52.0, 52.0, 52.0, 52.0, 51.0, 52.0, 51.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 45.0, 38.0, 39.0, 39.0], "value": 39.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 74.0, 75.0, 73.0, 74.0, 76.0, 74.0, 80.0, 76.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 129.0, 120.0, 116.0, 122.0, 116.0, 142.0, 122.0, 127.0, 156.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 175.0, 168.0, 169.0, 170.0, 170.0, 172.0, 175.0, 164.0, 172.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 69.0, 64.0, 65.0, 66.0, 64.0, 65.0, 66.0, 63.0], "value": 65.8, "unit": "score"}]}]} 03:35:55 INFO - Return code: 0 03:35:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:35:55 INFO - # TBPL SUCCESS # 03:35:55 INFO - Running post-action listener: _package_coverage_data 03:35:55 INFO - Running post-action listener: _resource_record_post_action 03:35:55 INFO - [mozharness: 2017-09-04 10:35:55.307450Z] Finished run-tests step (success) 03:35:55 INFO - Running post-run listener: _resource_record_post_run 03:35:55 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 7102464; Write bytes: 393453568; Read time: 3504; Write time: 413520 03:35:55 INFO - TinderboxPrint: CPU usage
13.1% 03:35:55 INFO - TinderboxPrint: I/O read bytes / time
7,102,464 / 3,504 03:35:55 INFO - TinderboxPrint: I/O write bytes / time
393,453,568 / 413,520 03:35:55 INFO - TinderboxPrint: CPU idle
8,269.3 (86.9%) 03:35:55 INFO - TinderboxPrint: CPU user
1,193.2 (12.5%) 03:35:55 INFO - TinderboxPrint: Swap in / out
0 / 0 03:35:55 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 106643456; Read time: 100; Write time: 108572 03:35:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:35:55 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 5353472; Write bytes: 286810112; Read time: 3340; Write time: 304948 03:35:55 INFO - Running post-run listener: _upload_blobber_files 03:35:55 INFO - Blob upload gear active. 03:35:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:35:55 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 03:35:55 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'] 03:35:55 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 03:35:56 INFO - (blobuploader) - INFO - Open directory for files ... 03:35:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:35:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:35:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:35:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:35:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:35:57 INFO - (blobuploader) - INFO - Done attempting. 03:35:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:35:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:35:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:36:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:00 INFO - (blobuploader) - INFO - Done attempting. 03:36:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:36:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:36:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:02 INFO - (blobuploader) - INFO - Done attempting. 03:36:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:36:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:36:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:03 INFO - (blobuploader) - INFO - Done attempting. 03:36:03 INFO - (blobuploader) - INFO - Iteration through files over. 03:36:03 INFO - Return code: 0 03:36:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:36:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:36:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dc09913c838f14c9eef45af1194f3944b2085a7753a18dbe10f65a769de029f5be46f1bb88a3b4e78af758b96b20298b4d9d7463fb31332f9fe31b00f7fe87ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8d80c52b68c5a0be40ba2ea386e56522a0dd1543ae7e20968f688876bddb963773331607822f01b1d88cee2d74c38960d7968635895b324b6b86c9e16930e19", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/91ae4ae39ab03121d025794912e4068121408a66ecfd4876d5e40e93fb42ff536e327daaf1405f120ed07c11b7b98f1d1878abfdf1b56f1192206640dbd940f4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4f5c317e6fc094dc71e396728418abcfed50c0c369db5883aa9c4b6688c3125c02b6a2fb064d6dfca3d89ba9743672ae17c4dc96608ee5aa79f4b3715887dcd2"} 03:36:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:36:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:36:03 INFO - Contents: 03:36:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dc09913c838f14c9eef45af1194f3944b2085a7753a18dbe10f65a769de029f5be46f1bb88a3b4e78af758b96b20298b4d9d7463fb31332f9fe31b00f7fe87ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8d80c52b68c5a0be40ba2ea386e56522a0dd1543ae7e20968f688876bddb963773331607822f01b1d88cee2d74c38960d7968635895b324b6b86c9e16930e19", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/91ae4ae39ab03121d025794912e4068121408a66ecfd4876d5e40e93fb42ff536e327daaf1405f120ed07c11b7b98f1d1878abfdf1b56f1192206640dbd940f4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4f5c317e6fc094dc71e396728418abcfed50c0c369db5883aa9c4b6688c3125c02b6a2fb064d6dfca3d89ba9743672ae17c4dc96608ee5aa79f4b3715887dcd2"} 03:36:03 INFO - Running post-run listener: copy_logs_to_upload_dir 03:36:03 INFO - Copying logs to upload dir... 03:36:03 INFO - mkdir: /builds/slave/test/build/upload/logs 03:36:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.465652 ========= master_lag: 0.21 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-09-04 03:36:03.293347) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:36:03.298095) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496 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/dc09913c838f14c9eef45af1194f3944b2085a7753a18dbe10f65a769de029f5be46f1bb88a3b4e78af758b96b20298b4d9d7463fb31332f9fe31b00f7fe87ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8d80c52b68c5a0be40ba2ea386e56522a0dd1543ae7e20968f688876bddb963773331607822f01b1d88cee2d74c38960d7968635895b324b6b86c9e16930e19", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/91ae4ae39ab03121d025794912e4068121408a66ecfd4876d5e40e93fb42ff536e327daaf1405f120ed07c11b7b98f1d1878abfdf1b56f1192206640dbd940f4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4f5c317e6fc094dc71e396728418abcfed50c0c369db5883aa9c4b6688c3125c02b6a2fb064d6dfca3d89ba9743672ae17c4dc96608ee5aa79f4b3715887dcd2"} build_url:https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011063 build_url: 'https://queue.taskcluster.net/v1/task/ZH0GnAa4QqyzRlNalh5SXw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dc09913c838f14c9eef45af1194f3944b2085a7753a18dbe10f65a769de029f5be46f1bb88a3b4e78af758b96b20298b4d9d7463fb31332f9fe31b00f7fe87ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8d80c52b68c5a0be40ba2ea386e56522a0dd1543ae7e20968f688876bddb963773331607822f01b1d88cee2d74c38960d7968635895b324b6b86c9e16930e19", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/91ae4ae39ab03121d025794912e4068121408a66ecfd4876d5e40e93fb42ff536e327daaf1405f120ed07c11b7b98f1d1878abfdf1b56f1192206640dbd940f4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4f5c317e6fc094dc71e396728418abcfed50c0c369db5883aa9c4b6688c3125c02b6a2fb064d6dfca3d89ba9743672ae17c4dc96608ee5aa79f4b3715887dcd2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:36:03.341812) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:36:03.342108) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504520102.732310-2068136496 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004793 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:36:03.425680) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:36:03.428994) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:36:03.429289) ========= ========= Total master_lag: 0.42 =========