builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-089 starttime: 1503661883.5 results: success (0) revision: 3bbd8e25df3d92464b97bcb42413febd9041af6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:23.501220) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:23.502012) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:23.502301) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:23.530030) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:23.530432) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-25 04:51:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2017-08-25 04:51:23 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145412 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:23.699218) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:23.699594) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019118 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:23.758566) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-08-25 04:51:23.758884) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3bbd8e25df3d92464b97bcb42413febd9041af6f --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 3bbd8e25df3d92464b97bcb42413febd9041af6f --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=8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-25 04:51:23,816 truncating revision to first 12 chars 2017-08-25 04:51:23,816 Setting DEBUG logging. 2017-08-25 04:51:23,816 attempt 1/10 2017-08-25 04:51:23,816 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3bbd8e25df3d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-25 04:51:24,200 attempt 1/10 2017-08-25 04:51:25,007 current task status: no status available at this point. state: STARTED 2017-08-25 04:51:25,007 sleeping for 11.00s (attempt 1/10) 2017-08-25 04:51:36,019 attempt 2/10 2017-08-25 04:51:36,365 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-25 04:51:36,521 unpacking tar archive at: mozilla-central-3bbd8e25df3d/testing/mozharness/ program finished with exit code 0 elapsedTime=13.036986 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-08-25 04:51:36.812101) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:36.812577) ========= script_repo_revision: 3bbd8e25df3d92464b97bcb42413febd9041af6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:36.813153) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:36.813582) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-25 04:51:36.826601) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-08-25 04:51:36.827094) ========= /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=8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:51:37 INFO - MultiFileLogger online at 20170825 04:51:37 in /builds/slave/test 04:51:37 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 04:51:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:51:37 INFO - {'append_to_log': False, 04:51:37 INFO - 'base_work_dir': '/builds/slave/test', 04:51:37 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:51:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:51:37 INFO - 'branch': 'Firefox-Non-PGO', 04:51:37 INFO - 'buildbot_json_path': 'buildprops.json', 04:51:37 INFO - 'code_coverage': False, 04:51:37 INFO - 'config_files': ('talos/linux_config.py',), 04:51:37 INFO - 'default_actions': ('clobber', 04:51:37 INFO - 'read-buildbot-config', 04:51:37 INFO - 'download-and-extract', 04:51:37 INFO - 'populate-webroot', 04:51:37 INFO - 'create-virtualenv', 04:51:37 INFO - 'install', 04:51:37 INFO - 'setup-mitmproxy', 04:51:37 INFO - 'run-tests'), 04:51:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:51:37 INFO - 'disable_ccov_upload': False, 04:51:37 INFO - 'download_minidump_stackwalk': True, 04:51:37 INFO - 'download_symbols': 'ondemand', 04:51:37 INFO - 'enable_stylo': False, 04:51:37 INFO - 'enable_webrender': False, 04:51:37 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:51:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:51:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:51:37 INFO - 'gecko_profile': False, 04:51:37 INFO - 'gecko_profile_interval': 0, 04:51:37 INFO - 'installer_path': 'installer.exe', 04:51:37 INFO - 'log_level': 'info', 04:51:37 INFO - 'log_name': 'talos', 04:51:37 INFO - 'log_to_console': True, 04:51:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:51:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:51:37 INFO - 'opt_config_files': (), 04:51:37 INFO - 'pip_index': False, 04:51:37 INFO - 'suite': 'dromaeojs-e10s', 04:51:37 INFO - 'system_bits': '32', 04:51:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:51:37 INFO - 'title': 'talos-linux64-ix-089', 04:51:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:51:37 INFO - 'use_talos_json': True, 04:51:37 INFO - 'verify': 'False', 04:51:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:51:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:51:37 INFO - 'work_dir': 'build'} 04:51:37 INFO - [mozharness: 2017-08-25 11:51:37.021490Z] Running clobber step. 04:51:37 INFO - Running pre-action listener: _resource_record_pre_action 04:51:37 INFO - Running main action method: clobber 04:51:37 INFO - rmtree: /builds/slave/test/build 04:51:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:51:38 INFO - Running post-action listener: _resource_record_post_action 04:51:38 INFO - [mozharness: 2017-08-25 11:51:38.047356Z] Finished clobber step (success) 04:51:38 INFO - [mozharness: 2017-08-25 11:51:38.047452Z] Running read-buildbot-config step. 04:51:38 INFO - Running pre-action listener: _resource_record_pre_action 04:51:38 INFO - Running main action method: read_buildbot_config 04:51:38 INFO - Using buildbot properties: 04:51:38 INFO - { 04:51:38 INFO - "project": "", 04:51:38 INFO - "product": "firefox", 04:51:38 INFO - "who": "archaeopteryx@coole-files.de", 04:51:38 INFO - "installer_path": "public/build/target.tar.bz2", 04:51:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:51:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:51:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:51:38 INFO - "stage_platform": "linux64", 04:51:38 INFO - "basedir": "/builds/slave/test", 04:51:38 INFO - "buildnumber": 1101, 04:51:38 INFO - "platform": "ubuntu64_hw", 04:51:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:51:38 INFO - "slavebuilddir": "test", 04:51:38 INFO - "taskId": "OYDfVfWfTMGdu5xat4vYMg", 04:51:38 INFO - "branch": "mozilla-central", 04:51:38 INFO - "script_repo_revision": "production", 04:51:38 INFO - "revision": "3bbd8e25df3d92464b97bcb42413febd9041af6f", 04:51:38 INFO - "slavename": "talos-linux64-ix-089", 04:51:38 INFO - "repo_path": "mozilla-central" 04:51:38 INFO - } 04:51:38 INFO - Finding installer, test and symbols from parent task. 04:51:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYDfVfWfTMGdu5xat4vYMg'}, attempt #1 04:51:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYDfVfWfTMGdu5xat4vYMg'}, attempt #1 04:51:38 INFO - Task dependencies: Yd1KAK7PTkGgzWZxFL4DBA 04:51:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA'}, attempt #1 04:51:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 04:51:39 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.test_packages.json 04:51:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.crashreporter-symbols.zip 04:51:39 INFO - Running post-action listener: _resource_record_post_action 04:51:39 INFO - [mozharness: 2017-08-25 11:51:39.400639Z] Finished read-buildbot-config step (success) 04:51:39 INFO - [mozharness: 2017-08-25 11:51:39.400855Z] Running download-and-extract step. 04:51:39 INFO - Running pre-action listener: _resource_record_pre_action 04:51:39 INFO - Running main action method: download_and_extract 04:51:39 INFO - mkdir: /builds/slave/test/build/tests 04:51:39 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')]} 04:51:39 INFO - trying https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.test_packages.json 04:51:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:51:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:51:39 INFO - Downloaded 989 bytes. 04:51:39 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:51:39 INFO - Using the following test package requirements: 04:51:39 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:51:39 INFO - u'common': [u'target.common.tests.zip'], 04:51:39 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:51:39 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:51:39 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:51:39 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:51:39 INFO - u'mozbase': [u'target.common.tests.zip'], 04:51:39 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:51:39 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:51:39 INFO - u'web-platform': [u'target.common.tests.zip', 04:51:39 INFO - u'target.web-platform.tests.tar.gz'], 04:51:39 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:51:39 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:51:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip 04:51:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:51:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip into memory 04:51:41 INFO - Content-Length response header: 54980217 04:51:41 INFO - Bytes received: 54980217 04:51:52 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:51:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip 04:51:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:51:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.common.tests.zip into memory 04:51:53 INFO - Content-Length response header: 54980217 04:51:53 INFO - Bytes received: 54980217 04:52:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.talos.tests.zip 04:52:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:52:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.talos.tests.zip into memory 04:52:06 INFO - Content-Length response header: 14496611 04:52:06 INFO - Bytes received: 14496611 04:52:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:06 INFO - trying https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 04:52:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:52:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:52:09 INFO - Downloaded 61310919 bytes. 04:52:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 04:52:09 INFO - mkdir: /builds/slave/test/properties 04:52:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:52:09 INFO - Writing to file /builds/slave/test/properties/build_url 04:52:09 INFO - Contents: 04:52:09 INFO - build_url:https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 04:52:09 INFO - Running post-action listener: _resource_record_post_action 04:52:09 INFO - Running post-action listener: find_tests_for_verification 04:52:09 INFO - Running post-action listener: set_extra_try_arguments 04:52:09 INFO - [mozharness: 2017-08-25 11:52:09.125658Z] Finished download-and-extract step (success) 04:52:09 INFO - [mozharness: 2017-08-25 11:52:09.125884Z] Running populate-webroot step. 04:52:09 INFO - Running pre-action listener: _resource_record_pre_action 04:52:09 INFO - Running main action method: populate_webroot 04:52:09 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:52:09 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 04:52:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:52:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:52:09 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 04:52:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:52:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:52:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:09 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 04:52:09 INFO - 'talos_options': ['--stylo'], 04:52:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:52:09 INFO - 'tests': ['damp', 'tps']}, 04:52:09 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 04:52:09 INFO - 'talos_options': ['--stylo'], 04:52:09 INFO - 'tests': ['damp', 'tps']}, 04:52:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:52:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:52:09 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 04:52:09 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:52:09 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:52:09 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:52:09 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 04:52:09 INFO - 'talos_options': ['--stylo'], 04:52:09 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:52:09 INFO - 'other-e10s': {'tests': ['a11yr', 04:52:09 INFO - 'ts_paint', 04:52:09 INFO - 'tpaint', 04:52:09 INFO - 'sessionrestore', 04:52:09 INFO - 'sessionrestore_many_windows', 04:52:09 INFO - 'sessionrestore_no_auto_restore', 04:52:09 INFO - 'tabpaint']}, 04:52:09 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 04:52:09 INFO - 'tests': ['a11yr', 04:52:09 INFO - 'ts_paint', 04:52:09 INFO - 'tpaint', 04:52:09 INFO - 'sessionrestore', 04:52:09 INFO - 'sessionrestore_many_windows', 04:52:09 INFO - 'sessionrestore_no_auto_restore', 04:52:09 INFO - 'tabpaint']}, 04:52:09 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 04:52:09 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 04:52:09 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 04:52:09 INFO - 'tests': ['bloom_basic_singleton']}, 04:52:09 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 04:52:09 INFO - 'tests': ['bloom_basic']}, 04:52:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:52:09 INFO - 'tsvgr_opacity', 04:52:09 INFO - 'tart', 04:52:09 INFO - 'tscrollx', 04:52:09 INFO - 'tsvg_static']}, 04:52:09 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 04:52:09 INFO - 'tests': ['tsvgx', 04:52:09 INFO - 'tsvgr_opacity', 04:52:09 INFO - 'tart', 04:52:09 INFO - 'tscrollx', 04:52:09 INFO - 'tsvg_static']}, 04:52:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:52:09 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 04:52:09 INFO - 'talos_options': ['--stylo'], 04:52:09 INFO - 'tests': ['tp5o']}, 04:52:09 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:52:09 INFO - 'talos_options': ['--mitmproxy', 04:52:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:52:09 INFO - '--firstNonBlankPaint'], 04:52:09 INFO - 'tests': ['tp6_google', 04:52:09 INFO - 'tp6_youtube', 04:52:09 INFO - 'tp6_amazon', 04:52:09 INFO - 'tp6_facebook']}, 04:52:09 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:52:09 INFO - 'talos_options': ['--stylo', 04:52:09 INFO - '--mitmproxy', 04:52:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:52:09 INFO - '--firstNonBlankPaint'], 04:52:09 INFO - 'tests': ['tp6_google', 04:52:09 INFO - 'tp6_youtube', 04:52:09 INFO - 'tp6_amazon', 04:52:09 INFO - 'tp6_facebook']}, 04:52:09 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:52:09 INFO - 'talos_options': ['--stylo', 04:52:09 INFO - '--stylo-threads=1', 04:52:09 INFO - '--mitmproxy', 04:52:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:52:09 INFO - '--firstNonBlankPaint'], 04:52:09 INFO - 'tests': ['tp6_google', 04:52:09 INFO - 'tp6_youtube', 04:52:09 INFO - 'tp6_amazon', 04:52:09 INFO - 'tp6_facebook']}, 04:52:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:52:09 INFO - 'talos_options': ['--xperf_path', 04:52:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:09 INFO - 'tests': ['tp5n']}, 04:52:09 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 04:52:09 INFO - 'talos_options': ['--stylo', 04:52:09 INFO - '--xperf_path', 04:52:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:09 INFO - 'tests': ['tp5n']}}} 04:52:09 INFO - Running post-action listener: _resource_record_post_action 04:52:09 INFO - [mozharness: 2017-08-25 11:52:09.150692Z] Finished populate-webroot step (success) 04:52:09 INFO - [mozharness: 2017-08-25 11:52:09.150781Z] Running create-virtualenv step. 04:52:09 INFO - Running pre-action listener: _resource_record_pre_action 04:52:09 INFO - Running main action method: create_virtualenv 04:52:09 INFO - Creating virtualenv /builds/slave/test/build/venv 04:52:09 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:52:09 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:52:09 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:52:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:52:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:52:09 INFO - Installing distribute.............................................................................................................................................................................................done. 04:52:10 INFO - Installing pip...............done. 04:52:10 INFO - Return code: 0 04:52:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:52:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:52:10 INFO - Reading from file tmpfile_stdout 04:52:10 INFO - Output received: 04:52:10 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:52:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:10 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 0x17f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b64f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b62130>, '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 0x1b63030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b634c0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', '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 04:52:10 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 04:52:10 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 04:52:10 INFO - Using env: {'DISPLAY': ':0', 04:52:10 INFO - 'HOME': '/home/cltbld', 04:52:10 INFO - 'LANG': 'en_US.UTF-8', 04:52:10 INFO - 'LANGUAGE': 'en_US:en', 04:52:10 INFO - 'LOGNAME': 'cltbld', 04:52:10 INFO - 'MAIL': '/var/mail/cltbld', 04:52:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:10 INFO - 'MOZ_NO_REMOTE': '1', 04:52:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:10 INFO - 'NO_EM_RESTART': '1', 04:52:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:10 INFO - 'PWD': '/builds/slave/test', 04:52:10 INFO - 'SHELL': '/bin/bash', 04:52:10 INFO - 'SHLVL': '1', 04:52:10 INFO - 'TERM': 'linux', 04:52:10 INFO - 'TMOUT': '86400', 04:52:10 INFO - 'USER': 'cltbld', 04:52:10 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:10 INFO - '_': '/tools/buildbot/bin/python'} 04:52:11 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:52:11 INFO - Downloading/unpacking pip>=1.5 04:52:11 INFO - Running setup.py egg_info for package pip 04:52:11 INFO - warning: no files found matching 'pip/cacert.pem' 04:52:11 INFO - warning: no files found matching '*.html' under directory 'docs' 04:52:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:52:11 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:52:11 INFO - Installing collected packages: pip 04:52:11 INFO - Found existing installation: pip 1.1 04:52:11 INFO - Uninstalling pip: 04:52:11 INFO - Successfully uninstalled pip 04:52:11 INFO - Running setup.py install for pip 04:52:11 INFO - warning: no files found matching 'pip/cacert.pem' 04:52:11 INFO - warning: no files found matching '*.html' under directory 'docs' 04:52:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:52:11 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:52:11 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:52:11 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:52:11 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:52:11 INFO - Successfully installed pip 04:52:11 INFO - Cleaning up... 04:52:11 INFO - Return code: 0 04:52:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:52:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:11 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 0x17f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b64f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b62130>, '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 0x1b63030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b634c0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', '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 04:52:11 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 04:52:11 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 04:52:11 INFO - Using env: {'DISPLAY': ':0', 04:52:11 INFO - 'HOME': '/home/cltbld', 04:52:11 INFO - 'LANG': 'en_US.UTF-8', 04:52:11 INFO - 'LANGUAGE': 'en_US:en', 04:52:11 INFO - 'LOGNAME': 'cltbld', 04:52:11 INFO - 'MAIL': '/var/mail/cltbld', 04:52:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:11 INFO - 'MOZ_NO_REMOTE': '1', 04:52:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:11 INFO - 'NO_EM_RESTART': '1', 04:52:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:11 INFO - 'PWD': '/builds/slave/test', 04:52:11 INFO - 'SHELL': '/bin/bash', 04:52:11 INFO - 'SHLVL': '1', 04:52:11 INFO - 'TERM': 'linux', 04:52:11 INFO - 'TMOUT': '86400', 04:52:11 INFO - 'USER': 'cltbld', 04:52:11 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:11 INFO - '_': '/tools/buildbot/bin/python'} 04:52:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:11 INFO - Downloading/unpacking psutil>=3.1.1 04:52:11 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 04:52:11 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 04:52:11 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 04:52:11 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 04:52:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:52:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:12 INFO - Installing collected packages: psutil 04:52:12 INFO - Running setup.py install for psutil 04:52:12 INFO - building 'psutil._psutil_linux' extension 04:52:12 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 04:52:13 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 04:52:13 INFO - building 'psutil._psutil_posix' extension 04:52:13 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 04:52:13 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 04:52:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:13 INFO - Successfully installed psutil 04:52:13 INFO - Cleaning up... 04:52:13 INFO - Return code: 0 04:52:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:52:13 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')]} 04:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:13 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')]} 04:52:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:13 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 0x17f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b64f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b62130>, '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 0x1b63030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b634c0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', '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 04:52:13 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 04:52:13 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 04:52:13 INFO - Using env: {'DISPLAY': ':0', 04:52:13 INFO - 'HOME': '/home/cltbld', 04:52:13 INFO - 'LANG': 'en_US.UTF-8', 04:52:13 INFO - 'LANGUAGE': 'en_US:en', 04:52:13 INFO - 'LOGNAME': 'cltbld', 04:52:13 INFO - 'MAIL': '/var/mail/cltbld', 04:52:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:13 INFO - 'MOZ_NO_REMOTE': '1', 04:52:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:13 INFO - 'NO_EM_RESTART': '1', 04:52:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:13 INFO - 'PWD': '/builds/slave/test', 04:52:13 INFO - 'SHELL': '/bin/bash', 04:52:13 INFO - 'SHLVL': '1', 04:52:13 INFO - 'TERM': 'linux', 04:52:13 INFO - 'TMOUT': '86400', 04:52:13 INFO - 'USER': 'cltbld', 04:52:13 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:13 INFO - '_': '/tools/buildbot/bin/python'} 04:52:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:52:13 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 04:52:13 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 04:52:13 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 04:52:13 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 04:52:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:52:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:52:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:52:14 INFO - Installing collected packages: mozsystemmonitor 04:52:14 INFO - Running setup.py install for mozsystemmonitor 04:52:14 INFO - Successfully installed mozsystemmonitor 04:52:14 INFO - Cleaning up... 04:52:14 INFO - Return code: 0 04:52:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:52:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b64f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b62130>, '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 0x1b63030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b634c0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', '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 04:52:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:52:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:52:14 INFO - Using env: {'DISPLAY': ':0', 04:52:14 INFO - 'HOME': '/home/cltbld', 04:52:14 INFO - 'LANG': 'en_US.UTF-8', 04:52:14 INFO - 'LANGUAGE': 'en_US:en', 04:52:14 INFO - 'LOGNAME': 'cltbld', 04:52:14 INFO - 'MAIL': '/var/mail/cltbld', 04:52:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:14 INFO - 'MOZ_NO_REMOTE': '1', 04:52:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:14 INFO - 'NO_EM_RESTART': '1', 04:52:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:14 INFO - 'PWD': '/builds/slave/test', 04:52:14 INFO - 'SHELL': '/bin/bash', 04:52:14 INFO - 'SHLVL': '1', 04:52:14 INFO - 'TERM': 'linux', 04:52:14 INFO - 'TMOUT': '86400', 04:52:14 INFO - 'USER': 'cltbld', 04:52:14 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:14 INFO - '_': '/tools/buildbot/bin/python'} 04:52:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:15 INFO - Downloading/unpacking jsonschema==2.5.1 04:52:15 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 04:52:15 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 04:52:15 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 04:52:15 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 04:52:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:52:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:52:16 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 04:52:16 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 04:52:16 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 04:52:16 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 04:52:16 INFO - Downloading functools32-3.2.3-2.tar.gz 04:52:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:52:16 INFO - warning: no files found matching '*.txt' 04:52:16 INFO - no previously-included directories found matching 'build' 04:52:16 INFO - no previously-included directories found matching 'dist' 04:52:16 INFO - no previously-included directories found matching '.git*' 04:52:16 INFO - Installing collected packages: jsonschema, functools32 04:52:16 INFO - Running setup.py install for functools32 04:52:16 INFO - warning: no files found matching '*.txt' 04:52:16 INFO - no previously-included directories found matching 'build' 04:52:16 INFO - no previously-included directories found matching 'dist' 04:52:16 INFO - no previously-included directories found matching '.git*' 04:52:16 INFO - Successfully installed jsonschema functools32 04:52:16 INFO - Cleaning up... 04:52:16 INFO - Return code: 0 04:52:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:52:16 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')]} 04:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:16 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')]} 04:52:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:16 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 0x17f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b64f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b62130>, '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 0x1b63030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b634c0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', '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 04:52:16 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 04:52:16 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 04:52:16 INFO - Using env: {'DISPLAY': ':0', 04:52:16 INFO - 'HOME': '/home/cltbld', 04:52:16 INFO - 'LANG': 'en_US.UTF-8', 04:52:16 INFO - 'LANGUAGE': 'en_US:en', 04:52:16 INFO - 'LOGNAME': 'cltbld', 04:52:16 INFO - 'MAIL': '/var/mail/cltbld', 04:52:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:16 INFO - 'MOZ_NO_REMOTE': '1', 04:52:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:16 INFO - 'NO_EM_RESTART': '1', 04:52:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:16 INFO - 'PWD': '/builds/slave/test', 04:52:16 INFO - 'SHELL': '/bin/bash', 04:52:16 INFO - 'SHLVL': '1', 04:52:16 INFO - 'TERM': 'linux', 04:52:16 INFO - 'TMOUT': '86400', 04:52:16 INFO - 'USER': 'cltbld', 04:52:16 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:16 INFO - '_': '/tools/buildbot/bin/python'} 04:52:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:52:16 INFO - Cleaning up... 04:52:16 INFO - Return code: 0 04:52:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:52:16 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')]} 04:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:16 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')]} 04:52:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:16 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 0x17f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b64f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b62130>, '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 0x1b63030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b634c0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', '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 04:52:16 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 04:52:16 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 04:52:16 INFO - Using env: {'DISPLAY': ':0', 04:52:16 INFO - 'HOME': '/home/cltbld', 04:52:16 INFO - 'LANG': 'en_US.UTF-8', 04:52:16 INFO - 'LANGUAGE': 'en_US:en', 04:52:16 INFO - 'LOGNAME': 'cltbld', 04:52:16 INFO - 'MAIL': '/var/mail/cltbld', 04:52:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:16 INFO - 'MOZ_NO_REMOTE': '1', 04:52:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:16 INFO - 'NO_EM_RESTART': '1', 04:52:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:16 INFO - 'PWD': '/builds/slave/test', 04:52:16 INFO - 'SHELL': '/bin/bash', 04:52:16 INFO - 'SHLVL': '1', 04:52:16 INFO - 'TERM': 'linux', 04:52:16 INFO - 'TMOUT': '86400', 04:52:16 INFO - 'USER': 'cltbld', 04:52:16 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:16 INFO - '_': '/tools/buildbot/bin/python'} 04:52:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:16 INFO - Downloading/unpacking blobuploader==1.2.4 04:52:16 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 04:52:16 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 04:52:16 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 04:52:16 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 04:52:18 INFO - Downloading blobuploader-1.2.4.tar.gz 04:52:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:52:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:52:18 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 04:52:18 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 04:52:18 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 04:52:18 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 04:52:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:52:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:52:18 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 04:52:18 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 04:52:18 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 04:52:18 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 04:52:18 INFO - Downloading docopt-0.6.1.tar.gz 04:52:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:52:18 INFO - Installing collected packages: blobuploader, requests, docopt 04:52:18 INFO - Running setup.py install for blobuploader 04:52:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:52:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:52:18 INFO - Running setup.py install for requests 04:52:19 INFO - Running setup.py install for docopt 04:52:19 INFO - Successfully installed blobuploader requests docopt 04:52:19 INFO - Cleaning up... 04:52:19 INFO - Return code: 0 04:52:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52:19 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')]} 04:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:19 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')]} 04:52:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:19 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 0x17f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b64f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b62130>, '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 0x1b63030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b634c0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', '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 04:52:19 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 04:52:19 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 04:52:19 INFO - Using env: {'DISPLAY': ':0', 04:52:19 INFO - 'HOME': '/home/cltbld', 04:52:19 INFO - 'LANG': 'en_US.UTF-8', 04:52:19 INFO - 'LANGUAGE': 'en_US:en', 04:52:19 INFO - 'LOGNAME': 'cltbld', 04:52:19 INFO - 'MAIL': '/var/mail/cltbld', 04:52:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:19 INFO - 'MOZ_NO_REMOTE': '1', 04:52:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:19 INFO - 'NO_EM_RESTART': '1', 04:52:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:19 INFO - 'PWD': '/builds/slave/test', 04:52:19 INFO - 'SHELL': '/bin/bash', 04:52:19 INFO - 'SHLVL': '1', 04:52:19 INFO - 'TERM': 'linux', 04:52:19 INFO - 'TMOUT': '86400', 04:52:19 INFO - 'USER': 'cltbld', 04:52:19 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:19 INFO - '_': '/tools/buildbot/bin/python'} 04:52:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:52:19 INFO - Running setup.py (path:/tmp/pip-u7u6sS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:52:19 INFO - Running setup.py (path:/tmp/pip-Z7HRa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:52:19 INFO - Running setup.py (path:/tmp/pip-G3qIu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:52:19 INFO - Running setup.py (path:/tmp/pip-HTL6iU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:52:19 INFO - Running setup.py (path:/tmp/pip-F9KJmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:52:19 INFO - Running setup.py (path:/tmp/pip-NwuMSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:52:19 INFO - Running setup.py (path:/tmp/pip-Xpsw4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:52:19 INFO - Running setup.py (path:/tmp/pip-pQxko7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:52:20 INFO - Running setup.py (path:/tmp/pip-X7wKwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:52:20 INFO - Running setup.py (path:/tmp/pip-7YuFJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:52:20 INFO - Running setup.py (path:/tmp/pip-C3BW29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:52:20 INFO - Running setup.py (path:/tmp/pip-jQHi5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:52:20 INFO - Running setup.py (path:/tmp/pip-7vAI_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:52:20 INFO - Running setup.py (path:/tmp/pip-GNE_x6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:20 INFO - Running setup.py (path:/tmp/pip-Y8RszC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:52:20 INFO - Running setup.py (path:/tmp/pip-PhR2_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:52:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:52:20 INFO - Running setup.py (path:/tmp/pip-gmMha0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:52:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:52:20 INFO - Running setup.py install for manifestparser 04:52:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:52:20 INFO - Running setup.py install for mozcrash 04:52:20 INFO - Running setup.py install for mozdebug 04:52:20 INFO - Running setup.py install for mozdevice 04:52:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Running setup.py install for mozfile 04:52:21 INFO - Running setup.py install for mozhttpd 04:52:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Running setup.py install for mozinfo 04:52:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Running setup.py install for mozInstall 04:52:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Running setup.py install for mozleak 04:52:21 INFO - Running setup.py install for mozlog 04:52:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Running setup.py install for moznetwork 04:52:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Running setup.py install for mozprocess 04:52:21 INFO - Running setup.py install for mozprofile 04:52:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:52:21 INFO - Running setup.py install for mozrunner 04:52:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:52:22 INFO - Running setup.py install for mozscreenshot 04:52:22 INFO - Running setup.py install for moztest 04:52:22 INFO - Running setup.py install for mozversion 04:52:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:52:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:52:22 INFO - Cleaning up... 04:52:22 INFO - Return code: 0 04:52:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:22 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 0x17f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b64f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b62130>, '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 0x1b63030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b634c0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', '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 04:52:22 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 04:52:22 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 04:52:22 INFO - Using env: {'DISPLAY': ':0', 04:52:22 INFO - 'HOME': '/home/cltbld', 04:52:22 INFO - 'LANG': 'en_US.UTF-8', 04:52:22 INFO - 'LANGUAGE': 'en_US:en', 04:52:22 INFO - 'LOGNAME': 'cltbld', 04:52:22 INFO - 'MAIL': '/var/mail/cltbld', 04:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:22 INFO - 'MOZ_NO_REMOTE': '1', 04:52:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:22 INFO - 'NO_EM_RESTART': '1', 04:52:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:22 INFO - 'PWD': '/builds/slave/test', 04:52:22 INFO - 'SHELL': '/bin/bash', 04:52:22 INFO - 'SHLVL': '1', 04:52:22 INFO - 'TERM': 'linux', 04:52:22 INFO - 'TMOUT': '86400', 04:52:22 INFO - 'USER': 'cltbld', 04:52:22 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:22 INFO - '_': '/tools/buildbot/bin/python'} 04:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:52:22 INFO - Running setup.py (path:/tmp/pip-tsHe7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:52:22 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)) 04:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:52:22 INFO - Running setup.py (path:/tmp/pip-6kd8i5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:52:22 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)) 04:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:52:22 INFO - Running setup.py (path:/tmp/pip-C8TCv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:52:22 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)) 04:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:52:22 INFO - Running setup.py (path:/tmp/pip-byRc2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:52:22 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)) 04:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:52:22 INFO - Running setup.py (path:/tmp/pip-jCEmv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:52:23 INFO - Running setup.py (path:/tmp/pip-Q6_77p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:52:23 INFO - Running setup.py (path:/tmp/pip-i9mrid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:52:23 INFO - Running setup.py (path:/tmp/pip-DLMV2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:52:23 INFO - Running setup.py (path:/tmp/pip-OB7DLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:52:23 INFO - Running setup.py (path:/tmp/pip-6nlPK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:52:23 INFO - Running setup.py (path:/tmp/pip-7l3NRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:52:23 INFO - Running setup.py (path:/tmp/pip-vJnLso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:52:23 INFO - Running setup.py (path:/tmp/pip-4Lv1iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:52:23 INFO - Running setup.py (path:/tmp/pip-ekKq1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:23 INFO - Running setup.py (path:/tmp/pip-MXRHc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:52:23 INFO - Running setup.py (path:/tmp/pip-x4jq54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:52:23 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)) 04:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:52:23 INFO - Running setup.py (path:/tmp/pip-D1YHoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:52:23 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)) 04:52:23 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)) 04:52:23 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)) 04:52:23 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)) 04:52:23 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)) 04:52:23 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)) 04:52:23 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)) 04:52:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:24 INFO - Downloading blessings-1.6.tar.gz 04:52:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:52:25 INFO - Installing collected packages: blessings 04:52:25 INFO - Running setup.py install for blessings 04:52:25 INFO - Successfully installed blessings 04:52:25 INFO - Cleaning up... 04:52:25 INFO - Return code: 0 04:52:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:52:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:52:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:52:25 INFO - Reading from file tmpfile_stdout 04:52:25 INFO - Current package versions: 04:52:25 INFO - argparse == 1.2.1 04:52:25 INFO - blessings == 1.6 04:52:25 INFO - blobuploader == 1.2.4 04:52:25 INFO - docopt == 0.6.1 04:52:25 INFO - functools32 == 3.2.3-2 04:52:25 INFO - jsonschema == 2.5.1 04:52:25 INFO - manifestparser == 1.1 04:52:25 INFO - mozInstall == 1.13 04:52:25 INFO - mozcrash == 1.0 04:52:25 INFO - mozdebug == 0.1 04:52:25 INFO - mozdevice == 0.51 04:52:25 INFO - mozfile == 1.2 04:52:25 INFO - mozhttpd == 0.7 04:52:25 INFO - mozinfo == 0.10 04:52:25 INFO - mozleak == 0.1 04:52:25 INFO - mozlog == 3.5 04:52:25 INFO - moznetwork == 0.27 04:52:25 INFO - mozprocess == 0.25 04:52:25 INFO - mozprofile == 0.28 04:52:25 INFO - mozrunner == 6.13 04:52:25 INFO - mozscreenshot == 0.1 04:52:25 INFO - mozsystemmonitor == 0.3 04:52:25 INFO - moztest == 0.8 04:52:25 INFO - mozversion == 1.4 04:52:25 INFO - psutil == 3.1.1 04:52:25 INFO - requests == 1.2.3 04:52:25 INFO - wsgiref == 0.1.2 04:52:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:25 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 0x17f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b64f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b62130>, '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 0x1b63030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b634c0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', '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 04:52:25 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 04:52:25 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 04:52:25 INFO - Using env: {'DISPLAY': ':0', 04:52:25 INFO - 'HOME': '/home/cltbld', 04:52:25 INFO - 'LANG': 'en_US.UTF-8', 04:52:25 INFO - 'LANGUAGE': 'en_US:en', 04:52:25 INFO - 'LOGNAME': 'cltbld', 04:52:25 INFO - 'MAIL': '/var/mail/cltbld', 04:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:25 INFO - 'MOZ_NO_REMOTE': '1', 04:52:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:25 INFO - 'NO_EM_RESTART': '1', 04:52:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:25 INFO - 'PWD': '/builds/slave/test', 04:52:25 INFO - 'SHELL': '/bin/bash', 04:52:25 INFO - 'SHLVL': '1', 04:52:25 INFO - 'TERM': 'linux', 04:52:25 INFO - 'TMOUT': '86400', 04:52:25 INFO - 'USER': 'cltbld', 04:52:25 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:25 INFO - '_': '/tools/buildbot/bin/python'} 04:52:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:25 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)) 04:52:25 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)) 04:52:25 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)) 04:52:25 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)) 04:52:25 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)) 04:52:25 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)) 04:52:25 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)) 04:52:25 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)) 04:52:25 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)) 04:52:25 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:52:26 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)) 04:52:26 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)) 04:52:26 INFO - Installing collected packages: simplejson 04:52:26 INFO - Running setup.py install for simplejson 04:52:26 INFO - building 'simplejson._speedups' extension 04:52:26 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 04:52:27 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 04:52:27 INFO - Successfully installed simplejson 04:52:27 INFO - Cleaning up... 04:52:27 INFO - Return code: 0 04:52:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b64f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b62130>, '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 0x1b63030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b634c0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', '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 04:52:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:52:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:52:27 INFO - Using env: {'DISPLAY': ':0', 04:52:27 INFO - 'HOME': '/home/cltbld', 04:52:27 INFO - 'LANG': 'en_US.UTF-8', 04:52:27 INFO - 'LANGUAGE': 'en_US:en', 04:52:27 INFO - 'LOGNAME': 'cltbld', 04:52:27 INFO - 'MAIL': '/var/mail/cltbld', 04:52:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:27 INFO - 'MOZ_NO_REMOTE': '1', 04:52:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:27 INFO - 'NO_EM_RESTART': '1', 04:52:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:27 INFO - 'PWD': '/builds/slave/test', 04:52:27 INFO - 'SHELL': '/bin/bash', 04:52:27 INFO - 'SHLVL': '1', 04:52:27 INFO - 'TERM': 'linux', 04:52:27 INFO - 'TMOUT': '86400', 04:52:27 INFO - 'USER': 'cltbld', 04:52:27 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:27 INFO - '_': '/tools/buildbot/bin/python'} 04:52:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:52:28 INFO - Cleaning up... 04:52:28 INFO - Return code: 0 04:52:28 INFO - Running post-action listener: _resource_record_post_action 04:52:28 INFO - Running post-action listener: _start_resource_monitoring 04:52:28 INFO - Starting resource monitoring. 04:52:28 INFO - [mozharness: 2017-08-25 11:52:28.118668Z] Finished create-virtualenv step (success) 04:52:28 INFO - [mozharness: 2017-08-25 11:52:28.119254Z] Running install step. 04:52:28 INFO - Running pre-action listener: _resource_record_pre_action 04:52:28 INFO - Running main action method: install 04:52:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:52:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:52:28 INFO - Reading from file tmpfile_stdout 04:52:28 INFO - Detecting whether we're running mozinstall >=1.0... 04:52:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:52:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:52:28 INFO - Reading from file tmpfile_stdout 04:52:28 INFO - Output received: 04:52:28 INFO - Usage: mozinstall [options] installer 04:52:28 INFO - Options: 04:52:28 INFO - -h, --help show this help message and exit 04:52:28 INFO - -d DEST, --destination=DEST 04:52:28 INFO - Directory to install application into. [default: 04:52:28 INFO - "/builds/slave/test"] 04:52:28 INFO - --app=APP Application being installed. [default: firefox] 04:52:28 INFO - mkdir: /builds/slave/test/build/application 04:52:28 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'] 04:52:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:52:46 INFO - Reading from file tmpfile_stdout 04:52:46 INFO - Output received: 04:52:46 INFO - /builds/slave/test/build/application/firefox/firefox 04:52:46 INFO - Running post-action listener: _resource_record_post_action 04:52:46 INFO - [mozharness: 2017-08-25 11:52:46.538872Z] Finished install step (success) 04:52:46 INFO - [mozharness: 2017-08-25 11:52:46.539218Z] Running setup-mitmproxy step. 04:52:46 INFO - Running pre-action listener: _resource_record_pre_action 04:52:46 INFO - Running main action method: setup_mitmproxy 04:52:46 INFO - Skipping: mitmproxy is not required 04:52:46 INFO - Running post-action listener: _resource_record_post_action 04:52:46 INFO - [mozharness: 2017-08-25 11:52:46.540345Z] Finished setup-mitmproxy step (success) 04:52:46 INFO - [mozharness: 2017-08-25 11:52:46.540564Z] Running run-tests step. 04:52:46 INFO - Running pre-action listener: _resource_record_pre_action 04:52:46 INFO - Running pre-action listener: _set_gcov_prefix 04:52:46 INFO - Running main action method: run_tests 04:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:52:46 INFO - Python 2.7.3 04:52:46 INFO - Return code: 0 04:52:46 INFO - grabbing minidump binary from tooltool 04:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:46 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 0x1b62130>, '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 0x1b63030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b634c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:52:46 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 04:52:46 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 04:52:46 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 04:52:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:52:46 INFO - Return code: 0 04:52:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:52:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:52:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:52:46 INFO - ENV: RUST_BACKTRACE is now full 04:52:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:52:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:52:46 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/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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 04:52:46 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/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-089 --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 04:52:46 INFO - Using env: {'DISPLAY': ':0', 04:52:46 INFO - 'HOME': '/home/cltbld', 04:52:46 INFO - 'LANG': 'en_US.UTF-8', 04:52:46 INFO - 'LANGUAGE': 'en_US:en', 04:52:46 INFO - 'LOGNAME': 'cltbld', 04:52:46 INFO - 'MAIL': '/var/mail/cltbld', 04:52:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:52:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:46 INFO - 'MOZ_NO_REMOTE': '1', 04:52:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:52:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:46 INFO - 'NO_EM_RESTART': '1', 04:52:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:46 INFO - 'PWD': '/builds/slave/test', 04:52:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:52:46 INFO - 'RUST_BACKTRACE': 'full', 04:52:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:52:46 INFO - 'SHELL': '/bin/bash', 04:52:46 INFO - 'SHLVL': '1', 04:52:46 INFO - 'TERM': 'linux', 04:52:46 INFO - 'TMOUT': '86400', 04:52:46 INFO - 'USER': 'cltbld', 04:52:46 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:46 INFO - '_': '/tools/buildbot/bin/python'} 04:52:46 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/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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 04:52:46 INFO - mozversion application_buildid: 20170825110814 04:52:46 INFO - mozversion application_changeset: 3bbd8e25df3d92464b97bcb42413febd9041af6f 04:52:46 INFO - mozversion application_display_name: Nightly 04:52:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:52:46 INFO - mozversion application_name: Firefox 04:52:46 INFO - mozversion application_remotingname: firefox 04:52:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:52:46 INFO - mozversion application_vendor: Mozilla 04:52:46 INFO - mozversion application_version: 57.0a1 04:52:46 INFO - mozversion platform_buildid: 20170825110814 04:52:46 INFO - mozversion platform_changeset: 3bbd8e25df3d92464b97bcb42413febd9041af6f 04:52:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:52:46 INFO - mozversion platform_version: 57.0a1 04:52:46 INFO - using testdate: 1503661966 04:52:46 INFO - actual date: 1503661966 04:52:46 INFO - starting webserver on 'localhost:42986' 04:52:46 INFO - SUITE-START | Running 2 tests 04:52:46 INFO - TEST-START | dromaeo_css 04:52:46 INFO - Initialising browser for dromaeo_css test... 04:52:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42986/getInfo.html -profile /tmp/tmpP6Bpt6/profile 04:52:46 INFO - TEST-INFO | started process 15379 (/builds/slave/test/build/application/firefox/firefox http://localhost:42986/getInfo.html) 04:52:52 INFO - TEST-INFO | 15379: exit 0 04:52:52 INFO - Browser initialized. 04:52:52 INFO - Running cycle 1/1 for dromaeo_css test... 04:52:52 INFO - Using env: {'DISPLAY': ':0', 04:52:52 INFO - 'HOME': '/home/cltbld', 04:52:52 INFO - 'JSGC_DISABLE_POISONING': '1', 04:52:52 INFO - 'LANG': 'en_US.UTF-8', 04:52:52 INFO - 'LANGUAGE': 'en_US:en', 04:52:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:52:52 INFO - 'LOGNAME': 'cltbld', 04:52:52 INFO - 'MAIL': '/var/mail/cltbld', 04:52:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:52:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:52:52 INFO - 'MOZ_CRASHREPORTER': '1', 04:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:52:52 INFO - 'MOZ_NO_REMOTE': '1', 04:52:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:52:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:52 INFO - 'NO_EM_RESTART': '1', 04:52:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:52 INFO - 'PWD': '/builds/slave/test', 04:52:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:52:52 INFO - 'RUST_BACKTRACE': 'full', 04:52:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:52:52 INFO - 'SHELL': '/bin/bash', 04:52:52 INFO - 'SHLVL': '1', 04:52:52 INFO - 'TERM': 'linux', 04:52:52 INFO - 'TMOUT': '86400', 04:52:52 INFO - 'USER': 'cltbld', 04:52:52 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 04:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:52 INFO - '_': '/tools/buildbot/bin/python'} 04:52:52 INFO - TEST-INFO | started process 15553 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP6Bpt6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:52:53 INFO - PID 15553 | 04:52:53 INFO - PID 15553 | (/builds/slave/test/build/application/firefox/firefox:15603): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:52:53 INFO - PID 15553 | 04:52:54 INFO - PID 15553 | 04:52:54 INFO - PID 15553 | (/builds/slave/test/build/application/firefox/firefox:15657): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:52:54 INFO - PID 15553 | 04:52:54 INFO - PID 15553 | __metrics Screen width/height:1600/1200 04:52:54 INFO - PID 15553 | colorDepth:24 04:52:54 INFO - PID 15553 | Browser inner width/height: 1024/768 04:52:54 INFO - PID 15553 | __metrics 04:53:04 INFO - PID 15553 | RSS: Main: 171737088 04:53:04 INFO - PID 15553 | 04:56:03 INFO - PID 15553 | Cycle 1(1): loaded http://localhost:42986/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42986/tests/dromaeo/cssquery-ext.html) 04:56:05 INFO - PID 15553 | RSS: Main: 173658112 04:56:05 INFO - PID 15553 | 04:59:06 INFO - PID 15553 | Cycle 1(1): loaded http://localhost:42986/tests/dromaeo/cssquery-ext.html (next: http://localhost:42986/tests/dromaeo/cssquery-jquery.html) 04:59:07 INFO - PID 15553 | RSS: Main: 172011520 04:59:07 INFO - PID 15553 | 05:02:09 INFO - PID 15553 | Cycle 1(1): loaded http://localhost:42986/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42986/tests/dromaeo/cssquery-mootools.html) 05:02:09 INFO - PID 15553 | RSS: Main: 174129152 05:02:09 INFO - PID 15553 | 05:05:11 INFO - PID 15553 | Cycle 1(1): loaded http://localhost:42986/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42986/tests/dromaeo/cssquery-prototype.html) 05:05:12 INFO - PID 15553 | RSS: Main: 172904448 05:05:12 INFO - PID 15553 | 05:08:15 INFO - PID 15553 | Cycle 1(1): loaded http://localhost:42986/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42986/tests/dromaeo/cssquery-yui.html) 05:08:16 INFO - PID 15553 | RSS: Main: 176795648 05:08:16 INFO - PID 15553 | 05:11:18 INFO - PID 15553 | Cycle 1(1): loaded http://localhost:42986/tests/dromaeo/cssquery-yui.html (next: http://localhost:42986/tests/dromaeo/cssquery-dojo.html) 05:11:18 INFO - PID 15553 | RSS: Main: 176992256 05:11:18 INFO - PID 15553 | 05:11:18 INFO - PID 15553 | __start_tp_report 05:11:18 INFO - PID 15553 | _x_x_mozilla_page_load 05:11:18 INFO - PID 15553 | _x_x_mozilla_page_load_details 05:11:18 INFO - PID 15553 | |i|pagename|runs| 05:11:18 INFO - PID 15553 | |0;dojo.html;3246.9837650811746;3295.719863811576;3331.550240717502;3334.5665063541737;3348.9665103348957;6301.2753533343675;6404.903926441107;6408.359164083589;6412.839679008021;6461.353864613537;6211.782587609435;6287.748490060403;6298.7165577549;6319.494440444765;6340.778072767454;5777.988851950902;5825.388334224909;5825.941740582603;5828.9417105828825;5885.4114588541015;5851.941480585182;5856.76572937082;5898.115282707607;5908.291005079396;5956.9873121569435;5829.271341082364;5888.293404791431;5898.587098903078;5912.645241285537;5913.172155898178;5581.079121944874;5746.051901436258;5815.244018277639;5857.794977175799;5868.501177399905;5658.943410565883;5683.943160568383;5741.913871291934;5776.942230577724;5829.795957141479;6286.49708023357;6323.462505687022;6323.715432805535;6347.555671103025;6374.649394283312;6487.7080531376205;6580.967095164541;6753.257141714456;6809.216940051859;6864.828379290508;6854.725810967555;6903.965480172617;6906.965465172692;6907.309269073103;6910.965445172742;6335.303116657164;6339.049142628643;6387.776427825051;6421.389967953037;6422.614643121427;6994.825129371807;6997.4052205562075;7079.115110611174;7089.7873063808165;7095.077639906779;6574.5397822152945;6689.531732778756;6727.159105111861;6747.628880411625;6761.830954226085;6773.187217533928;6877.9656101720175;6882.621455819878;6899.275576064541;6916.446684265246;6758.932410675929;6777.796666100025;6780.627065511346;6869.862602747917;6874.346937040973;6570.441512471398;6662.167229096363;6795.558288711218;6879.036934829128;6881.759138430131;5330.093884039723;5426.457354264542;5429;5429.212764149176;5431.728413579305;4685.99251161002;4745.620350371962;4755.690880092783;4758.714477131383;4798.136335459581;5831.88336233273;5876.353601103875;5892.499137573269;5894.705264736746;5914.083317085859;6751.628660423724;6765.898511522331;6768.56004359715;6797.626130562768;6809.421199198124;5910.911336329958;5927.318358388754;5929.3181284152015;6023.337432882379;6029.216201893726;5901.439363260483;5966.522678185735;5984.910226346608;5989.0716938873675;5993.220881285405;6541;6667.6666166691475;6680.432163266079;6780.7287708492095;6781.186257649114;6813.931860681329;6878.55289406187;6879.174499060145;6880.277570855188;6882.105326307548;6619.3049729779605;6660.234073081658;6712.966435167793;6736.225334086446;6755.93244067553;6679.398854102959;6683.43190828785;6768;6808.251092379735;6851.212110607344;6815.114035175396;6818.522703410714;6864.897026544706;6898.827529311808;6943.79168624942;6451.612903225821;6599.7360105595235;6602.8349291268105;6647.235567909559;6680.799576012728;6596.67016649156;6653.933460665525;6702.19573651165;6732.663366831541;6742.224644165787;6473.061406096184;6491;6564.5076619252795;6570.85010123236;6598.439132673777;6574.342565743387;6625.138731964814;6667.833304167435;6732.057511948429;6750.696218670073;6415.358464153547;6433.967830160819;6439.195100612424;6439.935600643934;6456.9354306456335 05:11:18 INFO - PID 15553 | |1;ext.html;760.7793739815454;828.1097819843669;846.6359465429866;855.1533981358457;1125.8311253312008;29334.85332573337;29822.850885745567;38570.8071459643;39396;39440.80279598605;40054.39918401219;40386.7980660097;40390.39414408786;40414.79792601033;40469.392959105564;7524.2851929066655;7634.3129118379475;7711.884321735179;7717;7773.689052437924;7614.2766437188375;7705.15243323234;7706.884396734054;7712.344450721667;7712.498687585317;7685.154632990367;7726.0342457192855;7734.14924358323;7739.071311442606;7740.380769538423;7670.7698769037015;7696.461247712662;7705.306522412981;7707.845843083163;7710.267524585155;7663.233676632348;7702.6918923243;7710.036245469317;7713.112992005879;7714;35745.82127089348;35909.28181436383;35955.460668089996;35961.64038359609;35988;3480.6345333739846;3515.8417871195857;3518.472229165646;3519.2433626770367;3522.894313170609;30257.394852103054;30356.84821575878;30358;30377;30459.23851903698;4925.285833554115;5058.974705126488;5075.923861142086;5111.386633603955;5129.230615407719;186854;187221;187280;187416.06291968588;187450;46313.30530042052;46410;46532;46638.300425493646;46776.38683358019;50939.490605094215;51849.48150518446;51878;51917.48082519202;52054.479455204964;12728.23630582168;14344.282785860874;14393.712125757425;14405.279736013157;14557.636059098395;10632.25574209798;11819.940900295443;12123.696907577381;12143.271403715806;12146.089043321786;13870.514531991534;13918.512852050331;14020.579382618538;14072.15567065959;14171.85828141726;5436.776725236869;5594.41258667834;5614.6631202128;5659.886802263932;5667.631603945731;990.8513722941616;1016.9644062457778;1017.516679577195;1020.0819262663662;1033.1012019543045;22699.205527806447;22839;23048.07807687707;23092.884535577214;23233.535329293318;1090.4493230918495;1101.4217535793773;1110.4669758515959;1114.3425379026414;1115.1524841120645;11822.34977076252;11967.102467314811;12063.75872482563;12085.697857553456;12175.45210465531;208724;209071;209642;210630;211093;49674.503254966985;49903.75048124809;49904.50095499071;49953.50046499561;49985.500144998085;1369.7534443800012;1431.9284035798169;1439.8704116629553;1449.4419648435428;1449.6013596261112;22033.889830550746;22166.113355466245;22285.888570557043;22619.208327708453;22619.321420357417;35188.82405587956;35509.1122721934;35528.289434211685;35555.466667999484;35641.46537801987;180209;180494;180805.0959745193;180806;181301;29551.85224073866;29955.850220748758;32401.35197296088;32870;33270.83364583162;41111.38332924949;41133.58866411298;41408.37887431631;41456.170876581695;42013.57986420097;31085.68914310828;31092.378152436373;31236.219094521908;31552.842235788674;31583.684163158076;30102.247443813205;30266;30502.694973049984;30544.847275763477;30759.692403076577;34128;34193.487097693054;34359.65640343565;34395.14012149716;34457.31085378329;30006.69993300039;30040.699593003792;30398.39203215879;30410.391792164475;30461.695383045884;22704.205352812573;22713;22865;23014.654780178647;23372.298831035096 05:11:18 INFO - PID 15553 | |2;jquery.html;546.8769526856457;561.2283110722756;565.4402141879541;567.8268128220891;577.1054864959312;21838.344849654495;22331.888340558315;22751.31746047617;22753.43116422088;22782.88608556957;25836.354091147732;26424.075157369494;26567.86716066417;26638.60042099365;26643.866780666118;6985.476089293298;7125.1093613298335;7130.643467826614;7164.391026762732;7164.892526612105;6371.299157092716;6656.0348749431305;6994.4754143439195;7009.334113259232;7022.578645281274;6981.755638552651;7012.053372794659;7015.19325277592;7022.052022976909;7022.332878376546;7040.366367026966;7040.894386584206;7042.647867606651;7043.260457651924;7044.471664625148;6870.244273129952;6881.759138430181;6886.931130688679;6893.103896493474;6898.103246577913;29816.552751708525;30831.691683083107;30882.536761948588;31136.844315778504;31311.217219569466;2040.2655044184082;2079.916803327865;2083;2085.384811480617;2087.645100332947;20391.38825835227;20903.477413064644;20916.163353465843;20963;21012.789872101235;2151;2397.784199422043;2414.4446777241333;2422.7819496245247;2425.7089149302196;136367.31816340855;139132;139671;139773.99999999898;139901;20152.798472015387;20397.59204815925;21078.894605526875;21404.07363337276;21758;42596.36105458421;42621.78689106597;42748.145037099704;42818.78590607027;42846.785766071596;10439.478026098664;10439.478026098664;10469.528871200815;10636;10735.355878647306;8592.828143437097;8605.182507661762;8617.48295102296;8620.094890036582;8654.264387527004;9707.369021013612;11319.151063670257;11387.032102271234;11397.316161030365;11573.594924177612;3897.824597893102;3981.6615587675374;4054.553999060101;4219.831206751695;4269.786510674454;948.8292107420731;960.1838437328355;967.7725734452398;977.1108291454742;978.2516374973009;19255.06186164587;19267.132979015976;19556.315528956417;19641.41075767729;19891.403257901995;1002.8746406699163;1006.4011912911806;1017.2574020964589;1020.3265844542566;1033.6330602636106;10720.41037742916;10816.621418250481;10820.296680715728;10845.945770271099;11122.388268645302;451035;466759;469075;469096;477958;105067.47466262773;105961;106474;106711.46644266728;107255;1145.066770581973;1154.1228666213738;1172.865120511135;1177.4112943528237;1183.2900259844196;17314.74027889608;17609.647807044043;17624.73562896583;17969.73045404346;18033.00818454945;52965.7351713239;54109.45890541044;55003.449965499836;55701.16448253198;57189;63850;65536;66107.66946165239;66251;66759.33240667531;15427.382904683687;19718.40844774659;19859.900700496404;19941.501462463555;20262.08820603047;22158.446038849157;22264.888675556518;22335.77664223337;22521;22535.211267605555;17445.563860903578;17532.561685957953;17635.382761603283;17904;18084.186211620246;14996.400143994118;15111.924440377728;15774.369025238862;16316.918415408321;16583.419580314632;17465.650686985937;18160;18190.72713909319;18487.168077436818;18531.90734046321;19443.222271109564;19520.414387568395;19586.31447899317;19599.510012249808;19642;16645.91677041607;16852.15739213059;16872.493825185265;17512.211950462006;17521.474355769347 05:11:18 INFO - PID 15553 | |3;mootools.html;1267.9429425675844;1305.1320871620374;1327.1638867513468;1329.3021163888964;1331.5539294336397;14587.56237312881;15712.450064247754;15713.214339283018;15715.84284157158;15724.135172565504;15312.693746125098;15534.378624854991;15546.61133471661;15558.144302063381;15578.766318505202;2532.3669082729366;2546.5925451927696;2550.2476769352993;2552.1705445730117;2554.5657238269446;2398.70016247969;2522.495500899827;2575.072973729456;2577.6262441945905;2583.289595361272;2469.6663801547143;2552.2981180175507;2556.7315431879665;2557.9360515987155;2558.040734724478;2518.848869067853;2531.1267612673596;2544.274881658718;2552.5915853463357;2553.3616595851036;2491.7134529529153;2581.070814506776;2581.9612705809427;2664.8534330612;2670.3190686375046;14293.857061429357;14421.206833624146;14465.493707720285;14478.782818257734;14492.56522304322;7239.601821899793;7451.888221676625;7461.179270280265;7473.85052298951;7478.887816682754;5017.448080711119;5152.304438900738;5164.406093299243;5217.373915130209;5233.319668443116;1862.6274745051044;1883.077292126848;1887.1507821480252;1928.7878333383458;1933.3330001149582;25140.622890656654;25760.355991100187;25781.871090644614;25787.55018273261;25803.225903222934;6523.510736694767;6752.189737231563;6984.917337812647;6985.022096906438;6992.230854605989;16599.41902033423;16729.665406692042;16754.162291885357;16768.580785480215;16779.748303775454;7494.5503269803985;7494.5503269803985;7508.662110205054;7745.806354841174;7779.961100194463;6874;6934.098567186234;6963.547369421073;6983.720651173998;6989.126359205099;5105.5149760772665;5215;5238.633295669343;5254.4220135785035;5258.447862974409;1468.3906178935622;1484.3617244584932;1485.5543336998858;1499.2054211268046;1503.4061545689392;852.6333676519156;855.2772906893665;862.2670729879552;869.9695510657223;874.9343799215085;7377.0041044459385;7380.695874231935;7399.778006659809;7412.18465968743;7427.700152473403;687.2165731066588;706.1773034414952;709.8509313044216;712.8182313510022;717.3794667612477;4859.1496488114435;4883.8290659826735;5030.924536131961;5389.461053894579;5431.6740995540395;20882.686759698616;21311.25410610652;21790.128394864027;21829.563408731738;21835.672464913037;20543.4864128395;20725.378238652866;21185.470363240733;21214.46963825886;21295.89352053261;10376.533056012497;10553.683389498327;10716.839247411295;10754.139668826476;10769.515371808131;7800.765977020698;8200.753977380688;8538.957305213435;8558.400911936105;8634.654613815377;17561.209745561224;18002.639947201245;18113.275468981094;18274.177662004975;18399;18322.908385457988;18389;18713.345032923782;18841.24635014639;19008.42974710761;11800.881991179978;12026.699332516757;12074.879251207376;12081.637550873487;12084.395780210778;13414.932925335312;13545;13568.253746044062;13580.45678172862;13590.59228223155;11180.888191117985;11189.272697274651;11233.101351891992;11295.491702873225;11465.656030319104;10509.842352364567;10593.947030264799;10716.19628527848;10798.784024319626;11024.669259922215;9627.951860240933;9886.110250077527;10026.398416095057;10253.58985640566;10260.12788912921;6677.499187560855;6831.351021652935;6878.65606719652;6953.548019378724;6957.512974091765;6833.282505336868;6935.757248496278;6951.582905025714;6958.060661810793;7035.648217588998 05:11:18 INFO - PID 15553 | |4;prototype.html;501.6341847233989;506.25833154428767;516.4448218165472;528.4081828352246;532.0343576408818;6373.968130159348;6380.246716196434;6431.292557818648;6507.479401647869;6522.9021564676505;6319.684015799203;6360.028391198732;6387;6428.614283143003;6434.646094464802;5234.293370395005;5238.790448382079;5268.631195816294;5301.231321458386;5301.973490132554;4867.537583929521;5003.699778013312;5219.71291578963;5229.843104706865;5273.70994595299;4943.431505376874;5002.823771659321;5025.120603894322;5029.572486338666;5038.370203724535;4957.479464656195;4987.15218412867;5003.124453220672;5007.574356179729;5011.223260394645;4374.081442896995;4431.556844315575;4471.1728330259;4497.987952710647;4500.662450316207;6187.597806142587;6191.19514463117;6205.87588248237;6219.720112594899;6310.446806810024;5703.287089113861;5761.48146666802;5762.571270094287;5767.452092051249;5789.8263052108505;6604.900926486107;6625.602463852184;6627.403533681943;6696.096027036337;6725.361090696376;4934.851954441372;4941.950580494185;4966.106100901836;4971.92542111865;4972.577330926875;6541;6650.2352229493745;6841.576889075359;6866.244713081557;7033.030751389619;5018.648694591344;5024.171011783015;5025.3718285214345;5035.924461133086;5052.282224043843;5094.923576146361;5114.590832733373;5117.645882893836;5134.127198376285;5161.941801930595;5656.264685590847;5665.5467562594895;5721.77112915487;5775.797847075416;5778.682172480554;6634.469242460592;6664.166979127609;6681.398674119353;6720.521485273232;6799.388055075067;1782.3405340024274;1788.8658350623755;1791.5431564951114;1791.8387345139;1797.4427927342304;1162.8023236049892;1196.4496331687521;1217.8477690288714;1228.5515786737776;1238.6655602987144;5843.941560584425;5872.324682661463;5888.440598143169;5920.911186332209;5964.642121472725;6729.05793188954;6763;6947.131608548932;6991.657794638853;7014.649267536704;5767.307923049261;5823.359430462646;5840.065589505743;5842.444967728059;5898.557608179403;3544.468329750558;3599.8380072896257;3624.401973674367;3662.6337366263692;3677.5841301098735;6669.866122759143;6686.4985126115735;6689.264180940093;6708.563943343667;6728.42808361285;6451.870962580722;6891.896621550681;6954.8956765647545;6996.475264355194;7054.6472676365975;6523.64119973406;6538.313477084933;6609.702563384659;6623.56867368753;6641.804475194512;6621.702023409054;6691.130153080068;6778;6798.456123510206;6847.043072526933;6744.62904540255;6786.864262714619;6821.2155602106395;6824.385805277549;6907.682698313554;6506.772262970773;6570.901436478553;6688.632125233159;6695.832604184934;6735.191776986793;4960.8759781005765;4985.7257850817105;5009.448960614257;5026.899462010666;5065.7720402581235;6614.966925165343;6688.197416310074;6756.966215168893;6768.7292508299115;6779.932200678128;6494.6103233806125;6554.705038273194;6569.901451478136;6582.539222254588;6691.933080669326;6544.54188206821;6583.506237032338;6602.471802255712;6648.401643852077;6671.733130674718;6479.060536222316;6479.352064793483;6484.244196216683;6536.346365363425;6581.638009909501;6607.2071351438135;6632.30360812127;6640.867182656417;6644.136262285871;6804.55770374924;6418.390252925965;6443.6158621310415;6489.383508566679;6493.2857385688485;6598.571092878948 05:11:18 INFO - PID 15553 | |5;yui.html;859.2696208223015;882.082634060577;892.9776755581114;895.5611750242375;899.235649697757;15946.28241729122;16321.183940802943;16551.503454896356;16594.087325197124;22051.779482205213;20198.091085901135;20650;21081.26215582455;21088.472788180305;21185;934.2619330728717;965.179597342257;969.8060387922409;969.8884628267768;970.4904924914406;929.4655573045492;957.1624828275259;969.8108868770593;969.8884628267733;970.4856426094182;986.6102889358699;987.501311837521;987.7678745494803;990.6780296403698;999.7950420163885;981.3523074770653;981.8723565936453;982.2240430060244;983.9803203935953;995.8008398320364;723.5333210079469;723.7430712097236;723.9963800181018;728.0753443127198;735.7902997645646;18838.246470141177;18964.24143034277;19203.039848007684;19247.13387897548;20318;20890.164393424246;20987.685184722242;21280.46798830026;21512.139514419403;21525.56948861014;16489.752653710202;16609.501714948572;16792.748108778378;16801.579960500963;16804.57988550296;3678.429843374281;3704.073981504624;3706.388445906422;3707.2400157967554;3719.6838268747188;128000;129325.35337323348;131607;134123.32938335344;134410;16359.345626175058;16558.420455284006;16643.251053702614;16843.663126737396;17016.659666806594;28026.439471210462;28946.421071578872;29998.550021749692;30142.84928575343;30143.547846782316;7252.166000909858;7627.8855817162785;8462.661493540312;8722.912770872337;8975.147361000694;4125.525564560114;4234.703570750018;4237.258479766028;4243.6605071594195;4247.235497610398;5158.123119069692;5216.686998780086;5249.081410753103;5283.471652834686;5292.285541451854;1007.6271779441507;1021.8824835144054;1033.8914413986574;1035.3477309295247;1037.6990672704983;643.8615697625033;689.1248114894056;695.7043256615918;698.3644883156303;712.9821754456076;11108.666739997812;11455.942720286344;11727.296362218123;11999.64001079969;12504.562340318227;634.22307673101;680.0818894492396;690.8721886451023;706.2972342519226;706.5372181221309;5677.914831277534;5816.796412125555;5845.1232315153065;5845.386234445407;6007.429294217042;105724;109227.45386273018;109879;111408.44295778469;112153.43923280331;99834;102047.4897625522;103533;104251.4787426058;104490.47754761178;80955;84013;84935;85342.57328713441;85649.99999999875;10258.640947566799;10290.897091028994;10509.9474502627;10584.576616935545;10599.788004240027;37904.62095379011;38537;38878;39011.6098839008;39065.41401879031;50248.74875625598;50521.494785053146;50596.747016264686;52175;71151.64424177846;17374.565635859206;17518.562035949202;17611.735823962394;17719.114044297992;17731.468055958343;18866.811331886507;19171.424857254304;20029.699554506402;21333.146674132862;21526;16760.49718508398;17051.82948170552;17149;18279.919066770235;18416.35542755997;18412.171452284947;18833.340833070775;18960;19057.428277151706;19429.708554371977;17811.10944452746;18104.547386315448;18272.817271827644;18676.906615466836;18692.25230990745;11818.172727908965;12039.337836419081;12563;12861.2926289055;13373.732525349633;13885.514007009706;13911.165330080228;14132.364043617856;14207.289635517976;14727.26363681833 05:11:18 INFO - PID 15553 | __end_tp_report 05:11:18 INFO - PID 15553 | __start_cc_report 05:11:18 INFO - PID 15553 | _x_x_mozilla_cycle_collect,366 05:11:18 INFO - PID 15553 | __end_cc_report 05:11:18 INFO - PID 15553 | __startTimestamp1503663078338__endTimestamp 05:11:18 INFO - PID 15553 | 05:11:18 INFO - PID 15553 | ------- Summary: start ------- 05:11:18 INFO - PID 15553 | Number of tests: 6 05:11:18 INFO - PID 15553 | 05:11:18 INFO - PID 15553 | [#0] dojo.html Cycles:165 Average:6278.27 Median:6487.71 stddev:734.07 (11.3%) stddev-sans-first:696.74 05:11:18 INFO - PID 15553 | Values: 3247.0 3295.7 3331.6 3334.6 3349.0 6301.3 6404.9 6408.4 6412.8 6461.4 6211.8 6287.7 6298.7 6319.5 6340.8 5778.0 5825.4 5825.9 5828.9 5885.4 5851.9 5856.8 5898.1 5908.3 5957.0 5829.3 5888.3 5898.6 5912.6 5913.2 5581.1 5746.1 5815.2 5857.8 5868.5 5658.9 5683.9 5741.9 5776.9 5829.8 6286.5 6323.5 6323.7 6347.6 6374.6 6487.7 6581.0 6753.3 6809.2 6864.8 6854.7 6904.0 6907.0 6907.3 6911.0 6335.3 6339.0 6387.8 6421.4 6422.6 6994.8 6997.4 7079.1 7089.8 7095.1 6574.5 6689.5 6727.2 6747.6 6761.8 6773.2 6878.0 6882.6 6899.3 6916.4 6758.9 6777.8 6780.6 6869.9 6874.3 6570.4 6662.2 6795.6 6879.0 6881.8 5330.1 5426.5 5429.0 5429.2 5431.7 4686.0 4745.6 4755.7 4758.7 4798.1 5831.9 5876.4 5892.5 5894.7 5914.1 6751.6 6765.9 6768.6 6797.6 6809.4 5910.9 5927.3 5929.3 6023.3 6029.2 5901.4 5966.5 5984.9 5989.1 5993.2 6541.0 6667.7 6680.4 6780.7 6781.2 6813.9 6878.6 6879.2 6880.3 6882.1 6619.3 6660.2 6713.0 6736.2 6755.9 6679.4 6683.4 6768.0 6808.3 6851.2 6815.1 6818.5 6864.9 6898.8 6943.8 6451.6 6599.7 6602.8 6647.2 6680.8 6596.7 6653.9 6702.2 6732.7 6742.2 6473.1 6491.0 6564.5 6570.9 6598.4 6574.3 6625.1 6667.8 6732.1 6750.7 6415.4 6434.0 6439.2 6439.9 6456.9 05:11:18 INFO - PID 15553 | 05:11:18 INFO - PID 15553 | [#1] ext.html Cycles:180 Average:35554.16 Median:33070.42 stddev:49933.33 (151.0%) stddev-sans-first:50005.06 05:11:18 INFO - PID 15553 | Values: 760.8 828.1 846.6 855.2 1125.8 29334.9 29822.9 38570.8 39396.0 39440.8 40054.4 40386.8 40390.4 40414.8 40469.4 7524.3 7634.3 7711.9 7717.0 7773.7 7614.3 7705.2 7706.9 7712.3 7712.5 7685.2 7726.0 7734.1 7739.1 7740.4 7670.8 7696.5 7705.3 7707.8 7710.3 7663.2 7702.7 7710.0 7713.1 7714.0 35745.8 35909.3 35955.5 35961.6 35988.0 3480.6 3515.8 3518.5 3519.2 3522.9 30257.4 30356.8 30358.0 30377.0 30459.2 4925.3 5059.0 5075.9 5111.4 5129.2 186854.0 187221.0 187280.0 187416.1 187450.0 46313.3 46410.0 46532.0 46638.3 46776.4 50939.5 51849.5 51878.0 51917.5 52054.5 12728.2 14344.3 14393.7 14405.3 14557.6 10632.3 11819.9 12123.7 12143.3 12146.1 13870.5 13918.5 14020.6 14072.2 14171.9 5436.8 5594.4 5614.7 5659.9 5667.6 990.9 1017.0 1017.5 1020.1 1033.1 22699.2 22839.0 23048.1 23092.9 23233.5 1090.4 1101.4 1110.5 1114.3 1115.2 11822.3 11967.1 12063.8 12085.7 12175.5 208724.0 209071.0 209642.0 210630.0 211093.0 49674.5 49903.8 49904.5 49953.5 49985.5 1369.8 1431.9 1439.9 1449.4 1449.6 22033.9 22166.1 22285.9 22619.2 22619.3 35188.8 35509.1 35528.3 35555.5 35641.5 180209.0 180494.0 180805.1 180806.0 181301.0 29551.9 29955.9 32401.4 32870.0 33270.8 41111.4 41133.6 41408.4 41456.2 42013.6 31085.7 31092.4 31236.2 31552.8 31583.7 30102.2 30266.0 30502.7 30544.8 30759.7 34128.0 34193.5 34359.7 34395.1 34457.3 30006.7 30040.7 30398.4 30410.4 30461.7 22704.2 22713.0 22865.0 23014.7 23372.3 05:11:18 INFO - PID 15553 | 05:11:18 INFO - PID 15553 | [#2] jquery.html Cycles:180 Average:34780.48 Median:21998.40 stddev:78579.18 (357.2%) stddev-sans-first:78757.58 05:11:18 INFO - PID 15553 | Values: 546.9 561.2 565.4 567.8 577.1 21838.3 22331.9 22751.3 22753.4 22782.9 25836.4 26424.1 26567.9 26638.6 26643.9 6985.5 7125.1 7130.6 7164.4 7164.9 6371.3 6656.0 6994.5 7009.3 7022.6 6981.8 7012.1 7015.2 7022.1 7022.3 7040.4 7040.9 7042.6 7043.3 7044.5 6870.2 6881.8 6886.9 6893.1 6898.1 29816.6 30831.7 30882.5 31136.8 31311.2 2040.3 2079.9 2083.0 2085.4 2087.6 20391.4 20903.5 20916.2 20963.0 21012.8 2151.0 2397.8 2414.4 2422.8 2425.7 136367.3 139132.0 139671.0 139774.0 139901.0 20152.8 20397.6 21078.9 21404.1 21758.0 42596.4 42621.8 42748.1 42818.8 42846.8 10439.5 10439.5 10469.5 10636.0 10735.4 8592.8 8605.2 8617.5 8620.1 8654.3 9707.4 11319.2 11387.0 11397.3 11573.6 3897.8 3981.7 4054.6 4219.8 4269.8 948.8 960.2 967.8 977.1 978.3 19255.1 19267.1 19556.3 19641.4 19891.4 1002.9 1006.4 1017.3 1020.3 1033.6 10720.4 10816.6 10820.3 10845.9 11122.4 451035.0 466759.0 469075.0 469096.0 477958.0 105067.5 105961.0 106474.0 106711.5 107255.0 1145.1 1154.1 1172.9 1177.4 1183.3 17314.7 17609.6 17624.7 17969.7 18033.0 52965.7 54109.5 55003.4 55701.2 57189.0 63850.0 65536.0 66107.7 66251.0 66759.3 15427.4 19718.4 19859.9 19941.5 20262.1 22158.4 22264.9 22335.8 22521.0 22535.2 17445.6 17532.6 17635.4 17904.0 18084.2 14996.4 15111.9 15774.4 16316.9 16583.4 17465.7 18160.0 18190.7 18487.2 18531.9 19443.2 19520.4 19586.3 19599.5 19642.0 16645.9 16852.2 16872.5 17512.2 17521.5 05:11:18 INFO - PID 15553 | 05:11:18 INFO - PID 15553 | [#3] mootools.html Cycles:180 Average:9115.06 Median:2538.32 stddev:6574.87 (259.0%) stddev-sans-first:6566.88 05:11:18 INFO - PID 15553 | Values: 1267.9 1305.1 1327.2 1329.3 1331.6 14587.6 15712.5 15713.2 15715.8 15724.1 15312.7 15534.4 15546.6 15558.1 15578.8 2532.4 2546.6 2550.2 2552.2 2554.6 2398.7 2522.5 2575.1 2577.6 2583.3 2469.7 2552.3 2556.7 2557.9 2558.0 2518.8 2531.1 2544.3 2552.6 2553.4 2491.7 2581.1 2582.0 2664.9 2670.3 14293.9 14421.2 14465.5 14478.8 14492.6 7239.6 7451.9 7461.2 7473.9 7478.9 5017.4 5152.3 5164.4 5217.4 5233.3 1862.6 1883.1 1887.2 1928.8 1933.3 25140.6 25760.4 25781.9 25787.6 25803.2 6523.5 6752.2 6984.9 6985.0 6992.2 16599.4 16729.7 16754.2 16768.6 16779.7 7494.6 7494.6 7508.7 7745.8 7780.0 6874.0 6934.1 6963.5 6983.7 6989.1 5105.5 5215.0 5238.6 5254.4 5258.4 1468.4 1484.4 1485.6 1499.2 1503.4 852.6 855.3 862.3 870.0 874.9 7377.0 7380.7 7399.8 7412.2 7427.7 687.2 706.2 709.9 712.8 717.4 4859.1 4883.8 5030.9 5389.5 5431.7 20882.7 21311.3 21790.1 21829.6 21835.7 20543.5 20725.4 21185.5 21214.5 21295.9 10376.5 10553.7 10716.8 10754.1 10769.5 7800.8 8200.8 8539.0 8558.4 8634.7 17561.2 18002.6 18113.3 18274.2 18399.0 18322.9 18389.0 18713.3 18841.2 19008.4 11800.9 12026.7 12074.9 12081.6 12084.4 13414.9 13545.0 13568.3 13580.5 13590.6 11180.9 11189.3 11233.1 11295.5 11465.7 10509.8 10593.9 10716.2 10798.8 11024.7 9628.0 9886.1 10026.4 10253.6 10260.1 6677.5 6831.4 6878.7 6953.5 6957.5 6833.3 6935.8 6951.6 6958.1 7035.6 05:11:18 INFO - PID 15553 | 05:11:18 INFO - PID 15553 | [#4] prototype.html Cycles:180 Average:5592.57 Median:6339.86 stddev:1571.09 (24.8%) stddev-sans-first:1528.32 05:11:18 INFO - PID 15553 | Values: 501.6 506.3 516.4 528.4 532.0 6374.0 6380.2 6431.3 6507.5 6522.9 6319.7 6360.0 6387.0 6428.6 6434.6 5234.3 5238.8 5268.6 5301.2 5302.0 4867.5 5003.7 5219.7 5229.8 5273.7 4943.4 5002.8 5025.1 5029.6 5038.4 4957.5 4987.2 5003.1 5007.6 5011.2 4374.1 4431.6 4471.2 4498.0 4500.7 6187.6 6191.2 6205.9 6219.7 6310.4 5703.3 5761.5 5762.6 5767.5 5789.8 6604.9 6625.6 6627.4 6696.1 6725.4 4934.9 4942.0 4966.1 4971.9 4972.6 6541.0 6650.2 6841.6 6866.2 7033.0 5018.6 5024.2 5025.4 5035.9 5052.3 5094.9 5114.6 5117.6 5134.1 5161.9 5656.3 5665.5 5721.8 5775.8 5778.7 6634.5 6664.2 6681.4 6720.5 6799.4 1782.3 1788.9 1791.5 1791.8 1797.4 1162.8 1196.4 1217.8 1228.6 1238.7 5843.9 5872.3 5888.4 5920.9 5964.6 6729.1 6763.0 6947.1 6991.7 7014.6 5767.3 5823.4 5840.1 5842.4 5898.6 3544.5 3599.8 3624.4 3662.6 3677.6 6669.9 6686.5 6689.3 6708.6 6728.4 6451.9 6891.9 6954.9 6996.5 7054.6 6523.6 6538.3 6609.7 6623.6 6641.8 6621.7 6691.1 6778.0 6798.5 6847.0 6744.6 6786.9 6821.2 6824.4 6907.7 6506.8 6570.9 6688.6 6695.8 6735.2 4960.9 4985.7 5009.4 5026.9 5065.8 6615.0 6688.2 6757.0 6768.7 6779.9 6494.6 6554.7 6569.9 6582.5 6691.9 6544.5 6583.5 6602.5 6648.4 6671.7 6479.1 6479.4 6484.2 6536.3 6581.6 6607.2 6632.3 6640.9 6644.1 6804.6 6418.4 6443.6 6489.4 6493.3 6598.6 05:11:18 INFO - PID 15553 | 05:11:18 INFO - PID 15553 | [#5] yui.html Cycles:180 Average:23336.34 Median:21518.85 stddev:32348.88 (150.3%) stddev-sans-first:32395.60 05:11:18 INFO - PID 15553 | Values: 859.3 882.1 893.0 895.6 899.2 15946.3 16321.2 16551.5 16594.1 22051.8 20198.1 20650.0 21081.3 21088.5 21185.0 934.3 965.2 969.8 969.9 970.5 929.5 957.2 969.8 969.9 970.5 986.6 987.5 987.8 990.7 999.8 981.4 981.9 982.2 984.0 995.8 723.5 723.7 724.0 728.1 735.8 18838.2 18964.2 19203.0 19247.1 20318.0 20890.2 20987.7 21280.5 21512.1 21525.6 16489.8 16609.5 16792.7 16801.6 16804.6 3678.4 3704.1 3706.4 3707.2 3719.7 128000.0 129325.4 131607.0 134123.3 134410.0 16359.3 16558.4 16643.3 16843.7 17016.7 28026.4 28946.4 29998.6 30142.8 30143.5 7252.2 7627.9 8462.7 8722.9 8975.1 4125.5 4234.7 4237.3 4243.7 4247.2 5158.1 5216.7 5249.1 5283.5 5292.3 1007.6 1021.9 1033.9 1035.3 1037.7 643.9 689.1 695.7 698.4 713.0 11108.7 11455.9 11727.3 11999.6 12504.6 634.2 680.1 690.9 706.3 706.5 5677.9 5816.8 5845.1 5845.4 6007.4 105724.0 109227.5 109879.0 111408.4 112153.4 99834.0 102047.5 103533.0 104251.5 104490.5 80955.0 84013.0 84935.0 85342.6 85650.0 10258.6 10290.9 10509.9 10584.6 10599.8 37904.6 38537.0 38878.0 39011.6 39065.4 50248.7 50521.5 50596.7 52175.0 71151.6 17374.6 17518.6 17611.7 17719.1 17731.5 18866.8 19171.4 20029.7 21333.1 21526.0 16760.5 17051.8 17149.0 18279.9 18416.4 18412.2 18833.3 18960.0 19057.4 19429.7 17811.1 18104.5 18272.8 18676.9 18692.3 11818.2 12039.3 12563.0 12861.3 13373.7 13885.5 13911.2 14132.4 14207.3 14727.3 05:11:18 INFO - PID 15553 | -------- Summary: end -------- 05:11:18 INFO - PID 15553 | 05:11:19 INFO - TEST-INFO | 15553: exit 0 05:11:19 INFO - TEST-OK | dromaeo_css | took 1112243ms 05:11:19 INFO - TEST-START | kraken 05:11:19 INFO - Initialising browser for kraken test... 05:11:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42986/getInfo.html -profile /tmp/tmp0sc5Vb/profile 05:11:19 INFO - TEST-INFO | started process 15959 (/builds/slave/test/build/application/firefox/firefox http://localhost:42986/getInfo.html) 05:11:24 INFO - TEST-INFO | 15959: exit 0 05:11:25 INFO - Browser initialized. 05:11:25 INFO - Running cycle 1/1 for kraken test... 05:11:25 INFO - Using env: {'DISPLAY': ':0', 05:11:25 INFO - 'HOME': '/home/cltbld', 05:11:25 INFO - 'JSGC_DISABLE_POISONING': '1', 05:11:25 INFO - 'LANG': 'en_US.UTF-8', 05:11:25 INFO - 'LANGUAGE': 'en_US:en', 05:11:25 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:11:25 INFO - 'LOGNAME': 'cltbld', 05:11:25 INFO - 'MAIL': '/var/mail/cltbld', 05:11:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:11:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:11:25 INFO - 'MOZ_CRASHREPORTER': '1', 05:11:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:25 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:11:25 INFO - 'MOZ_NO_REMOTE': '1', 05:11:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:11:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:25 INFO - 'NO_EM_RESTART': '1', 05:11:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:25 INFO - 'PWD': '/builds/slave/test', 05:11:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:11:25 INFO - 'RUST_BACKTRACE': 'full', 05:11:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:11:25 INFO - 'SHELL': '/bin/bash', 05:11:25 INFO - 'SHLVL': '1', 05:11:25 INFO - 'TERM': 'linux', 05:11:25 INFO - 'TMOUT': '86400', 05:11:25 INFO - 'USER': 'cltbld', 05:11:25 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131', 05:11:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:25 INFO - '_': '/tools/buildbot/bin/python'} 05:11:25 INFO - TEST-INFO | started process 16138 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0sc5Vb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:26 INFO - PID 16138 | 05:11:26 INFO - PID 16138 | (/builds/slave/test/build/application/firefox/firefox:16187): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:11:26 INFO - PID 16138 | 05:11:26 INFO - PID 16138 | 05:11:26 INFO - PID 16138 | (/builds/slave/test/build/application/firefox/firefox:16242): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:11:26 INFO - PID 16138 | 05:11:26 INFO - PID 16138 | __metrics Screen width/height:1600/1200 05:11:26 INFO - PID 16138 | colorDepth:24 05:11:26 INFO - PID 16138 | Browser inner width/height: 1024/768 05:11:26 INFO - PID 16138 | __metrics 05:11:36 INFO - PID 16138 | RSS: Main: 172040192 05:11:36 INFO - PID 16138 | 05:11:38 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42986/tests/kraken/driver.html?testName=audio-beat-detection) 05:11:39 INFO - PID 16138 | RSS: Main: 175951872 05:11:39 INFO - PID 16138 | 05:11:42 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42986/tests/kraken/driver.html?testName=audio-dft) 05:11:43 INFO - PID 16138 | RSS: Main: 176373760 05:11:43 INFO - PID 16138 | 05:11:46 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42986/tests/kraken/driver.html?testName=audio-fft) 05:11:46 INFO - PID 16138 | RSS: Main: 170209280 05:11:46 INFO - PID 16138 | 05:11:48 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42986/tests/kraken/driver.html?testName=audio-oscillator) 05:11:49 INFO - PID 16138 | RSS: Main: 171286528 05:11:49 INFO - PID 16138 | 05:11:51 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42986/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:11:52 INFO - PID 16138 | RSS: Main: 171925504 05:11:52 INFO - PID 16138 | 05:11:55 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42986/tests/kraken/driver.html?testName=imaging-darkroom) 05:11:56 INFO - PID 16138 | RSS: Main: 172736512 05:11:56 INFO - PID 16138 | 05:11:59 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42986/tests/kraken/driver.html?testName=imaging-desaturate) 05:12:00 INFO - PID 16138 | RSS: Main: 173387776 05:12:00 INFO - PID 16138 | 05:12:02 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42986/tests/kraken/driver.html?testName=json-parse-financial) 05:12:03 INFO - PID 16138 | RSS: Main: 172765184 05:12:03 INFO - PID 16138 | 05:12:05 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42986/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:12:05 INFO - PID 16138 | RSS: Main: 173285376 05:12:05 INFO - PID 16138 | 05:12:07 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42986/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:12:08 INFO - PID 16138 | RSS: Main: 173191168 05:12:08 INFO - PID 16138 | 05:12:10 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42986/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:12:10 INFO - PID 16138 | RSS: Main: 173604864 05:12:10 INFO - PID 16138 | 05:12:13 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42986/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:12:14 INFO - PID 16138 | RSS: Main: 173547520 05:12:14 INFO - PID 16138 | 05:12:17 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42986/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:12:17 INFO - PID 16138 | RSS: Main: 174231552 05:12:17 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | Cycle 1(1): loaded http://localhost:42986/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42986/tests/kraken/driver.html?testName=ai-astar) 05:12:19 INFO - PID 16138 | RSS: Main: 175247360 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | __start_tp_report 05:12:19 INFO - PID 16138 | _x_x_mozilla_page_load 05:12:19 INFO - PID 16138 | _x_x_mozilla_page_load_details 05:12:19 INFO - PID 16138 | |i|pagename|runs| 05:12:19 INFO - PID 16138 | |0;ai-astar;97;107;98;106;106;108;109;101;105;107 05:12:19 INFO - PID 16138 | |1;audio-beat-detection;143;118;154;175;120;147;141;123;182;118 05:12:19 INFO - PID 16138 | |2;audio-dft;164;161;161;160;167;164;162;170;167;163 05:12:19 INFO - PID 16138 | |3;audio-fft;74;74;73;186;74;73;73;86;96;75 05:12:19 INFO - PID 16138 | |4;audio-oscillator;82;81;137;81;79;79;123;78;79;129 05:12:19 INFO - PID 16138 | |5;imaging-gaussian-blur;117;114;115;115;114;114;113;114;114;114 05:12:19 INFO - PID 16138 | |6;imaging-darkroom;148;149;148;147;148;148;150;147;148;146 05:12:19 INFO - PID 16138 | |7;imaging-desaturate;94;94;93;93;94;96;94;94;94;94 05:12:19 INFO - PID 16138 | |8;json-parse-financial;51;52;51;51;52;51;52;51;52;55 05:12:19 INFO - PID 16138 | |9;json-stringify-tinderbox;57;56;55;55;59;61;56;56;55;55 05:12:19 INFO - PID 16138 | |10;stanford-crypto-aes;75;74;75;74;74;76;74;75;81;80 05:12:19 INFO - PID 16138 | |11;stanford-crypto-ccm;118;118;121;118;123;114;117;125;120;163 05:12:19 INFO - PID 16138 | |12;stanford-crypto-pbkdf2;187;180;172;176;169;171;178;170;186;172 05:12:19 INFO - PID 16138 | |13;stanford-crypto-sha256-iterative;65;65;63;64;66;65;66;62;63;63 05:12:19 INFO - PID 16138 | __end_tp_report 05:12:19 INFO - PID 16138 | __start_cc_report 05:12:19 INFO - PID 16138 | _x_x_mozilla_cycle_collect,953 05:12:19 INFO - PID 16138 | __end_cc_report 05:12:19 INFO - PID 16138 | __startTimestamp1503663139937__endTimestamp 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | ------- Summary: start ------- 05:12:19 INFO - PID 16138 | Number of tests: 14 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#0] ai-astar Cycles:10 Average:104.40 Median:107.50 stddev:4.22 (3.9%) stddev-sans-first:3.53 05:12:19 INFO - PID 16138 | Values: 97.0 107.0 98.0 106.0 106.0 108.0 109.0 101.0 105.0 107.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#1] audio-beat-detection Cycles:10 Average:142.10 Median:145.00 stddev:23.23 (16.0%) stddev-sans-first:24.64 05:12:19 INFO - PID 16138 | Values: 143.0 118.0 154.0 175.0 120.0 147.0 141.0 123.0 182.0 118.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#2] audio-dft Cycles:10 Average:163.90 Median:164.00 stddev:3.21 (2.0%) stddev-sans-first:3.41 05:12:19 INFO - PID 16138 | Values: 164.0 161.0 161.0 160.0 167.0 164.0 162.0 170.0 167.0 163.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#3] audio-fft Cycles:10 Average:88.40 Median:74.00 stddev:35.12 (47.5%) stddev-sans-first:36.86 05:12:19 INFO - PID 16138 | Values: 74.0 74.0 73.0 186.0 74.0 73.0 73.0 86.0 96.0 75.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.00 stddev:24.32 (30.8%) stddev-sans-first:25.35 05:12:19 INFO - PID 16138 | Values: 82.0 81.0 137.0 81.0 79.0 79.0 123.0 78.0 79.0 129.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:1.07 (0.9%) stddev-sans-first:0.60 05:12:19 INFO - PID 16138 | Values: 117.0 114.0 115.0 115.0 114.0 114.0 113.0 114.0 114.0 114.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 05:12:19 INFO - PID 16138 | Values: 148.0 149.0 148.0 147.0 148.0 148.0 150.0 147.0 148.0 146.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 05:12:19 INFO - PID 16138 | Values: 94.0 94.0 93.0 93.0 94.0 96.0 94.0 94.0 94.0 94.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.23 (2.4%) stddev-sans-first:1.27 05:12:19 INFO - PID 16138 | Values: 51.0 52.0 51.0 51.0 52.0 51.0 52.0 51.0 52.0 55.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#9] json-stringify-tinderbox Cycles:10 Average:56.50 Median:56.00 stddev:2.01 (3.6%) stddev-sans-first:2.13 05:12:19 INFO - PID 16138 | Values: 57.0 56.0 55.0 55.0 59.0 61.0 56.0 56.0 55.0 55.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:75.00 stddev:2.57 (3.4%) stddev-sans-first:2.71 05:12:19 INFO - PID 16138 | Values: 75.0 74.0 75.0 74.0 74.0 76.0 74.0 75.0 81.0 80.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:120.50 stddev:14.16 (11.7%) stddev-sans-first:14.87 05:12:19 INFO - PID 16138 | Values: 118.0 118.0 121.0 118.0 123.0 114.0 117.0 125.0 120.0 163.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:177.00 stddev:6.52 (3.7%) stddev-sans-first:5.60 05:12:19 INFO - PID 16138 | Values: 187.0 180.0 172.0 176.0 169.0 171.0 178.0 170.0 186.0 172.0 05:12:19 INFO - PID 16138 | 05:12:19 INFO - PID 16138 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:65.00 stddev:1.40 (2.2%) stddev-sans-first:1.45 05:12:19 INFO - PID 16138 | Values: 65.0 65.0 63.0 64.0 66.0 65.0 66.0 62.0 63.0 63.0 05:12:19 INFO - PID 16138 | -------- Summary: end -------- 05:12:19 INFO - PID 16138 | 05:12:20 INFO - PID 16138 | 05:12:20 INFO - PID 16138 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150081,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 05:12:20 INFO - PID 16138 | 05:12:20 INFO - PID 16138 | 05:12:20 INFO - PID 16138 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 05:12:20 INFO - PID 16138 | 05:12:20 INFO - PID 16138 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:12:20 INFO - TEST-INFO | 16138: exit 0 05:12:20 INFO - TEST-OK | kraken | took 61571ms 05:12:20 INFO - SUITE-END | took 1173s 05:12:21 INFO - Completed test suite (00:19:34) 05:12:21 INFO - javascript benchmark 05:12:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8527.560992859873, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3246.9837650811746, 3295.719863811576, 3331.550240717502, 3334.5665063541737, 3348.9665103348957, 6301.2753533343675, 6404.903926441107, 6408.359164083589, 6412.839679008021, 6461.353864613537, 6211.782587609435, 6287.748490060403, 6298.7165577549, 6319.494440444765, 6340.778072767454, 5777.988851950902, 5825.388334224909, 5825.941740582603, 5828.9417105828825, 5885.4114588541015, 5851.941480585182, 5856.76572937082, 5898.115282707607, 5908.291005079396, 5956.9873121569435, 5829.271341082364, 5888.293404791431, 5898.587098903078, 5912.645241285537, 5913.172155898178, 5581.079121944874, 5746.051901436258, 5815.244018277639, 5857.794977175799, 5868.501177399905, 5658.943410565883, 5683.943160568383, 5741.913871291934, 5776.942230577724, 5829.795957141479, 6286.49708023357, 6323.462505687022, 6323.715432805535, 6347.555671103025, 6374.649394283312, 6487.7080531376205, 6580.967095164541, 6753.257141714456, 6809.216940051859, 6864.828379290508, 6854.725810967555, 6903.965480172617, 6906.965465172692, 6907.309269073103, 6910.965445172742, 6335.303116657164, 6339.049142628643, 6387.776427825051, 6421.389967953037, 6422.614643121427, 6994.825129371807, 6997.4052205562075, 7079.115110611174, 7089.7873063808165, 7095.077639906779, 6574.5397822152945, 6689.531732778756, 6727.159105111861, 6747.628880411625, 6761.830954226085, 6773.187217533928, 6877.9656101720175, 6882.621455819878, 6899.275576064541, 6916.446684265246, 6758.932410675929, 6777.796666100025, 6780.627065511346, 6869.862602747917, 6874.346937040973, 6570.441512471398, 6662.167229096363, 6795.558288711218, 6879.036934829128, 6881.759138430131, 5330.093884039723, 5426.457354264542, 5429.0, 5429.212764149176, 5431.728413579305, 4685.99251161002, 4745.620350371962, 4755.690880092783, 4758.714477131383, 4798.136335459581, 5831.88336233273, 5876.353601103875, 5892.499137573269, 5894.705264736746, 5914.083317085859, 6751.628660423724, 6765.898511522331, 6768.56004359715, 6797.626130562768, 6809.421199198124, 5910.911336329958, 5927.318358388754, 5929.3181284152015, 6023.337432882379, 6029.216201893726, 5901.439363260483, 5966.522678185735, 5984.910226346608, 5989.0716938873675, 5993.220881285405, 6541.0, 6667.6666166691475, 6680.432163266079, 6780.7287708492095, 6781.186257649114, 6813.931860681329, 6878.55289406187, 6879.174499060145, 6880.277570855188, 6882.105326307548, 6619.3049729779605, 6660.234073081658, 6712.966435167793, 6736.225334086446, 6755.93244067553, 6679.398854102959, 6683.43190828785, 6768.0, 6808.251092379735, 6851.212110607344, 6815.114035175396, 6818.522703410714, 6864.897026544706, 6898.827529311808, 6943.79168624942, 6451.612903225821, 6599.7360105595235, 6602.8349291268105, 6647.235567909559, 6680.799576012728, 6596.67016649156, 6653.933460665525, 6702.19573651165, 6732.663366831541, 6742.224644165787, 6473.061406096184, 6491.0, 6564.5076619252795, 6570.85010123236, 6598.439132673777, 6574.342565743387, 6625.138731964814, 6667.833304167435, 6732.057511948429, 6750.696218670073, 6415.358464153547, 6433.967830160819, 6439.195100612424, 6439.935600643934, 6456.9354306456335], "value": 6224.651626564194, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.7793739815454, 828.1097819843669, 846.6359465429866, 855.1533981358457, 1125.8311253312008, 29334.85332573337, 29822.850885745567, 38570.8071459643, 39396.0, 39440.80279598605, 40054.39918401219, 40386.7980660097, 40390.39414408786, 40414.79792601033, 40469.392959105564, 7524.2851929066655, 7634.3129118379475, 7711.884321735179, 7717.0, 7773.689052437924, 7614.2766437188375, 7705.15243323234, 7706.884396734054, 7712.344450721667, 7712.498687585317, 7685.154632990367, 7726.0342457192855, 7734.14924358323, 7739.071311442606, 7740.380769538423, 7670.7698769037015, 7696.461247712662, 7705.306522412981, 7707.845843083163, 7710.267524585155, 7663.233676632348, 7702.6918923243, 7710.036245469317, 7713.112992005879, 7714.0, 35745.82127089348, 35909.28181436383, 35955.460668089996, 35961.64038359609, 35988.0, 3480.6345333739846, 3515.8417871195857, 3518.472229165646, 3519.2433626770367, 3522.894313170609, 30257.394852103054, 30356.84821575878, 30358.0, 30377.0, 30459.23851903698, 4925.285833554115, 5058.974705126488, 5075.923861142086, 5111.386633603955, 5129.230615407719, 186854.0, 187221.0, 187280.0, 187416.06291968588, 187450.0, 46313.30530042052, 46410.0, 46532.0, 46638.300425493646, 46776.38683358019, 50939.490605094215, 51849.48150518446, 51878.0, 51917.48082519202, 52054.479455204964, 12728.23630582168, 14344.282785860874, 14393.712125757425, 14405.279736013157, 14557.636059098395, 10632.25574209798, 11819.940900295443, 12123.696907577381, 12143.271403715806, 12146.089043321786, 13870.514531991534, 13918.512852050331, 14020.579382618538, 14072.15567065959, 14171.85828141726, 5436.776725236869, 5594.41258667834, 5614.6631202128, 5659.886802263932, 5667.631603945731, 990.8513722941616, 1016.9644062457778, 1017.516679577195, 1020.0819262663662, 1033.1012019543045, 22699.205527806447, 22839.0, 23048.07807687707, 23092.884535577214, 23233.535329293318, 1090.4493230918495, 1101.4217535793773, 1110.4669758515959, 1114.3425379026414, 1115.1524841120645, 11822.34977076252, 11967.102467314811, 12063.75872482563, 12085.697857553456, 12175.45210465531, 208724.0, 209071.0, 209642.0, 210630.0, 211093.0, 49674.503254966985, 49903.75048124809, 49904.50095499071, 49953.50046499561, 49985.500144998085, 1369.7534443800012, 1431.9284035798169, 1439.8704116629553, 1449.4419648435428, 1449.6013596261112, 22033.889830550746, 22166.113355466245, 22285.888570557043, 22619.208327708453, 22619.321420357417, 35188.82405587956, 35509.1122721934, 35528.289434211685, 35555.466667999484, 35641.46537801987, 180209.0, 180494.0, 180805.0959745193, 180806.0, 181301.0, 29551.85224073866, 29955.850220748758, 32401.35197296088, 32870.0, 33270.83364583162, 41111.38332924949, 41133.58866411298, 41408.37887431631, 41456.170876581695, 42013.57986420097, 31085.68914310828, 31092.378152436373, 31236.219094521908, 31552.842235788674, 31583.684163158076, 30102.247443813205, 30266.0, 30502.694973049984, 30544.847275763477, 30759.692403076577, 34128.0, 34193.487097693054, 34359.65640343565, 34395.14012149716, 34457.31085378329, 30006.69993300039, 30040.699593003792, 30398.39203215879, 30410.391792164475, 30461.695383045884, 22704.205352812573, 22713.0, 22865.0, 23014.654780178647, 23372.298831035096], "value": 16557.630334794496, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.8769526856457, 561.2283110722756, 565.4402141879541, 567.8268128220891, 577.1054864959312, 21838.344849654495, 22331.888340558315, 22751.31746047617, 22753.43116422088, 22782.88608556957, 25836.354091147732, 26424.075157369494, 26567.86716066417, 26638.60042099365, 26643.866780666118, 6985.476089293298, 7125.1093613298335, 7130.643467826614, 7164.391026762732, 7164.892526612105, 6371.299157092716, 6656.0348749431305, 6994.4754143439195, 7009.334113259232, 7022.578645281274, 6981.755638552651, 7012.053372794659, 7015.19325277592, 7022.052022976909, 7022.332878376546, 7040.366367026966, 7040.894386584206, 7042.647867606651, 7043.260457651924, 7044.471664625148, 6870.244273129952, 6881.759138430181, 6886.931130688679, 6893.103896493474, 6898.103246577913, 29816.552751708525, 30831.691683083107, 30882.536761948588, 31136.844315778504, 31311.217219569466, 2040.2655044184082, 2079.916803327865, 2083.0, 2085.384811480617, 2087.645100332947, 20391.38825835227, 20903.477413064644, 20916.163353465843, 20963.0, 21012.789872101235, 2151.0, 2397.784199422043, 2414.4446777241333, 2422.7819496245247, 2425.7089149302196, 136367.31816340855, 139132.0, 139671.0, 139773.99999999898, 139901.0, 20152.798472015387, 20397.59204815925, 21078.894605526875, 21404.07363337276, 21758.0, 42596.36105458421, 42621.78689106597, 42748.145037099704, 42818.78590607027, 42846.785766071596, 10439.478026098664, 10439.478026098664, 10469.528871200815, 10636.0, 10735.355878647306, 8592.828143437097, 8605.182507661762, 8617.48295102296, 8620.094890036582, 8654.264387527004, 9707.369021013612, 11319.151063670257, 11387.032102271234, 11397.316161030365, 11573.594924177612, 3897.824597893102, 3981.6615587675374, 4054.553999060101, 4219.831206751695, 4269.786510674454, 948.8292107420731, 960.1838437328355, 967.7725734452398, 977.1108291454742, 978.2516374973009, 19255.06186164587, 19267.132979015976, 19556.315528956417, 19641.41075767729, 19891.403257901995, 1002.8746406699163, 1006.4011912911806, 1017.2574020964589, 1020.3265844542566, 1033.6330602636106, 10720.41037742916, 10816.621418250481, 10820.296680715728, 10845.945770271099, 11122.388268645302, 451035.0, 466759.0, 469075.0, 469096.0, 477958.0, 105067.47466262773, 105961.0, 106474.0, 106711.46644266728, 107255.0, 1145.066770581973, 1154.1228666213738, 1172.865120511135, 1177.4112943528237, 1183.2900259844196, 17314.74027889608, 17609.647807044043, 17624.73562896583, 17969.73045404346, 18033.00818454945, 52965.7351713239, 54109.45890541044, 55003.449965499836, 55701.16448253198, 57189.0, 63850.0, 65536.0, 66107.66946165239, 66251.0, 66759.33240667531, 15427.382904683687, 19718.40844774659, 19859.900700496404, 19941.501462463555, 20262.08820603047, 22158.446038849157, 22264.888675556518, 22335.77664223337, 22521.0, 22535.211267605555, 17445.563860903578, 17532.561685957953, 17635.382761603283, 17904.0, 18084.186211620246, 14996.400143994118, 15111.924440377728, 15774.369025238862, 16316.918415408321, 16583.419580314632, 17465.650686985937, 18160.0, 18190.72713909319, 18487.168077436818, 18531.90734046321, 19443.222271109564, 19520.414387568395, 19586.31447899317, 19599.510012249808, 19642.0, 16645.91677041607, 16852.15739213059, 16872.493825185265, 17512.211950462006, 17521.474355769347], "value": 12666.965531383323, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1267.9429425675844, 1305.1320871620374, 1327.1638867513468, 1329.3021163888964, 1331.5539294336397, 14587.56237312881, 15712.450064247754, 15713.214339283018, 15715.84284157158, 15724.135172565504, 15312.693746125098, 15534.378624854991, 15546.61133471661, 15558.144302063381, 15578.766318505202, 2532.3669082729366, 2546.5925451927696, 2550.2476769352993, 2552.1705445730117, 2554.5657238269446, 2398.70016247969, 2522.495500899827, 2575.072973729456, 2577.6262441945905, 2583.289595361272, 2469.6663801547143, 2552.2981180175507, 2556.7315431879665, 2557.9360515987155, 2558.040734724478, 2518.848869067853, 2531.1267612673596, 2544.274881658718, 2552.5915853463357, 2553.3616595851036, 2491.7134529529153, 2581.070814506776, 2581.9612705809427, 2664.8534330612, 2670.3190686375046, 14293.857061429357, 14421.206833624146, 14465.493707720285, 14478.782818257734, 14492.56522304322, 7239.601821899793, 7451.888221676625, 7461.179270280265, 7473.85052298951, 7478.887816682754, 5017.448080711119, 5152.304438900738, 5164.406093299243, 5217.373915130209, 5233.319668443116, 1862.6274745051044, 1883.077292126848, 1887.1507821480252, 1928.7878333383458, 1933.3330001149582, 25140.622890656654, 25760.355991100187, 25781.871090644614, 25787.55018273261, 25803.225903222934, 6523.510736694767, 6752.189737231563, 6984.917337812647, 6985.022096906438, 6992.230854605989, 16599.41902033423, 16729.665406692042, 16754.162291885357, 16768.580785480215, 16779.748303775454, 7494.5503269803985, 7494.5503269803985, 7508.662110205054, 7745.806354841174, 7779.961100194463, 6874.0, 6934.098567186234, 6963.547369421073, 6983.720651173998, 6989.126359205099, 5105.5149760772665, 5215.0, 5238.633295669343, 5254.4220135785035, 5258.447862974409, 1468.3906178935622, 1484.3617244584932, 1485.5543336998858, 1499.2054211268046, 1503.4061545689392, 852.6333676519156, 855.2772906893665, 862.2670729879552, 869.9695510657223, 874.9343799215085, 7377.0041044459385, 7380.695874231935, 7399.778006659809, 7412.18465968743, 7427.700152473403, 687.2165731066588, 706.1773034414952, 709.8509313044216, 712.8182313510022, 717.3794667612477, 4859.1496488114435, 4883.8290659826735, 5030.924536131961, 5389.461053894579, 5431.6740995540395, 20882.686759698616, 21311.25410610652, 21790.128394864027, 21829.563408731738, 21835.672464913037, 20543.4864128395, 20725.378238652866, 21185.470363240733, 21214.46963825886, 21295.89352053261, 10376.533056012497, 10553.683389498327, 10716.839247411295, 10754.139668826476, 10769.515371808131, 7800.765977020698, 8200.753977380688, 8538.957305213435, 8558.400911936105, 8634.654613815377, 17561.209745561224, 18002.639947201245, 18113.275468981094, 18274.177662004975, 18399.0, 18322.908385457988, 18389.0, 18713.345032923782, 18841.24635014639, 19008.42974710761, 11800.881991179978, 12026.699332516757, 12074.879251207376, 12081.637550873487, 12084.395780210778, 13414.932925335312, 13545.0, 13568.253746044062, 13580.45678172862, 13590.59228223155, 11180.888191117985, 11189.272697274651, 11233.101351891992, 11295.491702873225, 11465.656030319104, 10509.842352364567, 10593.947030264799, 10716.19628527848, 10798.784024319626, 11024.669259922215, 9627.951860240933, 9886.110250077527, 10026.398416095057, 10253.58985640566, 10260.12788912921, 6677.499187560855, 6831.351021652935, 6878.65606719652, 6953.548019378724, 6957.512974091765, 6833.282505336868, 6935.757248496278, 6951.582905025714, 6958.060661810793, 7035.648217588998], "value": 6432.73288463876, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.6341847233989, 506.25833154428767, 516.4448218165472, 528.4081828352246, 532.0343576408818, 6373.968130159348, 6380.246716196434, 6431.292557818648, 6507.479401647869, 6522.9021564676505, 6319.684015799203, 6360.028391198732, 6387.0, 6428.614283143003, 6434.646094464802, 5234.293370395005, 5238.790448382079, 5268.631195816294, 5301.231321458386, 5301.973490132554, 4867.537583929521, 5003.699778013312, 5219.71291578963, 5229.843104706865, 5273.70994595299, 4943.431505376874, 5002.823771659321, 5025.120603894322, 5029.572486338666, 5038.370203724535, 4957.479464656195, 4987.15218412867, 5003.124453220672, 5007.574356179729, 5011.223260394645, 4374.081442896995, 4431.556844315575, 4471.1728330259, 4497.987952710647, 4500.662450316207, 6187.597806142587, 6191.19514463117, 6205.87588248237, 6219.720112594899, 6310.446806810024, 5703.287089113861, 5761.48146666802, 5762.571270094287, 5767.452092051249, 5789.8263052108505, 6604.900926486107, 6625.602463852184, 6627.403533681943, 6696.096027036337, 6725.361090696376, 4934.851954441372, 4941.950580494185, 4966.106100901836, 4971.92542111865, 4972.577330926875, 6541.0, 6650.2352229493745, 6841.576889075359, 6866.244713081557, 7033.030751389619, 5018.648694591344, 5024.171011783015, 5025.3718285214345, 5035.924461133086, 5052.282224043843, 5094.923576146361, 5114.590832733373, 5117.645882893836, 5134.127198376285, 5161.941801930595, 5656.264685590847, 5665.5467562594895, 5721.77112915487, 5775.797847075416, 5778.682172480554, 6634.469242460592, 6664.166979127609, 6681.398674119353, 6720.521485273232, 6799.388055075067, 1782.3405340024274, 1788.8658350623755, 1791.5431564951114, 1791.8387345139, 1797.4427927342304, 1162.8023236049892, 1196.4496331687521, 1217.8477690288714, 1228.5515786737776, 1238.6655602987144, 5843.941560584425, 5872.324682661463, 5888.440598143169, 5920.911186332209, 5964.642121472725, 6729.05793188954, 6763.0, 6947.131608548932, 6991.657794638853, 7014.649267536704, 5767.307923049261, 5823.359430462646, 5840.065589505743, 5842.444967728059, 5898.557608179403, 3544.468329750558, 3599.8380072896257, 3624.401973674367, 3662.6337366263692, 3677.5841301098735, 6669.866122759143, 6686.4985126115735, 6689.264180940093, 6708.563943343667, 6728.42808361285, 6451.870962580722, 6891.896621550681, 6954.8956765647545, 6996.475264355194, 7054.6472676365975, 6523.64119973406, 6538.313477084933, 6609.702563384659, 6623.56867368753, 6641.804475194512, 6621.702023409054, 6691.130153080068, 6778.0, 6798.456123510206, 6847.043072526933, 6744.62904540255, 6786.864262714619, 6821.2155602106395, 6824.385805277549, 6907.682698313554, 6506.772262970773, 6570.901436478553, 6688.632125233159, 6695.832604184934, 6735.191776986793, 4960.8759781005765, 4985.7257850817105, 5009.448960614257, 5026.899462010666, 5065.7720402581235, 6614.966925165343, 6688.197416310074, 6756.966215168893, 6768.7292508299115, 6779.932200678128, 6494.6103233806125, 6554.705038273194, 6569.901451478136, 6582.539222254588, 6691.933080669326, 6544.54188206821, 6583.506237032338, 6602.471802255712, 6648.401643852077, 6671.733130674718, 6479.060536222316, 6479.352064793483, 6484.244196216683, 6536.346365363425, 6581.638009909501, 6607.2071351438135, 6632.30360812127, 6640.867182656417, 6644.136262285871, 6804.55770374924, 6418.390252925965, 6443.6158621310415, 6489.383508566679, 6493.2857385688485, 6598.571092878948], "value": 5127.422211805614, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [859.2696208223015, 882.082634060577, 892.9776755581114, 895.5611750242375, 899.235649697757, 15946.28241729122, 16321.183940802943, 16551.503454896356, 16594.087325197124, 22051.779482205213, 20198.091085901135, 20650.0, 21081.26215582455, 21088.472788180305, 21185.0, 934.2619330728717, 965.179597342257, 969.8060387922409, 969.8884628267768, 970.4904924914406, 929.4655573045492, 957.1624828275259, 969.8108868770593, 969.8884628267733, 970.4856426094182, 986.6102889358699, 987.501311837521, 987.7678745494803, 990.6780296403698, 999.7950420163885, 981.3523074770653, 981.8723565936453, 982.2240430060244, 983.9803203935953, 995.8008398320364, 723.5333210079469, 723.7430712097236, 723.9963800181018, 728.0753443127198, 735.7902997645646, 18838.246470141177, 18964.24143034277, 19203.039848007684, 19247.13387897548, 20318.0, 20890.164393424246, 20987.685184722242, 21280.46798830026, 21512.139514419403, 21525.56948861014, 16489.752653710202, 16609.501714948572, 16792.748108778378, 16801.579960500963, 16804.57988550296, 3678.429843374281, 3704.073981504624, 3706.388445906422, 3707.2400157967554, 3719.6838268747188, 128000.0, 129325.35337323348, 131607.0, 134123.32938335344, 134410.0, 16359.345626175058, 16558.420455284006, 16643.251053702614, 16843.663126737396, 17016.659666806594, 28026.439471210462, 28946.421071578872, 29998.550021749692, 30142.84928575343, 30143.547846782316, 7252.166000909858, 7627.8855817162785, 8462.661493540312, 8722.912770872337, 8975.147361000694, 4125.525564560114, 4234.703570750018, 4237.258479766028, 4243.6605071594195, 4247.235497610398, 5158.123119069692, 5216.686998780086, 5249.081410753103, 5283.471652834686, 5292.285541451854, 1007.6271779441507, 1021.8824835144054, 1033.8914413986574, 1035.3477309295247, 1037.6990672704983, 643.8615697625033, 689.1248114894056, 695.7043256615918, 698.3644883156303, 712.9821754456076, 11108.666739997812, 11455.942720286344, 11727.296362218123, 11999.64001079969, 12504.562340318227, 634.22307673101, 680.0818894492396, 690.8721886451023, 706.2972342519226, 706.5372181221309, 5677.914831277534, 5816.796412125555, 5845.1232315153065, 5845.386234445407, 6007.429294217042, 105724.0, 109227.45386273018, 109879.0, 111408.44295778469, 112153.43923280331, 99834.0, 102047.4897625522, 103533.0, 104251.4787426058, 104490.47754761178, 80955.0, 84013.0, 84935.0, 85342.57328713441, 85649.99999999875, 10258.640947566799, 10290.897091028994, 10509.9474502627, 10584.576616935545, 10599.788004240027, 37904.62095379011, 38537.0, 38878.0, 39011.6098839008, 39065.41401879031, 50248.74875625598, 50521.494785053146, 50596.747016264686, 52175.0, 71151.64424177846, 17374.565635859206, 17518.562035949202, 17611.735823962394, 17719.114044297992, 17731.468055958343, 18866.811331886507, 19171.424857254304, 20029.699554506402, 21333.146674132862, 21526.0, 16760.49718508398, 17051.82948170552, 17149.0, 18279.919066770235, 18416.35542755997, 18412.171452284947, 18833.340833070775, 18960.0, 19057.428277151706, 19429.708554371977, 17811.10944452746, 18104.547386315448, 18272.817271827644, 18676.906615466836, 18692.25230990745, 11818.172727908965, 12039.337836419081, 12563.0, 12861.2926289055, 13373.732525349633, 13885.514007009706, 13911.165330080228, 14132.364043617856, 14207.289635517976, 14727.26363681833], "value": 8929.826679652459, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1497.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 98.0, 106.0, 106.0, 108.0, 109.0, 101.0, 105.0, 107.0], "value": 104.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 118.0, 154.0, 175.0, 120.0, 147.0, 141.0, 123.0, 182.0, 118.0], "value": 142.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 161.0, 160.0, 167.0, 164.0, 162.0, 170.0, 167.0, 163.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 186.0, 74.0, 73.0, 73.0, 86.0, 96.0, 75.0], "value": 88.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 137.0, 81.0, 79.0, 79.0, 123.0, 78.0, 79.0, 129.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 115.0, 115.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 148.0, 147.0, 148.0, 148.0, 150.0, 147.0, 148.0, 146.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 94.0, 96.0, 94.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 51.0, 51.0, 52.0, 51.0, 52.0, 51.0, 52.0, 55.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 55.0, 59.0, 61.0, 56.0, 56.0, 55.0, 55.0], "value": 56.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 74.0, 74.0, 76.0, 74.0, 75.0, 81.0, 80.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 121.0, 118.0, 123.0, 114.0, 117.0, 125.0, 120.0, 163.0], "value": 123.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 180.0, 172.0, 176.0, 169.0, 171.0, 178.0, 170.0, 186.0, 172.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 63.0, 64.0, 66.0, 65.0, 66.0, 62.0, 63.0, 63.0], "value": 64.2, "unit": "score"}]}]} 05:12:21 INFO - Return code: 0 05:12:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:12:21 INFO - # TBPL SUCCESS # 05:12:21 INFO - Running post-action listener: _package_coverage_data 05:12:21 INFO - Running post-action listener: _resource_record_post_action 05:12:21 INFO - [mozharness: 2017-08-25 12:12:21.221270Z] Finished run-tests step (success) 05:12:21 INFO - Running post-run listener: _resource_record_post_run 05:12:21 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 432889856; Read time: 12; Write time: 240680 05:12:21 INFO - TinderboxPrint: CPU usage
13.1% 05:12:21 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:12:21 INFO - TinderboxPrint: I/O write bytes / time
432,889,856 / 240,680 05:12:21 INFO - TinderboxPrint: CPU idle
8,266.1 (86.9%) 05:12:21 INFO - TinderboxPrint: CPU user
1,196.2 (12.6%) 05:12:21 INFO - TinderboxPrint: Swap in / out
0 / 0 05:12:21 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 110645248; Read time: 0; Write time: 82596 05:12:21 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:12:21 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 322244608; Read time: 0; Write time: 158084 05:12:21 INFO - Running post-run listener: _upload_blobber_files 05:12:21 INFO - Blob upload gear active. 05:12:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:12:21 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 05:12:21 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'] 05:12:21 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 05:12:22 INFO - (blobuploader) - INFO - Open directory for files ... 05:12:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:12:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:12:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:23 INFO - (blobuploader) - INFO - Done attempting. 05:12:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:12:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:12:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:24 INFO - (blobuploader) - INFO - Done attempting. 05:12:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:12:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:12:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:25 INFO - (blobuploader) - INFO - Done attempting. 05:12:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:12:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:12:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:12:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:12:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:12:28 INFO - (blobuploader) - INFO - Done attempting. 05:12:28 INFO - (blobuploader) - INFO - Iteration through files over. 05:12:28 INFO - Return code: 0 05:12:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:12:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:12:28 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4cb0b453c587c4c30d5ae58d1c67635d3e81f2043f498497483b66de29e02223d8e55807da78bfa3d73b176542c5af71d03d145eb623f6f67e756774c0b0c4ad", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/28f1d9e0f1d64804637e285f6c7704a5539b87a68c9bebae19fdd1ad7d759a07d7f6adf821f568ac19fcd2a957c2d72c52131ff6b0ee1378d325c0bd3cbf79a8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/95aa24fea81bfc7ab62c173ccea3f42de189f41e96f6485bc18dd0e8be70b0bba8547eed1ca1579a3f557270db90cce75afd4b1d0238d4a872804742082a6b86", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/418a28df8ce5d7dd13fbb5d4c3dced03ad9713e792ab2e20afbc632336edd88ffc9be40bc84031ab6608fcc094e50330af31ddd13d00c678938f7f766ef91e89"} 05:12:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:12:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:12:28 INFO - Contents: 05:12:28 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4cb0b453c587c4c30d5ae58d1c67635d3e81f2043f498497483b66de29e02223d8e55807da78bfa3d73b176542c5af71d03d145eb623f6f67e756774c0b0c4ad", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/28f1d9e0f1d64804637e285f6c7704a5539b87a68c9bebae19fdd1ad7d759a07d7f6adf821f568ac19fcd2a957c2d72c52131ff6b0ee1378d325c0bd3cbf79a8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/95aa24fea81bfc7ab62c173ccea3f42de189f41e96f6485bc18dd0e8be70b0bba8547eed1ca1579a3f557270db90cce75afd4b1d0238d4a872804742082a6b86", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/418a28df8ce5d7dd13fbb5d4c3dced03ad9713e792ab2e20afbc632336edd88ffc9be40bc84031ab6608fcc094e50330af31ddd13d00c678938f7f766ef91e89"} 05:12:28 INFO - Running post-run listener: copy_logs_to_upload_dir 05:12:28 INFO - Copying logs to upload dir... 05:12:28 INFO - mkdir: /builds/slave/test/build/upload/logs 05:12:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.570256 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-08-25 05:12:28.490146) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 05:12:28.498262) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4cb0b453c587c4c30d5ae58d1c67635d3e81f2043f498497483b66de29e02223d8e55807da78bfa3d73b176542c5af71d03d145eb623f6f67e756774c0b0c4ad", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/28f1d9e0f1d64804637e285f6c7704a5539b87a68c9bebae19fdd1ad7d759a07d7f6adf821f568ac19fcd2a957c2d72c52131ff6b0ee1378d325c0bd3cbf79a8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/95aa24fea81bfc7ab62c173ccea3f42de189f41e96f6485bc18dd0e8be70b0bba8547eed1ca1579a3f557270db90cce75afd4b1d0238d4a872804742082a6b86", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/418a28df8ce5d7dd13fbb5d4c3dced03ad9713e792ab2e20afbc632336edd88ffc9be40bc84031ab6608fcc094e50330af31ddd13d00c678938f7f766ef91e89"} build_url:https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/Yd1KAK7PTkGgzWZxFL4DBA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4cb0b453c587c4c30d5ae58d1c67635d3e81f2043f498497483b66de29e02223d8e55807da78bfa3d73b176542c5af71d03d145eb623f6f67e756774c0b0c4ad", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/28f1d9e0f1d64804637e285f6c7704a5539b87a68c9bebae19fdd1ad7d759a07d7f6adf821f568ac19fcd2a957c2d72c52131ff6b0ee1378d325c0bd3cbf79a8", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/95aa24fea81bfc7ab62c173ccea3f42de189f41e96f6485bc18dd0e8be70b0bba8547eed1ca1579a3f557270db90cce75afd4b1d0238d4a872804742082a6b86", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/418a28df8ce5d7dd13fbb5d4c3dced03ad9713e792ab2e20afbc632336edd88ffc9be40bc84031ab6608fcc094e50330af31ddd13d00c678938f7f766ef91e89"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-25 05:12:28.545609) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 05:12:28.549511) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1503654944.660945-2006529131 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004436 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-25 05:12:28.627516) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 05:12:28.627977) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-25 05:12:28.628530) ========= ========= Total master_lag: 0.28 =========