builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-062 starttime: 1503883203.96 results: success (0) revision: d10c97627b51a226e19d0fa801201897fe1932f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:03.958162) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:03.958636) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:03.958915) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:03.975685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:03.976182) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-27 18:20:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 46.9M=0s 2017-08-27 18:20:04 (46.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.192924 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:04.206978) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:04.207284) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020768 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:04.256774) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:04.257122) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d10c97627b51a226e19d0fa801201897fe1932f6 --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 d10c97627b51a226e19d0fa801201897fe1932f6 --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=a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-27 18:20:04,427 truncating revision to first 12 chars 2017-08-27 18:20:04,427 Setting DEBUG logging. 2017-08-27 18:20:04,427 attempt 1/10 2017-08-27 18:20:04,427 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d10c97627b51?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-27 18:20:04,878 unpacking tar archive at: mozilla-central-d10c97627b51/testing/mozharness/ program finished with exit code 0 elapsedTime=0.787831 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:05.059449) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:05.060081) ========= script_repo_revision: d10c97627b51a226e19d0fa801201897fe1932f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:05.060461) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:05.060725) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:20:05.071020) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-08-27 18:20:05.071295) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:20:05 INFO - MultiFileLogger online at 20170827 18:20:05 in /builds/slave/test 18:20:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:20:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:20:05 INFO - {'append_to_log': False, 18:20:05 INFO - 'base_work_dir': '/builds/slave/test', 18:20:05 INFO - 'blob_upload_branch': 'Firefox', 18:20:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:20:05 INFO - 'branch': 'Firefox', 18:20:05 INFO - 'buildbot_json_path': 'buildprops.json', 18:20:05 INFO - 'code_coverage': False, 18:20:05 INFO - 'config_files': ('talos/linux_config.py',), 18:20:05 INFO - 'default_actions': ('clobber', 18:20:05 INFO - 'read-buildbot-config', 18:20:05 INFO - 'download-and-extract', 18:20:05 INFO - 'populate-webroot', 18:20:05 INFO - 'create-virtualenv', 18:20:05 INFO - 'install', 18:20:05 INFO - 'setup-mitmproxy', 18:20:05 INFO - 'run-tests'), 18:20:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:20:05 INFO - 'disable_ccov_upload': False, 18:20:05 INFO - 'download_minidump_stackwalk': True, 18:20:05 INFO - 'download_symbols': 'ondemand', 18:20:05 INFO - 'enable_stylo': False, 18:20:05 INFO - 'enable_webrender': False, 18:20:05 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:20:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:20:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:20:05 INFO - 'gecko_profile': False, 18:20:05 INFO - 'gecko_profile_interval': 0, 18:20:05 INFO - 'installer_path': 'installer.exe', 18:20:05 INFO - 'log_level': 'info', 18:20:05 INFO - 'log_name': 'talos', 18:20:05 INFO - 'log_to_console': True, 18:20:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:20:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:20:05 INFO - 'opt_config_files': (), 18:20:05 INFO - 'pip_index': False, 18:20:05 INFO - 'suite': 'dromaeojs-e10s', 18:20:05 INFO - 'system_bits': '32', 18:20:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:20:05 INFO - 'title': 'talos-linux64-ix-062', 18:20:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:20:05 INFO - 'use_talos_json': True, 18:20:05 INFO - 'verify': 'False', 18:20:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:20:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:20:05 INFO - 'work_dir': 'build'} 18:20:05 INFO - [mozharness: 2017-08-28 01:20:05.495733Z] Running clobber step. 18:20:05 INFO - Running pre-action listener: _resource_record_pre_action 18:20:05 INFO - Running main action method: clobber 18:20:05 INFO - rmtree: /builds/slave/test/build 18:20:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:20:06 INFO - Running post-action listener: _resource_record_post_action 18:20:06 INFO - [mozharness: 2017-08-28 01:20:06.538715Z] Finished clobber step (success) 18:20:06 INFO - [mozharness: 2017-08-28 01:20:06.538813Z] Running read-buildbot-config step. 18:20:06 INFO - Running pre-action listener: _resource_record_pre_action 18:20:06 INFO - Running main action method: read_buildbot_config 18:20:06 INFO - Using buildbot properties: 18:20:06 INFO - { 18:20:06 INFO - "project": "", 18:20:06 INFO - "product": "firefox", 18:20:06 INFO - "who": "philringnalda@gmail.com", 18:20:06 INFO - "installer_path": "public/build/target.tar.bz2", 18:20:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:20:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:20:06 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:20:06 INFO - "stage_platform": "linux64-stylosequential", 18:20:06 INFO - "basedir": "/builds/slave/test", 18:20:06 INFO - "buildnumber": 214, 18:20:06 INFO - "platform": "ubuntu64_hw_styloseq", 18:20:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:20:06 INFO - "slavebuilddir": "test", 18:20:06 INFO - "taskId": "eVpVLa0oSji1XwWsvBL7wA", 18:20:06 INFO - "branch": "mozilla-central", 18:20:06 INFO - "script_repo_revision": "production", 18:20:06 INFO - "revision": "d10c97627b51a226e19d0fa801201897fe1932f6", 18:20:06 INFO - "slavename": "talos-linux64-ix-062", 18:20:06 INFO - "repo_path": "mozilla-central" 18:20:06 INFO - } 18:20:06 INFO - Finding installer, test and symbols from parent task. 18:20:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eVpVLa0oSji1XwWsvBL7wA'}, attempt #1 18:20:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eVpVLa0oSji1XwWsvBL7wA'}, attempt #1 18:20:07 INFO - Task dependencies: FgF7GK1MTSiueBqY-_8-1Q 18:20:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q'}, attempt #1 18:20:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 18:20:07 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.test_packages.json 18:20:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.crashreporter-symbols.zip 18:20:07 INFO - Running post-action listener: _resource_record_post_action 18:20:07 INFO - [mozharness: 2017-08-28 01:20:07.877906Z] Finished read-buildbot-config step (success) 18:20:07 INFO - [mozharness: 2017-08-28 01:20:07.878120Z] Running download-and-extract step. 18:20:07 INFO - Running pre-action listener: _resource_record_pre_action 18:20:07 INFO - Running main action method: download_and_extract 18:20:07 INFO - mkdir: /builds/slave/test/build/tests 18:20:07 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')]} 18:20:07 INFO - trying https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.test_packages.json 18:20:07 INFO - Downloading https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:20:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:20:08 INFO - Downloaded 989 bytes. 18:20:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:20:08 INFO - Using the following test package requirements: 18:20:08 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:20:08 INFO - u'common': [u'target.common.tests.zip'], 18:20:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:20:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:20:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:20:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:20:08 INFO - u'mozbase': [u'target.common.tests.zip'], 18:20:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:20:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:20:08 INFO - u'web-platform': [u'target.common.tests.zip', 18:20:08 INFO - u'target.web-platform.tests.tar.gz'], 18:20:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:20:08 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:20:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip 18:20:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:20:08 INFO - Fetch https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip into memory 18:20:09 INFO - Content-Length response header: 54978035 18:20:09 INFO - Bytes received: 54978035 18:20:20 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:20:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip 18:20:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:20:20 INFO - Fetch https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.common.tests.zip into memory 18:20:21 INFO - Content-Length response header: 54978035 18:20:21 INFO - Bytes received: 54978035 18:20:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.talos.tests.zip 18:20:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:20:34 INFO - Fetch https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.talos.tests.zip into memory 18:20:35 INFO - Content-Length response header: 14496611 18:20:35 INFO - Bytes received: 14496611 18:20:35 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')]} 18:20:35 INFO - trying https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 18:20:35 INFO - Downloading https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:20:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:20:36 INFO - Downloaded 61245636 bytes. 18:20:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 18:20:36 INFO - mkdir: /builds/slave/test/properties 18:20:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:20:36 INFO - Writing to file /builds/slave/test/properties/build_url 18:20:36 INFO - Contents: 18:20:36 INFO - build_url:https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 18:20:36 INFO - Running post-action listener: _resource_record_post_action 18:20:36 INFO - Running post-action listener: find_tests_for_verification 18:20:36 INFO - Running post-action listener: set_extra_try_arguments 18:20:36 INFO - [mozharness: 2017-08-28 01:20:36.432792Z] Finished download-and-extract step (success) 18:20:36 INFO - [mozharness: 2017-08-28 01:20:36.432894Z] Running populate-webroot step. 18:20:36 INFO - Running pre-action listener: _resource_record_pre_action 18:20:36 INFO - Running main action method: populate_webroot 18:20:36 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:20:36 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:20:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:20:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:20:36 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:20:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:20:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:20:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:36 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:20:36 INFO - 'talos_options': ['--stylo'], 18:20:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:20:36 INFO - 'tests': ['damp', 'tps']}, 18:20:36 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:20:36 INFO - 'talos_options': ['--stylo'], 18:20:36 INFO - 'tests': ['damp', 'tps']}, 18:20:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:20:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:20:36 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:20:36 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:20:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:20:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:20:36 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:20:36 INFO - 'talos_options': ['--stylo'], 18:20:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:20:36 INFO - 'other-e10s': {'tests': ['a11yr', 18:20:36 INFO - 'ts_paint', 18:20:36 INFO - 'tpaint', 18:20:36 INFO - 'sessionrestore', 18:20:36 INFO - 'sessionrestore_many_windows', 18:20:36 INFO - 'sessionrestore_no_auto_restore', 18:20:36 INFO - 'tabpaint']}, 18:20:36 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:20:36 INFO - 'tests': ['a11yr', 18:20:36 INFO - 'ts_paint', 18:20:36 INFO - 'tpaint', 18:20:36 INFO - 'sessionrestore', 18:20:36 INFO - 'sessionrestore_many_windows', 18:20:36 INFO - 'sessionrestore_no_auto_restore', 18:20:36 INFO - 'tabpaint']}, 18:20:36 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:20:36 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:20:36 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:20:36 INFO - 'tests': ['bloom_basic_singleton']}, 18:20:36 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:20:36 INFO - 'tests': ['bloom_basic']}, 18:20:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:20:36 INFO - 'tsvgr_opacity', 18:20:36 INFO - 'tart', 18:20:36 INFO - 'tscrollx', 18:20:36 INFO - 'tsvg_static']}, 18:20:36 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:20:36 INFO - 'tests': ['tsvgx', 18:20:36 INFO - 'tsvgr_opacity', 18:20:36 INFO - 'tart', 18:20:36 INFO - 'tscrollx', 18:20:36 INFO - 'tsvg_static']}, 18:20:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:20:36 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:20:36 INFO - 'talos_options': ['--stylo'], 18:20:36 INFO - 'tests': ['tp5o']}, 18:20:36 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:36 INFO - 'talos_options': ['--mitmproxy', 18:20:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:36 INFO - '--firstNonBlankPaint'], 18:20:36 INFO - 'tests': ['tp6_google', 18:20:36 INFO - 'tp6_youtube', 18:20:36 INFO - 'tp6_amazon', 18:20:36 INFO - 'tp6_facebook']}, 18:20:36 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:36 INFO - 'talos_options': ['--stylo', 18:20:36 INFO - '--mitmproxy', 18:20:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:36 INFO - '--firstNonBlankPaint'], 18:20:36 INFO - 'tests': ['tp6_google', 18:20:36 INFO - 'tp6_youtube', 18:20:36 INFO - 'tp6_amazon', 18:20:36 INFO - 'tp6_facebook']}, 18:20:36 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:36 INFO - 'talos_options': ['--stylo', 18:20:36 INFO - '--stylo-threads=1', 18:20:36 INFO - '--mitmproxy', 18:20:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:36 INFO - '--firstNonBlankPaint'], 18:20:36 INFO - 'tests': ['tp6_google', 18:20:36 INFO - 'tp6_youtube', 18:20:36 INFO - 'tp6_amazon', 18:20:36 INFO - 'tp6_facebook']}, 18:20:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:20:36 INFO - 'talos_options': ['--xperf_path', 18:20:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:36 INFO - 'tests': ['tp5n']}, 18:20:36 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:20:36 INFO - 'talos_options': ['--stylo', 18:20:36 INFO - '--xperf_path', 18:20:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:36 INFO - 'tests': ['tp5n']}}} 18:20:36 INFO - Running post-action listener: _resource_record_post_action 18:20:36 INFO - [mozharness: 2017-08-28 01:20:36.447069Z] Finished populate-webroot step (success) 18:20:36 INFO - [mozharness: 2017-08-28 01:20:36.447158Z] Running create-virtualenv step. 18:20:36 INFO - Running pre-action listener: _resource_record_pre_action 18:20:36 INFO - Running main action method: create_virtualenv 18:20:36 INFO - Creating virtualenv /builds/slave/test/build/venv 18:20:36 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:20:36 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:20:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:20:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:20:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:20:39 INFO - Installing distribute.............................................................................................................................................................................................done. 18:20:39 INFO - Installing pip...............done. 18:20:39 INFO - Return code: 0 18:20:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:20:39 INFO - Reading from file tmpfile_stdout 18:20:39 INFO - Output received: 18:20:39 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:20:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:20: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')]} 18:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20: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')]} 18:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:39 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 0x17521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1abe130>, '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 0x1abf030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abf4c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', '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 18:20:39 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 18:20:39 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 18:20:39 INFO - Using env: {'DISPLAY': ':0', 18:20:39 INFO - 'HOME': '/home/cltbld', 18:20:39 INFO - 'LANG': 'en_US.UTF-8', 18:20:39 INFO - 'LANGUAGE': 'en_US:en', 18:20:39 INFO - 'LOGNAME': 'cltbld', 18:20:39 INFO - 'MAIL': '/var/mail/cltbld', 18:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:39 INFO - 'MOZ_NO_REMOTE': '1', 18:20:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:39 INFO - 'NO_EM_RESTART': '1', 18:20:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:39 INFO - 'PWD': '/builds/slave/test', 18:20:39 INFO - 'SHELL': '/bin/bash', 18:20:39 INFO - 'SHLVL': '1', 18:20:39 INFO - 'TERM': 'linux', 18:20:39 INFO - 'TMOUT': '86400', 18:20:39 INFO - 'USER': 'cltbld', 18:20:39 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:39 INFO - '_': '/tools/buildbot/bin/python'} 18:20:40 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:20:40 INFO - Downloading/unpacking pip>=1.5 18:20:40 INFO - Running setup.py egg_info for package pip 18:20:40 INFO - warning: no files found matching 'pip/cacert.pem' 18:20:40 INFO - warning: no files found matching '*.html' under directory 'docs' 18:20:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:20:40 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:20:40 INFO - Installing collected packages: pip 18:20:40 INFO - Found existing installation: pip 1.1 18:20:40 INFO - Uninstalling pip: 18:20:40 INFO - Successfully uninstalled pip 18:20:40 INFO - Running setup.py install for pip 18:20:40 INFO - warning: no files found matching 'pip/cacert.pem' 18:20:40 INFO - warning: no files found matching '*.html' under directory 'docs' 18:20:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:20:40 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:20:40 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:20:40 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:20:40 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:20:40 INFO - Successfully installed pip 18:20:40 INFO - Cleaning up... 18:20:40 INFO - Return code: 0 18:20:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:20:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1abe130>, '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 0x1abf030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abf4c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', '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 18:20:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:20:40 INFO - Using env: {'DISPLAY': ':0', 18:20:40 INFO - 'HOME': '/home/cltbld', 18:20:40 INFO - 'LANG': 'en_US.UTF-8', 18:20:40 INFO - 'LANGUAGE': 'en_US:en', 18:20:40 INFO - 'LOGNAME': 'cltbld', 18:20:40 INFO - 'MAIL': '/var/mail/cltbld', 18:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:40 INFO - 'MOZ_NO_REMOTE': '1', 18:20:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:40 INFO - 'NO_EM_RESTART': '1', 18:20:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:40 INFO - 'PWD': '/builds/slave/test', 18:20:40 INFO - 'SHELL': '/bin/bash', 18:20:40 INFO - 'SHLVL': '1', 18:20:40 INFO - 'TERM': 'linux', 18:20:40 INFO - 'TMOUT': '86400', 18:20:40 INFO - 'USER': 'cltbld', 18:20:40 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:40 INFO - '_': '/tools/buildbot/bin/python'} 18:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:41 INFO - Downloading/unpacking psutil>=3.1.1 18:20:41 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 18:20:41 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 18:20:41 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 18:20:41 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 18:20:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:20:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:42 INFO - Installing collected packages: psutil 18:20:42 INFO - Running setup.py install for psutil 18:20:42 INFO - building 'psutil._psutil_linux' extension 18:20:42 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 18:20:43 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 18:20:43 INFO - building 'psutil._psutil_posix' extension 18:20:43 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 18:20:44 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 18:20:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:44 INFO - Successfully installed psutil 18:20:44 INFO - Cleaning up... 18:20:44 INFO - Return code: 0 18:20:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:20:44 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')]} 18:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:44 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')]} 18:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:44 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 0x17521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1abe130>, '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 0x1abf030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abf4c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', '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 18:20:44 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 18:20:44 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 18:20:44 INFO - Using env: {'DISPLAY': ':0', 18:20:44 INFO - 'HOME': '/home/cltbld', 18:20:44 INFO - 'LANG': 'en_US.UTF-8', 18:20:44 INFO - 'LANGUAGE': 'en_US:en', 18:20:44 INFO - 'LOGNAME': 'cltbld', 18:20:44 INFO - 'MAIL': '/var/mail/cltbld', 18:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:44 INFO - 'MOZ_NO_REMOTE': '1', 18:20:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:44 INFO - 'NO_EM_RESTART': '1', 18:20:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:44 INFO - 'PWD': '/builds/slave/test', 18:20:44 INFO - 'SHELL': '/bin/bash', 18:20:44 INFO - 'SHLVL': '1', 18:20:44 INFO - 'TERM': 'linux', 18:20:44 INFO - 'TMOUT': '86400', 18:20:44 INFO - 'USER': 'cltbld', 18:20:44 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:44 INFO - '_': '/tools/buildbot/bin/python'} 18:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:20:44 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 18:20:44 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 18:20:44 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 18:20:44 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 18:20:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:20:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:20:45 INFO - Installing collected packages: mozsystemmonitor 18:20:45 INFO - Running setup.py install for mozsystemmonitor 18:20:45 INFO - Successfully installed mozsystemmonitor 18:20:45 INFO - Cleaning up... 18:20:45 INFO - Return code: 0 18:20:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1abe130>, '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 0x1abf030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abf4c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', '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 18:20:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:20:45 INFO - Using env: {'DISPLAY': ':0', 18:20:45 INFO - 'HOME': '/home/cltbld', 18:20:45 INFO - 'LANG': 'en_US.UTF-8', 18:20:45 INFO - 'LANGUAGE': 'en_US:en', 18:20:45 INFO - 'LOGNAME': 'cltbld', 18:20:45 INFO - 'MAIL': '/var/mail/cltbld', 18:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:45 INFO - 'MOZ_NO_REMOTE': '1', 18:20:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:45 INFO - 'NO_EM_RESTART': '1', 18:20:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:45 INFO - 'PWD': '/builds/slave/test', 18:20:45 INFO - 'SHELL': '/bin/bash', 18:20:45 INFO - 'SHLVL': '1', 18:20:45 INFO - 'TERM': 'linux', 18:20:45 INFO - 'TMOUT': '86400', 18:20:45 INFO - 'USER': 'cltbld', 18:20:45 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:45 INFO - '_': '/tools/buildbot/bin/python'} 18:20:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:45 INFO - Downloading/unpacking jsonschema==2.5.1 18:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:20:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:47 INFO - Downloading functools32-3.2.3-2.tar.gz 18:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:20:47 INFO - warning: no files found matching '*.txt' 18:20:47 INFO - no previously-included directories found matching 'build' 18:20:47 INFO - no previously-included directories found matching 'dist' 18:20:47 INFO - no previously-included directories found matching '.git*' 18:20:47 INFO - Installing collected packages: jsonschema, functools32 18:20:47 INFO - Running setup.py install for functools32 18:20:47 INFO - warning: no files found matching '*.txt' 18:20:47 INFO - no previously-included directories found matching 'build' 18:20:47 INFO - no previously-included directories found matching 'dist' 18:20:47 INFO - no previously-included directories found matching '.git*' 18:20:47 INFO - Successfully installed jsonschema functools32 18:20:47 INFO - Cleaning up... 18:20:47 INFO - Return code: 0 18:20:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1abe130>, '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 0x1abf030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abf4c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', '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 18:20:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:20:47 INFO - Using env: {'DISPLAY': ':0', 18:20:47 INFO - 'HOME': '/home/cltbld', 18:20:47 INFO - 'LANG': 'en_US.UTF-8', 18:20:47 INFO - 'LANGUAGE': 'en_US:en', 18:20:47 INFO - 'LOGNAME': 'cltbld', 18:20:47 INFO - 'MAIL': '/var/mail/cltbld', 18:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:47 INFO - 'MOZ_NO_REMOTE': '1', 18:20:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:47 INFO - 'NO_EM_RESTART': '1', 18:20:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:47 INFO - 'PWD': '/builds/slave/test', 18:20:47 INFO - 'SHELL': '/bin/bash', 18:20:47 INFO - 'SHLVL': '1', 18:20:47 INFO - 'TERM': 'linux', 18:20:47 INFO - 'TMOUT': '86400', 18:20:47 INFO - 'USER': 'cltbld', 18:20:47 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:47 INFO - '_': '/tools/buildbot/bin/python'} 18:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:20:47 INFO - Cleaning up... 18:20:47 INFO - Return code: 0 18:20:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1abe130>, '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 0x1abf030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abf4c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', '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 18:20:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:20:47 INFO - Using env: {'DISPLAY': ':0', 18:20:47 INFO - 'HOME': '/home/cltbld', 18:20:47 INFO - 'LANG': 'en_US.UTF-8', 18:20:47 INFO - 'LANGUAGE': 'en_US:en', 18:20:47 INFO - 'LOGNAME': 'cltbld', 18:20:47 INFO - 'MAIL': '/var/mail/cltbld', 18:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:47 INFO - 'MOZ_NO_REMOTE': '1', 18:20:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:47 INFO - 'NO_EM_RESTART': '1', 18:20:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:47 INFO - 'PWD': '/builds/slave/test', 18:20:47 INFO - 'SHELL': '/bin/bash', 18:20:47 INFO - 'SHLVL': '1', 18:20:47 INFO - 'TERM': 'linux', 18:20:47 INFO - 'TMOUT': '86400', 18:20:47 INFO - 'USER': 'cltbld', 18:20:47 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:47 INFO - '_': '/tools/buildbot/bin/python'} 18:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:47 INFO - Downloading/unpacking blobuploader==1.2.4 18:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:48 INFO - Downloading blobuploader-1.2.4.tar.gz 18:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:20:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:20:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:49 INFO - Downloading docopt-0.6.1.tar.gz 18:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:20:49 INFO - Installing collected packages: blobuploader, requests, docopt 18:20:49 INFO - Running setup.py install for blobuploader 18:20:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:20:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:20:49 INFO - Running setup.py install for requests 18:20:50 INFO - Running setup.py install for docopt 18:20:50 INFO - Successfully installed blobuploader requests docopt 18:20:50 INFO - Cleaning up... 18:20:50 INFO - Return code: 0 18:20:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:50 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')]} 18:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:50 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')]} 18:20:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:50 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 0x17521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1abe130>, '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 0x1abf030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abf4c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', '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 18:20:50 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 18:20:50 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 18:20:50 INFO - Using env: {'DISPLAY': ':0', 18:20:50 INFO - 'HOME': '/home/cltbld', 18:20:50 INFO - 'LANG': 'en_US.UTF-8', 18:20:50 INFO - 'LANGUAGE': 'en_US:en', 18:20:50 INFO - 'LOGNAME': 'cltbld', 18:20:50 INFO - 'MAIL': '/var/mail/cltbld', 18:20:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:50 INFO - 'MOZ_NO_REMOTE': '1', 18:20:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:50 INFO - 'NO_EM_RESTART': '1', 18:20:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:50 INFO - 'PWD': '/builds/slave/test', 18:20:50 INFO - 'SHELL': '/bin/bash', 18:20:50 INFO - 'SHLVL': '1', 18:20:50 INFO - 'TERM': 'linux', 18:20:50 INFO - 'TMOUT': '86400', 18:20:50 INFO - 'USER': 'cltbld', 18:20:50 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:20:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:50 INFO - '_': '/tools/buildbot/bin/python'} 18:20:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:50 INFO - Running setup.py (path:/tmp/pip-BvxB7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:50 INFO - Running setup.py (path:/tmp/pip-PyRsD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:50 INFO - Running setup.py (path:/tmp/pip-rCkVfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:50 INFO - Running setup.py (path:/tmp/pip-6km7qV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:50 INFO - Running setup.py (path:/tmp/pip-gNXf79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:50 INFO - Running setup.py (path:/tmp/pip-x_l0cF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:50 INFO - Running setup.py (path:/tmp/pip-EXEXNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:50 INFO - Running setup.py (path:/tmp/pip-irCxrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:50 INFO - Running setup.py (path:/tmp/pip-3vXU2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:51 INFO - Running setup.py (path:/tmp/pip-EQhs7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:51 INFO - Running setup.py (path:/tmp/pip-R_advh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:51 INFO - Running setup.py (path:/tmp/pip-zn37i7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:51 INFO - Running setup.py (path:/tmp/pip-aBI71c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:51 INFO - Running setup.py (path:/tmp/pip-4iiwlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:51 INFO - Running setup.py (path:/tmp/pip-TUGWl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:51 INFO - Running setup.py (path:/tmp/pip-vGz8V3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:51 INFO - Running setup.py (path:/tmp/pip-sbrS0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:20:51 INFO - Running setup.py install for manifestparser 18:20:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:20:51 INFO - Running setup.py install for mozcrash 18:20:51 INFO - Running setup.py install for mozdebug 18:20:51 INFO - Running setup.py install for mozdevice 18:20:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:20:51 INFO - Running setup.py install for mozfile 18:20:52 INFO - Running setup.py install for mozhttpd 18:20:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:20:52 INFO - Running setup.py install for mozinfo 18:20:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:20:52 INFO - Running setup.py install for mozInstall 18:20:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:20:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:20:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:20:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:20:52 INFO - Running setup.py install for mozleak 18:20:52 INFO - Running setup.py install for mozlog 18:20:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:20:52 INFO - Running setup.py install for moznetwork 18:20:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:20:52 INFO - Running setup.py install for mozprocess 18:20:52 INFO - Running setup.py install for mozprofile 18:20:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:20:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:20:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:20:52 INFO - Running setup.py install for mozrunner 18:20:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:20:53 INFO - Running setup.py install for mozscreenshot 18:20:53 INFO - Running setup.py install for moztest 18:20:53 INFO - Running setup.py install for mozversion 18:20:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:20:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:20:53 INFO - Cleaning up... 18:20:53 INFO - Return code: 0 18:20:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:53 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 0x17521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1abe130>, '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 0x1abf030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abf4c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', '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 18:20:53 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 18:20:53 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 18:20:53 INFO - Using env: {'DISPLAY': ':0', 18:20:53 INFO - 'HOME': '/home/cltbld', 18:20:53 INFO - 'LANG': 'en_US.UTF-8', 18:20:53 INFO - 'LANGUAGE': 'en_US:en', 18:20:53 INFO - 'LOGNAME': 'cltbld', 18:20:53 INFO - 'MAIL': '/var/mail/cltbld', 18:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:53 INFO - 'MOZ_NO_REMOTE': '1', 18:20:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:53 INFO - 'NO_EM_RESTART': '1', 18:20:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:53 INFO - 'PWD': '/builds/slave/test', 18:20:53 INFO - 'SHELL': '/bin/bash', 18:20:53 INFO - 'SHLVL': '1', 18:20:53 INFO - 'TERM': 'linux', 18:20:53 INFO - 'TMOUT': '86400', 18:20:53 INFO - 'USER': 'cltbld', 18:20:53 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:53 INFO - '_': '/tools/buildbot/bin/python'} 18:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:53 INFO - Running setup.py (path:/tmp/pip-dplKYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:53 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)) 18:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:53 INFO - Running setup.py (path:/tmp/pip-6WE9tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:53 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)) 18:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:53 INFO - Running setup.py (path:/tmp/pip-qhudoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:53 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)) 18:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:53 INFO - Running setup.py (path:/tmp/pip-GKc3Da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:53 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)) 18:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:53 INFO - Running setup.py (path:/tmp/pip-VfGmkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:53 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)) 18:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:53 INFO - Running setup.py (path:/tmp/pip-zTyeo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:54 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)) 18:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:54 INFO - Running setup.py (path:/tmp/pip-dA6nTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:54 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)) 18:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:54 INFO - Running setup.py (path:/tmp/pip-8sTBpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:54 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)) 18:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:54 INFO - Running setup.py (path:/tmp/pip-VC9djA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:54 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)) 18:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:54 INFO - Running setup.py (path:/tmp/pip-aKJr7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:54 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)) 18:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:54 INFO - Running setup.py (path:/tmp/pip-04hX7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:54 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)) 18:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:54 INFO - Running setup.py (path:/tmp/pip-Y9IqAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:54 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)) 18:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:54 INFO - Running setup.py (path:/tmp/pip-OMRKyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:54 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)) 18:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:54 INFO - Running setup.py (path:/tmp/pip-CfVVtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:54 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)) 18:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:54 INFO - Running setup.py (path:/tmp/pip-f6n8dK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:54 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)) 18:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:54 INFO - Running setup.py (path:/tmp/pip-gfcml4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:54 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)) 18:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:54 INFO - Running setup.py (path:/tmp/pip-rYazSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:54 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)) 18:20:54 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)) 18:20:54 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)) 18:20:54 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)) 18:20:54 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)) 18:20:54 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)) 18:20:54 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)) 18:20:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:20:54 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 18:20:54 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 18:20:54 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 18:20:54 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 18:20:55 INFO - Downloading blessings-1.6.tar.gz 18:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:20:55 INFO - Installing collected packages: blessings 18:20:55 INFO - Running setup.py install for blessings 18:20:56 INFO - Successfully installed blessings 18:20:56 INFO - Cleaning up... 18:20:56 INFO - Return code: 0 18:20:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:20:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:56 INFO - Reading from file tmpfile_stdout 18:20:56 INFO - Current package versions: 18:20:56 INFO - argparse == 1.2.1 18:20:56 INFO - blessings == 1.6 18:20:56 INFO - blobuploader == 1.2.4 18:20:56 INFO - docopt == 0.6.1 18:20:56 INFO - functools32 == 3.2.3-2 18:20:56 INFO - jsonschema == 2.5.1 18:20:56 INFO - manifestparser == 1.1 18:20:56 INFO - mozInstall == 1.13 18:20:56 INFO - mozcrash == 1.0 18:20:56 INFO - mozdebug == 0.1 18:20:56 INFO - mozdevice == 0.51 18:20:56 INFO - mozfile == 1.2 18:20:56 INFO - mozhttpd == 0.7 18:20:56 INFO - mozinfo == 0.10 18:20:56 INFO - mozleak == 0.1 18:20:56 INFO - mozlog == 3.5 18:20:56 INFO - moznetwork == 0.27 18:20:56 INFO - mozprocess == 0.25 18:20:56 INFO - mozprofile == 0.28 18:20:56 INFO - mozrunner == 6.13 18:20:56 INFO - mozscreenshot == 0.1 18:20:56 INFO - mozsystemmonitor == 0.3 18:20:56 INFO - moztest == 0.8 18:20:56 INFO - mozversion == 1.4 18:20:56 INFO - psutil == 3.1.1 18:20:56 INFO - requests == 1.2.3 18:20:56 INFO - wsgiref == 0.1.2 18:20:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:56 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 0x17521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1abe130>, '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 0x1abf030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abf4c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', '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 18:20:56 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 18:20:56 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 18:20:56 INFO - Using env: {'DISPLAY': ':0', 18:20:56 INFO - 'HOME': '/home/cltbld', 18:20:56 INFO - 'LANG': 'en_US.UTF-8', 18:20:56 INFO - 'LANGUAGE': 'en_US:en', 18:20:56 INFO - 'LOGNAME': 'cltbld', 18:20:56 INFO - 'MAIL': '/var/mail/cltbld', 18:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:56 INFO - 'MOZ_NO_REMOTE': '1', 18:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:56 INFO - 'NO_EM_RESTART': '1', 18:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:56 INFO - 'PWD': '/builds/slave/test', 18:20:56 INFO - 'SHELL': '/bin/bash', 18:20:56 INFO - 'SHLVL': '1', 18:20:56 INFO - 'TERM': 'linux', 18:20:56 INFO - 'TMOUT': '86400', 18:20:56 INFO - 'USER': 'cltbld', 18:20:56 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:56 INFO - '_': '/tools/buildbot/bin/python'} 18:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:56 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)) 18:20:56 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)) 18:20:56 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)) 18:20:56 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)) 18:20:56 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)) 18:20:56 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)) 18:20:56 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)) 18:20:56 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)) 18:20:56 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)) 18:20:56 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:20:57 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)) 18:20:57 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)) 18:20:57 INFO - Installing collected packages: simplejson 18:20:57 INFO - Running setup.py install for simplejson 18:20:57 INFO - building 'simplejson._speedups' extension 18:20:57 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 18:20:58 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 18:20:58 INFO - Successfully installed simplejson 18:20:58 INFO - Cleaning up... 18:20:58 INFO - Return code: 0 18:20:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:58 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 0x17521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1abe130>, '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 0x1abf030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abf4c0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', '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 18:20:58 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 18:20:58 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 18:20:58 INFO - Using env: {'DISPLAY': ':0', 18:20:58 INFO - 'HOME': '/home/cltbld', 18:20:58 INFO - 'LANG': 'en_US.UTF-8', 18:20:58 INFO - 'LANGUAGE': 'en_US:en', 18:20:58 INFO - 'LOGNAME': 'cltbld', 18:20:58 INFO - 'MAIL': '/var/mail/cltbld', 18:20:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:58 INFO - 'MOZ_NO_REMOTE': '1', 18:20:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:58 INFO - 'NO_EM_RESTART': '1', 18:20:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:58 INFO - 'PWD': '/builds/slave/test', 18:20:58 INFO - 'SHELL': '/bin/bash', 18:20:58 INFO - 'SHLVL': '1', 18:20:58 INFO - 'TERM': 'linux', 18:20:58 INFO - 'TMOUT': '86400', 18:20:58 INFO - 'USER': 'cltbld', 18:20:58 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:20:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:58 INFO - '_': '/tools/buildbot/bin/python'} 18:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:20:59 INFO - Cleaning up... 18:20:59 INFO - Return code: 0 18:20:59 INFO - Running post-action listener: _resource_record_post_action 18:20:59 INFO - Running post-action listener: _start_resource_monitoring 18:20:59 INFO - Starting resource monitoring. 18:20:59 INFO - [mozharness: 2017-08-28 01:20:59.068700Z] Finished create-virtualenv step (success) 18:20:59 INFO - [mozharness: 2017-08-28 01:20:59.069860Z] Running install step. 18:20:59 INFO - Running pre-action listener: _resource_record_pre_action 18:20:59 INFO - Running main action method: install 18:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:59 INFO - Reading from file tmpfile_stdout 18:20:59 INFO - Detecting whether we're running mozinstall >=1.0... 18:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:20:59 INFO - Reading from file tmpfile_stdout 18:20:59 INFO - Output received: 18:20:59 INFO - Usage: mozinstall [options] installer 18:20:59 INFO - Options: 18:20:59 INFO - -h, --help show this help message and exit 18:20:59 INFO - -d DEST, --destination=DEST 18:20:59 INFO - Directory to install application into. [default: 18:20:59 INFO - "/builds/slave/test"] 18:20:59 INFO - --app=APP Application being installed. [default: firefox] 18:20:59 INFO - mkdir: /builds/slave/test/build/application 18:20:59 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'] 18:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:21:17 INFO - Reading from file tmpfile_stdout 18:21:17 INFO - Output received: 18:21:17 INFO - /builds/slave/test/build/application/firefox/firefox 18:21:17 INFO - Running post-action listener: _resource_record_post_action 18:21:17 INFO - [mozharness: 2017-08-28 01:21:17.080480Z] Finished install step (success) 18:21:17 INFO - [mozharness: 2017-08-28 01:21:17.080626Z] Running setup-mitmproxy step. 18:21:17 INFO - Running pre-action listener: _resource_record_pre_action 18:21:17 INFO - Running main action method: setup_mitmproxy 18:21:17 INFO - Skipping: mitmproxy is not required 18:21:17 INFO - Running post-action listener: _resource_record_post_action 18:21:17 INFO - [mozharness: 2017-08-28 01:21:17.082109Z] Finished setup-mitmproxy step (success) 18:21:17 INFO - [mozharness: 2017-08-28 01:21:17.082421Z] Running run-tests step. 18:21:17 INFO - Running pre-action listener: _resource_record_pre_action 18:21:17 INFO - Running pre-action listener: _set_gcov_prefix 18:21:17 INFO - Running main action method: run_tests 18:21:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:21:17 INFO - Python 2.7.3 18:21:17 INFO - Return code: 0 18:21:17 INFO - grabbing minidump binary from tooltool 18:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:17 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1abe130>, '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 0x1abf030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1abf4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:21:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:21:17 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:21:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:21:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:21:17 INFO - Return code: 0 18:21:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:21:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:21:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:21:17 INFO - ENV: RUST_BACKTRACE is now full 18:21:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:21:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:21:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 18:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 18:21:17 INFO - Using env: {'DISPLAY': ':0', 18:21:17 INFO - 'HOME': '/home/cltbld', 18:21:17 INFO - 'LANG': 'en_US.UTF-8', 18:21:17 INFO - 'LANGUAGE': 'en_US:en', 18:21:17 INFO - 'LOGNAME': 'cltbld', 18:21:17 INFO - 'MAIL': '/var/mail/cltbld', 18:21:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:21:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:17 INFO - 'MOZ_NO_REMOTE': '1', 18:21:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:21:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:17 INFO - 'NO_EM_RESTART': '1', 18:21:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:17 INFO - 'PWD': '/builds/slave/test', 18:21:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:21:17 INFO - 'RUST_BACKTRACE': 'full', 18:21:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:21:17 INFO - 'SHELL': '/bin/bash', 18:21:17 INFO - 'SHLVL': '1', 18:21:17 INFO - 'STYLO_FORCE_ENABLED': '1', 18:21:17 INFO - 'TERM': 'linux', 18:21:17 INFO - 'TMOUT': '86400', 18:21:17 INFO - 'USER': 'cltbld', 18:21:17 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:17 INFO - '_': '/tools/buildbot/bin/python'} 18:21:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 18:21:17 INFO - mozversion application_buildid: 20170828003210 18:21:17 INFO - mozversion application_changeset: d10c97627b51a226e19d0fa801201897fe1932f6 18:21:17 INFO - mozversion application_display_name: Nightly 18:21:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:21:17 INFO - mozversion application_name: Firefox 18:21:17 INFO - mozversion application_remotingname: firefox 18:21:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:21:17 INFO - mozversion application_vendor: Mozilla 18:21:17 INFO - mozversion application_version: 57.0a1 18:21:17 INFO - mozversion platform_buildid: 20170828003210 18:21:17 INFO - mozversion platform_changeset: d10c97627b51a226e19d0fa801201897fe1932f6 18:21:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:21:17 INFO - mozversion platform_version: 57.0a1 18:21:17 INFO - using testdate: 1503883277 18:21:17 INFO - actual date: 1503883277 18:21:17 INFO - starting webserver on 'localhost:41660' 18:21:17 INFO - SUITE-START | Running 2 tests 18:21:17 INFO - TEST-START | dromaeo_css 18:21:17 INFO - Initialising browser for dromaeo_css test... 18:21:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41660/getInfo.html -profile /tmp/tmpWtBIIZ/profile 18:21:17 INFO - TEST-INFO | started process 3473 (/builds/slave/test/build/application/firefox/firefox http://localhost:41660/getInfo.html) 18:21:25 INFO - TEST-INFO | 3473: exit 0 18:21:25 INFO - Browser initialized. 18:21:25 INFO - Running cycle 1/1 for dromaeo_css test... 18:21:25 INFO - Using env: {'DISPLAY': ':0', 18:21:25 INFO - 'HOME': '/home/cltbld', 18:21:25 INFO - 'JSGC_DISABLE_POISONING': '1', 18:21:25 INFO - 'LANG': 'en_US.UTF-8', 18:21:25 INFO - 'LANGUAGE': 'en_US:en', 18:21:25 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:21:25 INFO - 'LOGNAME': 'cltbld', 18:21:25 INFO - 'MAIL': '/var/mail/cltbld', 18:21:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:21:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:21:25 INFO - 'MOZ_CRASHREPORTER': '1', 18:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:25 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:21:25 INFO - 'MOZ_NO_REMOTE': '1', 18:21:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:25 INFO - 'NO_EM_RESTART': '1', 18:21:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:25 INFO - 'PWD': '/builds/slave/test', 18:21:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:21:25 INFO - 'RUST_BACKTRACE': 'full', 18:21:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:21:25 INFO - 'SHELL': '/bin/bash', 18:21:25 INFO - 'SHLVL': '1', 18:21:25 INFO - 'STYLO_FORCE_ENABLED': '1', 18:21:25 INFO - 'TERM': 'linux', 18:21:25 INFO - 'TMOUT': '86400', 18:21:25 INFO - 'USER': 'cltbld', 18:21:25 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:25 INFO - '_': '/tools/buildbot/bin/python'} 18:21:25 INFO - TEST-INFO | started process 3705 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWtBIIZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:26 INFO - PID 3705 | 18:21:26 INFO - PID 3705 | (/builds/slave/test/build/application/firefox/firefox:3754): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:21:26 INFO - PID 3705 | 18:21:28 INFO - PID 3705 | 18:21:28 INFO - PID 3705 | (/builds/slave/test/build/application/firefox/firefox:3816): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:21:28 INFO - PID 3705 | 18:21:28 INFO - PID 3705 | __metrics Screen width/height:1600/1200 18:21:28 INFO - PID 3705 | colorDepth:24 18:21:28 INFO - PID 3705 | Browser inner width/height: 1024/768 18:21:28 INFO - PID 3705 | __metrics 18:21:36 INFO - PID 3705 | RSS: Main: 175177728 18:21:36 INFO - PID 3705 | 18:24:35 INFO - PID 3705 | Cycle 1(1): loaded http://localhost:41660/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41660/tests/dromaeo/cssquery-ext.html) 18:24:36 INFO - PID 3705 | RSS: Main: 178544640 18:24:36 INFO - PID 3705 | 18:27:38 INFO - PID 3705 | Cycle 1(1): loaded http://localhost:41660/tests/dromaeo/cssquery-ext.html (next: http://localhost:41660/tests/dromaeo/cssquery-jquery.html) 18:27:39 INFO - PID 3705 | RSS: Main: 175128576 18:27:39 INFO - PID 3705 | 18:30:40 INFO - PID 3705 | Cycle 1(1): loaded http://localhost:41660/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41660/tests/dromaeo/cssquery-mootools.html) 18:30:41 INFO - PID 3705 | RSS: Main: 177483776 18:30:41 INFO - PID 3705 | 18:33:43 INFO - PID 3705 | Cycle 1(1): loaded http://localhost:41660/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41660/tests/dromaeo/cssquery-prototype.html) 18:33:43 INFO - PID 3705 | RSS: Main: 175185920 18:33:43 INFO - PID 3705 | 18:36:47 INFO - PID 3705 | Cycle 1(1): loaded http://localhost:41660/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41660/tests/dromaeo/cssquery-yui.html) 18:36:48 INFO - PID 3705 | RSS: Main: 175448064 18:36:48 INFO - PID 3705 | 18:39:49 INFO - PID 3705 | Cycle 1(1): loaded http://localhost:41660/tests/dromaeo/cssquery-yui.html (next: http://localhost:41660/tests/dromaeo/cssquery-dojo.html) 18:39:50 INFO - PID 3705 | RSS: Main: 179687424 18:39:50 INFO - PID 3705 | 18:39:50 INFO - PID 3705 | __start_tp_report 18:39:50 INFO - PID 3705 | _x_x_mozilla_page_load 18:39:50 INFO - PID 3705 | _x_x_mozilla_page_load_details 18:39:50 INFO - PID 3705 | |i|pagename|runs| 18:39:50 INFO - PID 3705 | |0;dojo.html;3236.3203727217283;3256.869725210992;3266.8856590019345;3292.2098696312883;3330.8834190803327;6295.653739044351;6311.715972781224;6385.786700526903;6404.551681382305;6425.036244563318;6302.180716506848;6324.177856878599;6415.06981487684;6432.839179020527;6462.159919210496;5692.800751973682;5772.336181339137;5818.389069147742;5832.212651292064;5915.73379197935;5779.537636989052;5814.709264536757;5913.201717768089;5924.229850119478;5941.138534912435;5804.680742559158;5838.195197023;5891.587588868781;5922.200502932092;5964.433378829004;5806.117675163964;5808.3030036395485;5848.853778655525;5895.941040589582;5901.498372638331;5779.682117483537;5786.3056433228285;5822.563307751893;5833.912491312633;5851.180834683148;6268.059791031336;6273.498120150422;6328.873422531569;6339.302676705559;6398.616083035033;6637.469002479838;6665.866682666319;6776.356246156607;6838.213605435389;6861.142357205349;6803.727850885959;6921.723131074751;6941.861162776767;6950.061741664861;6960.860782784316;6413.262474815363;6466.515011374166;6468.935310646881;6471;6482.092507049017;6891.241963384024;7019.333163349474;7025.227225005246;7122;7153.0343403639845;6704.66476676174;6706.396424321834;6710.228323742735;6714.966425167843;6731.2932142125355;6708.932910670831;6751.93248067513;6773.93226067733;6774.254831968479;6784.830379240559;6742.898856517156;6743.494237932076;6751.493637977172;6754.662266886636;6757.459403247728;6755.392014718699;6761.526693131434;6763.661816909135;6766.458683305324;6767.492438067165;5346.518813306821;5352.598555108382;5415;5424.240606315119;5431.429699881534;4703.412073490814;4703.45910220329;4711.528847115261;4744.264638980963;4793.976030119827;5976.910346344808;5982.252218472691;6073.96963015191;6086.3000754913755;6100.23747031621;6713.227978782503;6813.931860681429;6824.249332573413;6845.075914751545;6921.6193109379465;5987.161797348374;6021.42796434338;6021.42796434338;6035.064564992434;6049.697515124226;5856.209411729447;5909.8227053188475;5937.079752638348;5942.613730107616;5952.8809423812145;6502.187226596676;6545.639989800511;6574.408303252731;6621.966890165519;6633.336666333328;6737.225219099866;6833.145856767904;6849.48628852848;6917.481188910952;6960.199577048705;6546.9524876019605;6550.6069635822005;6555.180602424697;6560.40956313934;6560.803175904731;6604.24051234095;6758.256591774999;6768.864622707617;6784.05023296729;6791.456683465212;6808.353206445379;6821.249662537018;6836.5214434989075;6928.030075789293;6934.514583979073;6549.443297319781;6560.179977502812;6643.60138391698;6644.269130395749;6650.800475985729;6499.805005849832;6516.57642253252;6581.979793132072;6640.07039014548;6657;6479.838004049937;6520.413162815369;6524.869502610016;6533.020046992989;6545.967270163619;6526.216853977553;6579.80260592183;6626.171728533934;6633.734650613921;6677.232118306457;6395.968020159869;6441.259255185713;6449.806505804833;6454.548181627241;6510.544261901621 18:39:50 INFO - PID 3705 | |1;ext.html;793.1513280789558;830.1117803949769;853.6329378367302;880.5289170293892;882.5807741322868;38061;39178.21643567127;39191.80404097979;39235.21529569407;39238.60761392385;35327.4700879487;35647.643523564824;35679.2864142717;35680.64319356806;35754.64245357552;6858.2798806125265;6938.028675985366;6943.270956549565;6951;6995.370416662499;6705.932940670604;6711.892537731293;6716.83207919801;6795.93204067958;6838.829029274259;7487;7591.051118610174;7596.430267729915;7672.846543069135;7687.577183254919;7494.587797671126;7543.358814500747;7545.660445279976;7566.356859666907;7571.053618297713;7308.415326773846;7511.661975211074;7568.924310756932;7596.050493688289;7596.924030759732;36207;36551.26897462062;36589.08527286813;36598;36616;3398.762086653935;3444.6383129771266;3447.172678557128;3448.069021364243;3450.189205536697;29577.260568485744;29605.70394296051;29608.55587166194;29714.40571188564;29738.256543586365;5004.399472063339;5027.321311622957;5041.0422019816115;5044.041632089891;5156.690598564098;217887;221208;221478.9999999984;222465;223515;45662.77168614202;46512.3023154653;46533.76733116313;46568;46761.53238467572;47892.76053619779;48019.75990120027;48027;48070.278945815844;50098.49901500938;15542.30059647296;15833.208339582974;15842.60393490149;15854;15857.920710396374;12976.221426714428;13001.804972925414;13015.04774283865;13019.934900325627;13037.739245215233;13727.931360343135;13871.514496992557;13939.09395889264;14014.299285035708;14064.296785160906;5568.916466253009;5590.329160500766;5611.298587676541;5618.606697531215;5685.857853553694;971.825071487125;996.7508122969258;1005.8893521712606;1021.7598864266891;1023.948802559869;22108.778912210993;22422.775772242396;22493;22502;22541.323760287218;1094.8412480190327;1095.2005036323528;1097.3909480238385;1100.3617901617042;1111.9888801112047;11846.170768046153;12010.339431331186;12028.338441385808;12047.638570842888;12048.578299759467;233090;235426;235439;235954;236545;45521.089578208914;48654.513454864995;49621.75189124031;52045.47954520482;53519.464805352225;1281.20565249544;1398.0353556046296;1409.6616811965055;1411.2077661370004;1421.1326207738455;25821;26524.469510610066;26578.069767558816;26784.866075669495;27359.589606155525;47548;47697;47772.2834157481;47812;47877;207829;209378;209441;210194;211042;34077.14807129842;34144.829275854456;34164.82917585396;34465.82767086149;34633.307333853685;41006.79496602617;42131.157376852905;42255.366169506866;42401.36397953972;42402.363964541175;30476.238094046937;30597.23506912345;30624.387512250076;30786.230344241572;30891.536626950165;30156;30309.242268943453;30540.84729576338;31141.8442907784;31293.217669558442;34489.137771555914;34748.65251347555;34791;34845;35244.29511409706;29820.254493637833;30261;30407.54388684216;30519.389612208077;30678.84660576772;22692.319230423112;23068.653970190797;23351.534644824686;23531.647025294977;23676.408089797205 18:39:50 INFO - PID 3705 | |2;jquery.html;540.7616558081993;548.3858078951574;552.5551930695789;554.2988120028162;561.2171021425112;21376.35870923871;22182.334529964126;22187.77812221879;22204.88897555512;22542.21102261421;25733.742662573368;26270.868645656792;26287.211383658472;26295.605565916478;26369.604455933128;6448.097266382711;6585.308542603029;6607.20713514379;6607.273199948002;6620.238672552646;6454;6507.121538592301;6597.175353080865;6890.31096890312;6930.099087118668;6902.723891044352;6909.654517274142;6915.619640919747;6921.273266307041;6921.688524016431;6903.896441553381;6917.688704008307;6927.238003819576;6930.549514281581;6935.514513984023;6758.864822703567;6774.762883299061;6776.119104516381;6780.762673306411;6793.728250869959;30202.697973020207;31251.687483125334;31403.528947065814;31505.8424707875;31926.840365798023;2035;2077.5948690005457;2079.0332495389566;2083.1146762625976;2086.1029757204396;20259.594808103757;20693.482662933395;21065.47336316589;21068.26261080855;21373.786262137335;2527.2165628655175;2543.2116044026225;2576.1756238003845;2580.1227582622;2590.2617753940035;131456.3427182858;134453;137348;140219.99999999898;140972.29513852365;20512.79487205139;21319.360419187447;22408;22476;22507.662385064235;42404.45573265552;42605.36091958623;42612.57387426148;42622;42703.78648106802;11623.186376953701;11735.647930562096;11764.294142351315;11774.529018839321;11926.701832454084;7554.66004029809;9805.950970245103;9820.459874706818;9909.851352229722;9945.701628951143;9794.706158815246;11287.153463490107;11302.773944521228;11389.715257118472;11445.828312575319;3736.7197460190596;3977.602239776057;4088;4288.678349123828;4303.859477238534;932.5943214701626;961.8172547216006;964.0879717837746;973.5181085362791;978.3005151316843;19697.606047878962;19906.203751849764;20001.09995050197;20101.597968040558;20349.491262718253;986.6990567876867;1027.091024443373;1041.8645576075062;1041.9687409377732;1051.663467690347;10866.348019118877;10909.18181136418;10983.505742241618;11088.279261848116;11140.554377824958;556640;570831;581294;584405;589187;123473;124118;124985.37507312588;126684;127002;1201.4833621542646;1214.332117335462;1223.4555622747791;1223.5839814463304;1227.183922691418;16908.492745217172;17872.463826085237;17921.641567168845;21309.25417610376;22633.321000370015;59843;59953;60910.390896090474;62460.06309905446;63591;71627.28372716207;72942;73428.63285683538;73615.26384736298;73735;14269.571912842632;14614.269286535844;14684.118952862435;14691.265436728334;14694;23503.17738879131;24697.87651061733;24790.628140578265;24834.50330993406;24862.875685621457;19093.61812763765;19159.233630654617;19327.03364831781;19338.51653708612;19674.80325196787;16869.578260543585;16918.32326706967;16924.74612880783;17157.91421042887;17682.381116660854;19610.80389196147;19716.01419928969;19719.802801971797;19924.70112948336;19946.102425391182;14438.422463101359;14528.27358632043;14532.782008270096;14545.200013999332;14659.853401465849;18287;18550.628987420445;18630.254789808798;19244.422667319443;19283.90358048257 18:39:50 INFO - PID 3705 | |3;mootools.html;1300.8829205371517;1345.199606234291;1351.49319005373;1361.4758318047543;1366.6446723851802;14423.567292981214;15030.323635436403;15109.546713598582;15147.92426037871;15156.924215378935;14841.628959276024;15411.537653870373;15424.305906234218;15425.845741542582;15471.22643867805;2739.260399692082;2813.5920291557713;2814.268290244531;2819.0978886756247;2820.844853533055;2638.511875303075;2671.7461841125064;2682.570788673813;2769.709180536045;2776.9305767355877;2636.0773729194725;2675.933101672454;2684.8254863433913;2685.1810197889636;2685.5837345211526;2571.4985577812336;2606.609008648699;2636.854972976495;2636.934076648099;2640.3267166872324;2573.755493228159;2603.401217719935;2629.513539995107;2631.5263252614523;2632.0524611139977;14366;14657.853421465756;14677.706445871023;14696.706065878729;14742.262886855722;7743.806404839867;7965.362770978308;7975;7982.401319901031;8028.237317454832;5254.343207099113;5291.259223708684;5411.675299482044;5535.58483113768;5587.972060139714;1839.4389711138097;1842.0789605197401;1855.434092601756;1857.1271502393854;1887.7829049659329;25213.62179567308;25241.24276271715;25500.23499295024;25512.23463296104;25535.233942981555;6482.35176482348;6483.157189565326;6538.346165383424;6618.404343609002;6796.490263230277;16361.672766544603;16406.83593164077;16432.178391080397;16478.917605412134;16541.090240036672;7494.737684181027;7633.427492938052;7638.579878106758;7642.082950045919;7642.7325043623205;5736.62711923732;5800;5822.243108395882;6021.789237376671;7025.086738723933;4972.602191824645;5072.188449848007;5115.590752739772;5128.948710512922;5142.948570514247;1395.665040390299;1434.0496924183103;1443.4731323067006;1445.0968144909432;1452.978205326921;860.694453469022;868.9739307820776;880.9427387219811;886;889.3685483306924;7392.593407362647;7528.247175282428;8029.197080291924;8376.623051962677;8402.873956890651;968.675493709601;1002.4837208837455;1002.5789168549227;1014.949252537385;1016.9796604067877;4503.414556107685;4754.595859351994;4772.427308722976;4873.853784386474;5000.924986125211;20578;21013.264535741175;21415.678764818542;21438.892805535874;21451.570968580854;19549.21803127859;20554.897225514076;20772.376828695164;20806.791932080487;20844.270450534157;10765;11051.226414150933;11053;11063.059639930696;11065.612703555336;7737.883931741141;7912.208779122042;8252.876206857021;8395.370347223861;8445.788855278668;17479.563010924827;17529.123543823014;17560.473185804447;17595.20821563007;17841.286348546433;18147;18238.361657341928;18297;18303.816961830213;18363.724544132117;13539.458421663023;13809.447622095406;13829.792553111913;13835.723285534434;13843.515476958259;13426.597202083953;13524.256165910969;13799.965002624642;13836.861631383557;13863.72272554523;10894.836577451186;10967.122630189726;11052.392118433563;11112.666620001413;11235.606753763579;10336.638217662347;10350.482475876326;10550.630727924487;10553.261271710906;10790.028897399272;9293.210077143249;9328.440293582407;9361.344705870524;9366.718998430058;9436.103570160823;6891.241963384124;6995.055667484824;7081.433485321265;7089.680964356513;7129.572225666477;6611.206655201407;6644.568103073285;6715.3620406061345;6718.865622687421;6764.053032575542 18:39:50 INFO - PID 3705 | |4;prototype.html;500.38202819517915;507.5356049214967;509.05570167339584;525.5847880174662;527.2039220776626;6260.123582698426;6378;6386.393292637198;6396.488280937526;6404.519661025419;6213.2233470816145;6236.688165591725;6270.773875274269;6273.7490500380045;6292.182016337881;4809.086273608021;5026.321446604717;5049.77276022578;5071.467495912932;5080.974595127019;5025.246213068032;5045.243213517965;5055.191169412895;5063.670861393997;5077.873053173664;4898.583620392271;4968.764056174736;5014.974925125369;5017.573506251954;5023.572996295319;4973.4280557735965;5000.82497112603;5013.298138260647;5025.1708468102715;5032.572231360339;4507.5267096955;4519.525449827754;4522.163399771053;4530.433695788027;4543.454785425737;6061.757149854538;6135.233095863017;6175.2589689237575;6185.319614842364;6253.906191407133;5598.748056337475;5626.240457538221;5644.57665675076;5647.971760141214;5709.343425506078;6513.723883654208;6574.605523668595;6606.60360378374;6616.172978377703;6639.435647969928;4831.816205029791;4874.341963834872;4877.707337559757;4890.486498917634;4914.70511769295;6608.306127856602;6822.727090916308;6844.178698556156;6845.828854278633;7030.629027339671;4996.850094497171;5009.774560144802;5023.14606516893;5035.169197082514;5035.77339019745;5090.949090509048;5091.974540127349;5118.208626980601;5118.3090282811345;5122.692638441706;5688.77244910199;5690.2033715279895;5701.885962280731;5732.885342293131;5756.971215143897;6670.966645166744;6683.696679147617;6701.32986701326;6708.563943343667;6751.729930802713;1747.938822141219;1761.383515769496;1762.7444020616938;1764.9647007059787;1775.343123044482;1169.8069818480026;1232.0944106082022;1232.7657745028416;1240.3364200152876;1242.9192102513489;5933.8550568198925;5940.61386009908;5960.284765828128;5968.432998865101;5973.104034394789;6808.727650894008;6812.795616131524;6835.384815366641;6852.623105729133;6878.896816547755;5829.562782791308;5898.528117750569;5920.437558431942;5934.228550288522;5934.554908381802;3614.927701446009;3625.510556074949;3635.6182600826533;3666.7983260920905;3676.7058635309113;6490.837729056811;6508.60948343101;6537.280899101095;6555.278919318871;6567.540272180901;6600.0596904622735;6900.55088431435;6909.170899492093;6981.546199497118;7048;6449.097126402214;6501.674916254263;6520.706568204348;6533.640649764308;6536;6724.428423583854;6751.49363797727;6755.864882702417;6787.253402125663;6889.00109484132;6676.265610782712;6698.263191049078;6709.597424154371;6736.34959434922;6738.225104112894;6396.168498095404;6421;6432.742690292524;6444.226692796707;6450.548626558987;4957.876053098701;4960.603151747924;4968;4999.175136102648;5024.472430394756;6505.284418714033;6537.901931471127;6557.049227861838;6623.437007854386;6766.8646627068165;6339.873202535831;6525.738970441319;6577.50668699859;6586.80239592813;6632.767853125118;6525.934740652533;6529.314421985813;6581.769638062645;6645;6645.169353830771;6453.45145662624;6473.967630161819;6477.222733272038;6560.457353029311;6567.934320656732;6426.196725409324;6465.709043092978;6519.8044058678315;6585.96707016481;6611.537192396679;6301.494431481513;6306.495480361468;6308.589941653778;6387.968060159669;6455.61266324022 18:39:50 INFO - PID 3705 | |5;yui.html;868.7306934850201;881.5636260051274;895.5656506594305;899.9370044096912;907.2424525521192;16436.424725134624;17631.47105586833;17641.735373969386;17735.20191591378;18186;17605.91197044016;18513.814861851377;18715.3449629263;18743.53141171468;18821.153048112832;909.9454032758056;927.3369540778342;927.8097989912086;929.1080562659855;930.6184464369576;881.8544940084878;931.1061381074177;936.4147407870081;946.7775072857872;952.471378384996;992.2855544008303;995.348047029197;996.0885789502596;996.217968894418;996.5615129343066;983.7245571240064;984.7045886234173;990.2672022703184;990.3859607043643;990.990090099097;724.5942272327461;725.1081170160679;726.259215600085;728.0862517540505;728.8688036153491;16842;17142.142892855307;17155.742663860052;17174;17278.13609319529;20720.58558828815;20781.5843683127;21311.68032479514;21358.359249222394;21642;16831.663366732595;17048.147592620444;17304.91347543267;17321.393751218646;17334.653306933917;3688.1517251032415;3730.08612889841;3732.9813350933073;3759.3233218020746;3763.473113764075;118649;119678.40160799227;120602.39698801537;121211;123051.38474307572;15909.284082216329;15981.760273595906;16264.942778719345;16277.430289940032;16374.263158157912;29019.12942611725;29039;29118.56322155127;29228;29290.853545732134;8006.079300880473;8094.919050809534;8185.508869467851;8231.835363292821;8251.628676709563;3729.7948612826553;4173.833046678099;4203.516595591485;4217.072244106292;4274.935875961863;4114.423980642712;5336.599755018389;5537.086380747212;5544.140658197985;5555.8888822223325;1032.339302846179;1057.9576816927238;1073.6027669762238;1073.88187299397;1075.0485820049312;663.8805015097232;680.8059702984625;686.0909295183901;691.6749127909874;694.979150625482;11445.656630301104;11985.580504682293;12004.459799309052;12216.389180540937;12812;652.1717418878093;661.5964261800298;662.2880403566186;680.7004917836235;700.929907009295;5319;5651.237082993825;5750.108733146369;5758.88482230353;6138.987067133873;105630;106414.46792765986;110611;110795;113408;94191.52904235435;95463;97166;97558;97819;81890;84123;85258;85356;87616.56191719128;12562.434690439135;12745.61763147107;12799.232046077264;12851.550195743104;12970.610881673565;39844.60155398409;40001.59998399979;40711.59288407078;41702.374464382454;43729;48982;49272.75363623159;50129;50253.497465024884;51360;16283.755743664091;16648.500544983668;16693.83306166923;16759.91620041892;16864;19966.90016549908;20152.697709534663;20224.393268201977;20406.89796551067;20414.18343266253;18259.908700456413;18549.258029678662;18551;18672.90663546728;18713.345032923782;18201.544961376072;18383.90808045951;18396.724049139008;18481.90759046196;18500.259989600803;17129.743053853952;17279;17312.740308895627;17408;17444.302227910743;11914;12006.819897701367;12104.939475302566;12186.451609677408;12235.571754988532;12917.741645166856;13118.540851070167;13164.275964822027;13220.603381898558;13247.271400073088 18:39:50 INFO - PID 3705 | __end_tp_report 18:39:50 INFO - PID 3705 | __start_cc_report 18:39:50 INFO - PID 3705 | _x_x_mozilla_cycle_collect,366 18:39:50 INFO - PID 3705 | __end_cc_report 18:39:50 INFO - PID 3705 | __startTimestamp1503884390116__endTimestamp 18:39:50 INFO - PID 3705 | 18:39:50 INFO - PID 3705 | ------- Summary: start ------- 18:39:50 INFO - PID 3705 | Number of tests: 6 18:39:50 INFO - PID 3705 | 18:39:50 INFO - PID 3705 | [#0] dojo.html Cycles:165 Average:6274.01 Median:6510.54 stddev:727.56 (11.2%) stddev-sans-first:689.66 18:39:50 INFO - PID 3705 | Values: 3236.3 3256.9 3266.9 3292.2 3330.9 6295.7 6311.7 6385.8 6404.6 6425.0 6302.2 6324.2 6415.1 6432.8 6462.2 5692.8 5772.3 5818.4 5832.2 5915.7 5779.5 5814.7 5913.2 5924.2 5941.1 5804.7 5838.2 5891.6 5922.2 5964.4 5806.1 5808.3 5848.9 5895.9 5901.5 5779.7 5786.3 5822.6 5833.9 5851.2 6268.1 6273.5 6328.9 6339.3 6398.6 6637.5 6665.9 6776.4 6838.2 6861.1 6803.7 6921.7 6941.9 6950.1 6960.9 6413.3 6466.5 6468.9 6471.0 6482.1 6891.2 7019.3 7025.2 7122.0 7153.0 6704.7 6706.4 6710.2 6715.0 6731.3 6708.9 6751.9 6773.9 6774.3 6784.8 6742.9 6743.5 6751.5 6754.7 6757.5 6755.4 6761.5 6763.7 6766.5 6767.5 5346.5 5352.6 5415.0 5424.2 5431.4 4703.4 4703.5 4711.5 4744.3 4794.0 5976.9 5982.3 6074.0 6086.3 6100.2 6713.2 6813.9 6824.2 6845.1 6921.6 5987.2 6021.4 6021.4 6035.1 6049.7 5856.2 5909.8 5937.1 5942.6 5952.9 6502.2 6545.6 6574.4 6622.0 6633.3 6737.2 6833.1 6849.5 6917.5 6960.2 6547.0 6550.6 6555.2 6560.4 6560.8 6604.2 6758.3 6768.9 6784.1 6791.5 6808.4 6821.2 6836.5 6928.0 6934.5 6549.4 6560.2 6643.6 6644.3 6650.8 6499.8 6516.6 6582.0 6640.1 6657.0 6479.8 6520.4 6524.9 6533.0 6546.0 6526.2 6579.8 6626.2 6633.7 6677.2 6396.0 6441.3 6449.8 6454.5 6510.5 18:39:50 INFO - PID 3705 | 18:39:50 INFO - PID 3705 | [#1] ext.html Cycles:180 Average:38445.89 Median:18968.60 stddev:57688.01 (304.1%) stddev-sans-first:57780.57 18:39:50 INFO - PID 3705 | Values: 793.2 830.1 853.6 880.5 882.6 38061.0 39178.2 39191.8 39235.2 39238.6 35327.5 35647.6 35679.3 35680.6 35754.6 6858.3 6938.0 6943.3 6951.0 6995.4 6705.9 6711.9 6716.8 6795.9 6838.8 7487.0 7591.1 7596.4 7672.8 7687.6 7494.6 7543.4 7545.7 7566.4 7571.1 7308.4 7511.7 7568.9 7596.1 7596.9 36207.0 36551.3 36589.1 36598.0 36616.0 3398.8 3444.6 3447.2 3448.1 3450.2 29577.3 29605.7 29608.6 29714.4 29738.3 5004.4 5027.3 5041.0 5044.0 5156.7 217887.0 221208.0 221479.0 222465.0 223515.0 45662.8 46512.3 46533.8 46568.0 46761.5 47892.8 48019.8 48027.0 48070.3 50098.5 15542.3 15833.2 15842.6 15854.0 15857.9 12976.2 13001.8 13015.0 13019.9 13037.7 13727.9 13871.5 13939.1 14014.3 14064.3 5568.9 5590.3 5611.3 5618.6 5685.9 971.8 996.8 1005.9 1021.8 1023.9 22108.8 22422.8 22493.0 22502.0 22541.3 1094.8 1095.2 1097.4 1100.4 1112.0 11846.2 12010.3 12028.3 12047.6 12048.6 233090.0 235426.0 235439.0 235954.0 236545.0 45521.1 48654.5 49621.8 52045.5 53519.5 1281.2 1398.0 1409.7 1411.2 1421.1 25821.0 26524.5 26578.1 26784.9 27359.6 47548.0 47697.0 47772.3 47812.0 47877.0 207829.0 209378.0 209441.0 210194.0 211042.0 34077.1 34144.8 34164.8 34465.8 34633.3 41006.8 42131.2 42255.4 42401.4 42402.4 30476.2 30597.2 30624.4 30786.2 30891.5 30156.0 30309.2 30540.8 31141.8 31293.2 34489.1 34748.7 34791.0 34845.0 35244.3 29820.3 30261.0 30407.5 30519.4 30678.8 22692.3 23068.7 23351.5 23531.6 23676.4 18:39:50 INFO - PID 3705 | 18:39:50 INFO - PID 3705 | [#2] jquery.html Cycles:180 Average:38763.45 Median:22524.94 stddev:96116.89 (426.7%) stddev-sans-first:96343.67 18:39:50 INFO - PID 3705 | Values: 540.8 548.4 552.6 554.3 561.2 21376.4 22182.3 22187.8 22204.9 22542.2 25733.7 26270.9 26287.2 26295.6 26369.6 6448.1 6585.3 6607.2 6607.3 6620.2 6454.0 6507.1 6597.2 6890.3 6930.1 6902.7 6909.7 6915.6 6921.3 6921.7 6903.9 6917.7 6927.2 6930.5 6935.5 6758.9 6774.8 6776.1 6780.8 6793.7 30202.7 31251.7 31403.5 31505.8 31926.8 2035.0 2077.6 2079.0 2083.1 2086.1 20259.6 20693.5 21065.5 21068.3 21373.8 2527.2 2543.2 2576.2 2580.1 2590.3 131456.3 134453.0 137348.0 140220.0 140972.3 20512.8 21319.4 22408.0 22476.0 22507.7 42404.5 42605.4 42612.6 42622.0 42703.8 11623.2 11735.6 11764.3 11774.5 11926.7 7554.7 9806.0 9820.5 9909.9 9945.7 9794.7 11287.2 11302.8 11389.7 11445.8 3736.7 3977.6 4088.0 4288.7 4303.9 932.6 961.8 964.1 973.5 978.3 19697.6 19906.2 20001.1 20101.6 20349.5 986.7 1027.1 1041.9 1042.0 1051.7 10866.3 10909.2 10983.5 11088.3 11140.6 556640.0 570831.0 581294.0 584405.0 589187.0 123473.0 124118.0 124985.4 126684.0 127002.0 1201.5 1214.3 1223.5 1223.6 1227.2 16908.5 17872.5 17921.6 21309.3 22633.3 59843.0 59953.0 60910.4 62460.1 63591.0 71627.3 72942.0 73428.6 73615.3 73735.0 14269.6 14614.3 14684.1 14691.3 14694.0 23503.2 24697.9 24790.6 24834.5 24862.9 19093.6 19159.2 19327.0 19338.5 19674.8 16869.6 16918.3 16924.7 17157.9 17682.4 19610.8 19716.0 19719.8 19924.7 19946.1 14438.4 14528.3 14532.8 14545.2 14659.9 18287.0 18550.6 18630.3 19244.4 19283.9 18:39:50 INFO - PID 3705 | 18:39:50 INFO - PID 3705 | [#3] mootools.html Cycles:180 Average:9082.08 Median:2588.58 stddev:6471.73 (250.0%) stddev-sans-first:6463.48 18:39:50 INFO - PID 3705 | Values: 1300.9 1345.2 1351.5 1361.5 1366.6 14423.6 15030.3 15109.5 15147.9 15156.9 14841.6 15411.5 15424.3 15425.8 15471.2 2739.3 2813.6 2814.3 2819.1 2820.8 2638.5 2671.7 2682.6 2769.7 2776.9 2636.1 2675.9 2684.8 2685.2 2685.6 2571.5 2606.6 2636.9 2636.9 2640.3 2573.8 2603.4 2629.5 2631.5 2632.1 14366.0 14657.9 14677.7 14696.7 14742.3 7743.8 7965.4 7975.0 7982.4 8028.2 5254.3 5291.3 5411.7 5535.6 5588.0 1839.4 1842.1 1855.4 1857.1 1887.8 25213.6 25241.2 25500.2 25512.2 25535.2 6482.4 6483.2 6538.3 6618.4 6796.5 16361.7 16406.8 16432.2 16478.9 16541.1 7494.7 7633.4 7638.6 7642.1 7642.7 5736.6 5800.0 5822.2 6021.8 7025.1 4972.6 5072.2 5115.6 5128.9 5142.9 1395.7 1434.0 1443.5 1445.1 1453.0 860.7 869.0 880.9 886.0 889.4 7392.6 7528.2 8029.2 8376.6 8402.9 968.7 1002.5 1002.6 1014.9 1017.0 4503.4 4754.6 4772.4 4873.9 5000.9 20578.0 21013.3 21415.7 21438.9 21451.6 19549.2 20554.9 20772.4 20806.8 20844.3 10765.0 11051.2 11053.0 11063.1 11065.6 7737.9 7912.2 8252.9 8395.4 8445.8 17479.6 17529.1 17560.5 17595.2 17841.3 18147.0 18238.4 18297.0 18303.8 18363.7 13539.5 13809.4 13829.8 13835.7 13843.5 13426.6 13524.3 13800.0 13836.9 13863.7 10894.8 10967.1 11052.4 11112.7 11235.6 10336.6 10350.5 10550.6 10553.3 10790.0 9293.2 9328.4 9361.3 9366.7 9436.1 6891.2 6995.1 7081.4 7089.7 7129.6 6611.2 6644.6 6715.4 6718.9 6764.1 18:39:50 INFO - PID 3705 | 18:39:50 INFO - PID 3705 | [#4] prototype.html Cycles:180 Average:5545.08 Median:6245.30 stddev:1551.25 (24.8%) stddev-sans-first:1508.68 18:39:50 INFO - PID 3705 | Values: 500.4 507.5 509.1 525.6 527.2 6260.1 6378.0 6386.4 6396.5 6404.5 6213.2 6236.7 6270.8 6273.7 6292.2 4809.1 5026.3 5049.8 5071.5 5081.0 5025.2 5045.2 5055.2 5063.7 5077.9 4898.6 4968.8 5015.0 5017.6 5023.6 4973.4 5000.8 5013.3 5025.2 5032.6 4507.5 4519.5 4522.2 4530.4 4543.5 6061.8 6135.2 6175.3 6185.3 6253.9 5598.7 5626.2 5644.6 5648.0 5709.3 6513.7 6574.6 6606.6 6616.2 6639.4 4831.8 4874.3 4877.7 4890.5 4914.7 6608.3 6822.7 6844.2 6845.8 7030.6 4996.9 5009.8 5023.1 5035.2 5035.8 5090.9 5092.0 5118.2 5118.3 5122.7 5688.8 5690.2 5701.9 5732.9 5757.0 6671.0 6683.7 6701.3 6708.6 6751.7 1747.9 1761.4 1762.7 1765.0 1775.3 1169.8 1232.1 1232.8 1240.3 1242.9 5933.9 5940.6 5960.3 5968.4 5973.1 6808.7 6812.8 6835.4 6852.6 6878.9 5829.6 5898.5 5920.4 5934.2 5934.6 3614.9 3625.5 3635.6 3666.8 3676.7 6490.8 6508.6 6537.3 6555.3 6567.5 6600.1 6900.6 6909.2 6981.5 7048.0 6449.1 6501.7 6520.7 6533.6 6536.0 6724.4 6751.5 6755.9 6787.3 6889.0 6676.3 6698.3 6709.6 6736.3 6738.2 6396.2 6421.0 6432.7 6444.2 6450.5 4957.9 4960.6 4968.0 4999.2 5024.5 6505.3 6537.9 6557.0 6623.4 6766.9 6339.9 6525.7 6577.5 6586.8 6632.8 6525.9 6529.3 6581.8 6645.0 6645.2 6453.5 6474.0 6477.2 6560.5 6567.9 6426.2 6465.7 6519.8 6586.0 6611.5 6301.5 6306.5 6308.6 6388.0 6455.6 18:39:50 INFO - PID 3705 | 18:39:50 INFO - PID 3705 | [#5] yui.html Cycles:180 Average:22647.68 Median:29029.06 stddev:30828.63 (106.2%) stddev-sans-first:30871.74 18:39:50 INFO - PID 3705 | Values: 868.7 881.6 895.6 899.9 907.2 16436.4 17631.5 17641.7 17735.2 18186.0 17605.9 18513.8 18715.3 18743.5 18821.2 909.9 927.3 927.8 929.1 930.6 881.9 931.1 936.4 946.8 952.5 992.3 995.3 996.1 996.2 996.6 983.7 984.7 990.3 990.4 991.0 724.6 725.1 726.3 728.1 728.9 16842.0 17142.1 17155.7 17174.0 17278.1 20720.6 20781.6 21311.7 21358.4 21642.0 16831.7 17048.1 17304.9 17321.4 17334.7 3688.2 3730.1 3733.0 3759.3 3763.5 118649.0 119678.4 120602.4 121211.0 123051.4 15909.3 15981.8 16264.9 16277.4 16374.3 29019.1 29039.0 29118.6 29228.0 29290.9 8006.1 8094.9 8185.5 8231.8 8251.6 3729.8 4173.8 4203.5 4217.1 4274.9 4114.4 5336.6 5537.1 5544.1 5555.9 1032.3 1058.0 1073.6 1073.9 1075.0 663.9 680.8 686.1 691.7 695.0 11445.7 11985.6 12004.5 12216.4 12812.0 652.2 661.6 662.3 680.7 700.9 5319.0 5651.2 5750.1 5758.9 6139.0 105630.0 106414.5 110611.0 110795.0 113408.0 94191.5 95463.0 97166.0 97558.0 97819.0 81890.0 84123.0 85258.0 85356.0 87616.6 12562.4 12745.6 12799.2 12851.6 12970.6 39844.6 40001.6 40711.6 41702.4 43729.0 48982.0 49272.8 50129.0 50253.5 51360.0 16283.8 16648.5 16693.8 16759.9 16864.0 19966.9 20152.7 20224.4 20406.9 20414.2 18259.9 18549.3 18551.0 18672.9 18713.3 18201.5 18383.9 18396.7 18481.9 18500.3 17129.7 17279.0 17312.7 17408.0 17444.3 11914.0 12006.8 12104.9 12186.5 12235.6 12917.7 13118.5 13164.3 13220.6 13247.3 18:39:50 INFO - PID 3705 | -------- Summary: end -------- 18:39:50 INFO - PID 3705 | 18:39:50 INFO - TEST-INFO | 3705: exit 0 18:39:50 INFO - TEST-OK | dromaeo_css | took 1113262ms 18:39:50 INFO - TEST-START | kraken 18:39:50 INFO - Initialising browser for kraken test... 18:39:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41660/getInfo.html -profile /tmp/tmp8ewgH1/profile 18:39:50 INFO - TEST-INFO | started process 4126 (/builds/slave/test/build/application/firefox/firefox http://localhost:41660/getInfo.html) 18:39:56 INFO - TEST-INFO | 4126: exit 0 18:39:56 INFO - Browser initialized. 18:39:56 INFO - Running cycle 1/1 for kraken test... 18:39:56 INFO - Using env: {'DISPLAY': ':0', 18:39:56 INFO - 'HOME': '/home/cltbld', 18:39:56 INFO - 'JSGC_DISABLE_POISONING': '1', 18:39:56 INFO - 'LANG': 'en_US.UTF-8', 18:39:56 INFO - 'LANGUAGE': 'en_US:en', 18:39:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:39:56 INFO - 'LOGNAME': 'cltbld', 18:39:56 INFO - 'MAIL': '/var/mail/cltbld', 18:39:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:39:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:39:56 INFO - 'MOZ_CRASHREPORTER': '1', 18:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:39:56 INFO - 'MOZ_NO_REMOTE': '1', 18:39:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:39:56 INFO - 'NO_EM_RESTART': '1', 18:39:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:56 INFO - 'PWD': '/builds/slave/test', 18:39:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:39:56 INFO - 'RUST_BACKTRACE': 'full', 18:39:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:39:56 INFO - 'SHELL': '/bin/bash', 18:39:56 INFO - 'SHLVL': '1', 18:39:56 INFO - 'STYLO_FORCE_ENABLED': '1', 18:39:56 INFO - 'TERM': 'linux', 18:39:56 INFO - 'TMOUT': '86400', 18:39:56 INFO - 'USER': 'cltbld', 18:39:56 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292', 18:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:56 INFO - '_': '/tools/buildbot/bin/python'} 18:39:56 INFO - TEST-INFO | started process 4309 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8ewgH1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:39:57 INFO - PID 4309 | 18:39:57 INFO - PID 4309 | (/builds/slave/test/build/application/firefox/firefox:4358): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:39:57 INFO - PID 4309 | 18:39:57 INFO - PID 4309 | 18:39:57 INFO - PID 4309 | (/builds/slave/test/build/application/firefox/firefox:4424): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:39:57 INFO - PID 4309 | 18:39:57 INFO - PID 4309 | __metrics Screen width/height:1600/1200 18:39:57 INFO - PID 4309 | colorDepth:24 18:39:57 INFO - PID 4309 | Browser inner width/height: 1024/768 18:39:57 INFO - PID 4309 | __metrics 18:40:07 INFO - PID 4309 | RSS: Main: 180535296 18:40:07 INFO - PID 4309 | 18:40:10 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41660/tests/kraken/driver.html?testName=audio-beat-detection) 18:40:11 INFO - PID 4309 | RSS: Main: 184569856 18:40:11 INFO - PID 4309 | 18:40:13 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41660/tests/kraken/driver.html?testName=audio-dft) 18:40:14 INFO - PID 4309 | RSS: Main: 186884096 18:40:14 INFO - PID 4309 | 18:40:17 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41660/tests/kraken/driver.html?testName=audio-fft) 18:40:18 INFO - PID 4309 | RSS: Main: 179929088 18:40:18 INFO - PID 4309 | 18:40:20 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41660/tests/kraken/driver.html?testName=audio-oscillator) 18:40:20 INFO - PID 4309 | RSS: Main: 181002240 18:40:20 INFO - PID 4309 | 18:40:23 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41660/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:40:23 INFO - PID 4309 | RSS: Main: 181276672 18:40:23 INFO - PID 4309 | 18:40:26 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41660/tests/kraken/driver.html?testName=imaging-darkroom) 18:40:27 INFO - PID 4309 | RSS: Main: 182009856 18:40:27 INFO - PID 4309 | 18:40:31 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41660/tests/kraken/driver.html?testName=imaging-desaturate) 18:40:31 INFO - PID 4309 | RSS: Main: 181948416 18:40:31 INFO - PID 4309 | 18:40:34 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41660/tests/kraken/driver.html?testName=json-parse-financial) 18:40:35 INFO - PID 4309 | RSS: Main: 182177792 18:40:35 INFO - PID 4309 | 18:40:36 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41660/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:40:37 INFO - PID 4309 | RSS: Main: 182620160 18:40:37 INFO - PID 4309 | 18:40:38 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41660/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:40:39 INFO - PID 4309 | RSS: Main: 182444032 18:40:39 INFO - PID 4309 | 18:40:41 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41660/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:40:42 INFO - PID 4309 | RSS: Main: 182181888 18:40:42 INFO - PID 4309 | 18:40:45 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41660/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:40:45 INFO - PID 4309 | RSS: Main: 179511296 18:40:45 INFO - PID 4309 | 18:40:48 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41660/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:40:49 INFO - PID 4309 | RSS: Main: 179638272 18:40:49 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | Cycle 1(1): loaded http://localhost:41660/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41660/tests/kraken/driver.html?testName=ai-astar) 18:40:51 INFO - PID 4309 | RSS: Main: 180944896 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | __start_tp_report 18:40:51 INFO - PID 4309 | _x_x_mozilla_page_load 18:40:51 INFO - PID 4309 | _x_x_mozilla_page_load_details 18:40:51 INFO - PID 4309 | |i|pagename|runs| 18:40:51 INFO - PID 4309 | |0;ai-astar;101;107;100;108;108;112;108;97;110;109 18:40:51 INFO - PID 4309 | |1;audio-beat-detection;149;117;149;177;119;150;148;118;181;118 18:40:51 INFO - PID 4309 | |2;audio-dft;166;166;163;164;166;168;164;172;169;166 18:40:51 INFO - PID 4309 | |3;audio-fft;74;74;73;180;76;75;74;74;161;75 18:40:51 INFO - PID 4309 | |4;audio-oscillator;80;79;136;79;78;78;135;79;80;128 18:40:51 INFO - PID 4309 | |5;imaging-gaussian-blur;114;116;115;117;114;114;119;115;114;117 18:40:51 INFO - PID 4309 | |6;imaging-darkroom;148;149;148;149;150;148;150;151;148;148 18:40:51 INFO - PID 4309 | |7;imaging-desaturate;94;95;95;95;95;95;94;95;94;94 18:40:51 INFO - PID 4309 | |8;json-parse-financial;53;53;52;52;53;52;52;52;53;52 18:40:51 INFO - PID 4309 | |9;json-stringify-tinderbox;39;38;38;39;38;39;39;39;39;38 18:40:51 INFO - PID 4309 | |10;stanford-crypto-aes;77;76;77;75;78;77;73;74;74;77 18:40:51 INFO - PID 4309 | |11;stanford-crypto-ccm;128;130;132;118;125;119;126;125;118;158 18:40:51 INFO - PID 4309 | |12;stanford-crypto-pbkdf2;176;177;171;169;173;172;170;170;179;171 18:40:51 INFO - PID 4309 | |13;stanford-crypto-sha256-iterative;68;64;66;66;63;65;71;65;66;64 18:40:51 INFO - PID 4309 | __end_tp_report 18:40:51 INFO - PID 4309 | __start_cc_report 18:40:51 INFO - PID 4309 | _x_x_mozilla_cycle_collect,980 18:40:51 INFO - PID 4309 | __end_cc_report 18:40:51 INFO - PID 4309 | __startTimestamp1503884451440__endTimestamp 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | ------- Summary: start ------- 18:40:51 INFO - PID 4309 | Number of tests: 14 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#0] ai-astar Cycles:10 Average:106.00 Median:108.50 stddev:4.90 (4.5%) stddev-sans-first:4.85 18:40:51 INFO - PID 4309 | Values: 101.0 107.0 100.0 108.0 108.0 112.0 108.0 97.0 110.0 109.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:149.00 stddev:24.14 (16.2%) stddev-sans-first:25.50 18:40:51 INFO - PID 4309 | Values: 149.0 117.0 149.0 177.0 119.0 150.0 148.0 118.0 181.0 118.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#2] audio-dft Cycles:10 Average:166.40 Median:166.00 stddev:2.67 (1.6%) stddev-sans-first:2.83 18:40:51 INFO - PID 4309 | Values: 166.0 166.0 163.0 164.0 166.0 168.0 164.0 172.0 169.0 166.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#3] audio-fft Cycles:10 Average:93.60 Median:74.00 stddev:40.78 (55.1%) stddev-sans-first:42.64 18:40:51 INFO - PID 4309 | Values: 74.0 74.0 73.0 180.0 76.0 75.0 74.0 74.0 161.0 75.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:79.00 stddev:26.17 (33.1%) stddev-sans-first:27.18 18:40:51 INFO - PID 4309 | Values: 80.0 79.0 136.0 79.0 78.0 78.0 135.0 79.0 80.0 128.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#5] imaging-gaussian-blur Cycles:10 Average:115.50 Median:115.50 stddev:1.72 (1.5%) stddev-sans-first:1.73 18:40:51 INFO - PID 4309 | Values: 114.0 116.0 115.0 117.0 114.0 114.0 119.0 115.0 114.0 117.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:1.10 (0.7%) stddev-sans-first:1.12 18:40:51 INFO - PID 4309 | Values: 148.0 149.0 148.0 149.0 150.0 148.0 150.0 151.0 148.0 148.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 18:40:51 INFO - PID 4309 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 94.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.50 stddev:0.52 (1.0%) stddev-sans-first:0.50 18:40:51 INFO - PID 4309 | Values: 53.0 53.0 52.0 52.0 53.0 52.0 52.0 52.0 53.0 52.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:39.00 stddev:0.52 (1.3%) stddev-sans-first:0.53 18:40:51 INFO - PID 4309 | Values: 39.0 38.0 38.0 39.0 38.0 39.0 39.0 39.0 39.0 38.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:77.00 stddev:1.69 (2.2%) stddev-sans-first:1.73 18:40:51 INFO - PID 4309 | Values: 77.0 76.0 77.0 75.0 78.0 77.0 73.0 74.0 74.0 77.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:127.00 stddev:11.66 (9.2%) stddev-sans-first:12.36 18:40:51 INFO - PID 4309 | Values: 128.0 130.0 132.0 118.0 125.0 119.0 126.0 125.0 118.0 158.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:172.50 stddev:3.39 (2.0%) stddev-sans-first:3.40 18:40:51 INFO - PID 4309 | Values: 176.0 177.0 171.0 169.0 173.0 172.0 170.0 170.0 179.0 171.0 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:2.30 (3.5%) stddev-sans-first:2.30 18:40:51 INFO - PID 4309 | Values: 68.0 64.0 66.0 66.0 63.0 65.0 71.0 65.0 66.0 64.0 18:40:51 INFO - PID 4309 | -------- Summary: end -------- 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:40:51 INFO - PID 4309 | 18:40:51 INFO - PID 4309 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:40:52 INFO - TEST-INFO | 4309: exit 0 18:40:52 INFO - TEST-OK | kraken | took 61405ms 18:40:52 INFO - SUITE-END | took 1174s 18:40:52 INFO - Completed test suite (00:19:35) 18:40:52 INFO - javascript benchmark 18:40:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8569.550440644845, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3236.3203727217283, 3256.869725210992, 3266.8856590019345, 3292.2098696312883, 3330.8834190803327, 6295.653739044351, 6311.715972781224, 6385.786700526903, 6404.551681382305, 6425.036244563318, 6302.180716506848, 6324.177856878599, 6415.06981487684, 6432.839179020527, 6462.159919210496, 5692.800751973682, 5772.336181339137, 5818.389069147742, 5832.212651292064, 5915.73379197935, 5779.537636989052, 5814.709264536757, 5913.201717768089, 5924.229850119478, 5941.138534912435, 5804.680742559158, 5838.195197023, 5891.587588868781, 5922.200502932092, 5964.433378829004, 5806.117675163964, 5808.3030036395485, 5848.853778655525, 5895.941040589582, 5901.498372638331, 5779.682117483537, 5786.3056433228285, 5822.563307751893, 5833.912491312633, 5851.180834683148, 6268.059791031336, 6273.498120150422, 6328.873422531569, 6339.302676705559, 6398.616083035033, 6637.469002479838, 6665.866682666319, 6776.356246156607, 6838.213605435389, 6861.142357205349, 6803.727850885959, 6921.723131074751, 6941.861162776767, 6950.061741664861, 6960.860782784316, 6413.262474815363, 6466.515011374166, 6468.935310646881, 6471.0, 6482.092507049017, 6891.241963384024, 7019.333163349474, 7025.227225005246, 7122.0, 7153.0343403639845, 6704.66476676174, 6706.396424321834, 6710.228323742735, 6714.966425167843, 6731.2932142125355, 6708.932910670831, 6751.93248067513, 6773.93226067733, 6774.254831968479, 6784.830379240559, 6742.898856517156, 6743.494237932076, 6751.493637977172, 6754.662266886636, 6757.459403247728, 6755.392014718699, 6761.526693131434, 6763.661816909135, 6766.458683305324, 6767.492438067165, 5346.518813306821, 5352.598555108382, 5415.0, 5424.240606315119, 5431.429699881534, 4703.412073490814, 4703.45910220329, 4711.528847115261, 4744.264638980963, 4793.976030119827, 5976.910346344808, 5982.252218472691, 6073.96963015191, 6086.3000754913755, 6100.23747031621, 6713.227978782503, 6813.931860681429, 6824.249332573413, 6845.075914751545, 6921.6193109379465, 5987.161797348374, 6021.42796434338, 6021.42796434338, 6035.064564992434, 6049.697515124226, 5856.209411729447, 5909.8227053188475, 5937.079752638348, 5942.613730107616, 5952.8809423812145, 6502.187226596676, 6545.639989800511, 6574.408303252731, 6621.966890165519, 6633.336666333328, 6737.225219099866, 6833.145856767904, 6849.48628852848, 6917.481188910952, 6960.199577048705, 6546.9524876019605, 6550.6069635822005, 6555.180602424697, 6560.40956313934, 6560.803175904731, 6604.24051234095, 6758.256591774999, 6768.864622707617, 6784.05023296729, 6791.456683465212, 6808.353206445379, 6821.249662537018, 6836.5214434989075, 6928.030075789293, 6934.514583979073, 6549.443297319781, 6560.179977502812, 6643.60138391698, 6644.269130395749, 6650.800475985729, 6499.805005849832, 6516.57642253252, 6581.979793132072, 6640.07039014548, 6657.0, 6479.838004049937, 6520.413162815369, 6524.869502610016, 6533.020046992989, 6545.967270163619, 6526.216853977553, 6579.80260592183, 6626.171728533934, 6633.734650613921, 6677.232118306457, 6395.968020159869, 6441.259255185713, 6449.806505804833, 6454.548181627241, 6510.544261901621], "value": 6220.574211087702, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.1513280789558, 830.1117803949769, 853.6329378367302, 880.5289170293892, 882.5807741322868, 38061.0, 39178.21643567127, 39191.80404097979, 39235.21529569407, 39238.60761392385, 35327.4700879487, 35647.643523564824, 35679.2864142717, 35680.64319356806, 35754.64245357552, 6858.2798806125265, 6938.028675985366, 6943.270956549565, 6951.0, 6995.370416662499, 6705.932940670604, 6711.892537731293, 6716.83207919801, 6795.93204067958, 6838.829029274259, 7487.0, 7591.051118610174, 7596.430267729915, 7672.846543069135, 7687.577183254919, 7494.587797671126, 7543.358814500747, 7545.660445279976, 7566.356859666907, 7571.053618297713, 7308.415326773846, 7511.661975211074, 7568.924310756932, 7596.050493688289, 7596.924030759732, 36207.0, 36551.26897462062, 36589.08527286813, 36598.0, 36616.0, 3398.762086653935, 3444.6383129771266, 3447.172678557128, 3448.069021364243, 3450.189205536697, 29577.260568485744, 29605.70394296051, 29608.55587166194, 29714.40571188564, 29738.256543586365, 5004.399472063339, 5027.321311622957, 5041.0422019816115, 5044.041632089891, 5156.690598564098, 217887.0, 221208.0, 221478.9999999984, 222465.0, 223515.0, 45662.77168614202, 46512.3023154653, 46533.76733116313, 46568.0, 46761.53238467572, 47892.76053619779, 48019.75990120027, 48027.0, 48070.278945815844, 50098.49901500938, 15542.30059647296, 15833.208339582974, 15842.60393490149, 15854.0, 15857.920710396374, 12976.221426714428, 13001.804972925414, 13015.04774283865, 13019.934900325627, 13037.739245215233, 13727.931360343135, 13871.514496992557, 13939.09395889264, 14014.299285035708, 14064.296785160906, 5568.916466253009, 5590.329160500766, 5611.298587676541, 5618.606697531215, 5685.857853553694, 971.825071487125, 996.7508122969258, 1005.8893521712606, 1021.7598864266891, 1023.948802559869, 22108.778912210993, 22422.775772242396, 22493.0, 22502.0, 22541.323760287218, 1094.8412480190327, 1095.2005036323528, 1097.3909480238385, 1100.3617901617042, 1111.9888801112047, 11846.170768046153, 12010.339431331186, 12028.338441385808, 12047.638570842888, 12048.578299759467, 233090.0, 235426.0, 235439.0, 235954.0, 236545.0, 45521.089578208914, 48654.513454864995, 49621.75189124031, 52045.47954520482, 53519.464805352225, 1281.20565249544, 1398.0353556046296, 1409.6616811965055, 1411.2077661370004, 1421.1326207738455, 25821.0, 26524.469510610066, 26578.069767558816, 26784.866075669495, 27359.589606155525, 47548.0, 47697.0, 47772.2834157481, 47812.0, 47877.0, 207829.0, 209378.0, 209441.0, 210194.0, 211042.0, 34077.14807129842, 34144.829275854456, 34164.82917585396, 34465.82767086149, 34633.307333853685, 41006.79496602617, 42131.157376852905, 42255.366169506866, 42401.36397953972, 42402.363964541175, 30476.238094046937, 30597.23506912345, 30624.387512250076, 30786.230344241572, 30891.536626950165, 30156.0, 30309.242268943453, 30540.84729576338, 31141.8442907784, 31293.217669558442, 34489.137771555914, 34748.65251347555, 34791.0, 34845.0, 35244.29511409706, 29820.254493637833, 30261.0, 30407.54388684216, 30519.389612208077, 30678.84660576772, 22692.319230423112, 23068.653970190797, 23351.534644824686, 23531.647025294977, 23676.408089797205], "value": 16881.472068824816, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [540.7616558081993, 548.3858078951574, 552.5551930695789, 554.2988120028162, 561.2171021425112, 21376.35870923871, 22182.334529964126, 22187.77812221879, 22204.88897555512, 22542.21102261421, 25733.742662573368, 26270.868645656792, 26287.211383658472, 26295.605565916478, 26369.604455933128, 6448.097266382711, 6585.308542603029, 6607.20713514379, 6607.273199948002, 6620.238672552646, 6454.0, 6507.121538592301, 6597.175353080865, 6890.31096890312, 6930.099087118668, 6902.723891044352, 6909.654517274142, 6915.619640919747, 6921.273266307041, 6921.688524016431, 6903.896441553381, 6917.688704008307, 6927.238003819576, 6930.549514281581, 6935.514513984023, 6758.864822703567, 6774.762883299061, 6776.119104516381, 6780.762673306411, 6793.728250869959, 30202.697973020207, 31251.687483125334, 31403.528947065814, 31505.8424707875, 31926.840365798023, 2035.0, 2077.5948690005457, 2079.0332495389566, 2083.1146762625976, 2086.1029757204396, 20259.594808103757, 20693.482662933395, 21065.47336316589, 21068.26261080855, 21373.786262137335, 2527.2165628655175, 2543.2116044026225, 2576.1756238003845, 2580.1227582622, 2590.2617753940035, 131456.3427182858, 134453.0, 137348.0, 140219.99999999898, 140972.29513852365, 20512.79487205139, 21319.360419187447, 22408.0, 22476.0, 22507.662385064235, 42404.45573265552, 42605.36091958623, 42612.57387426148, 42622.0, 42703.78648106802, 11623.186376953701, 11735.647930562096, 11764.294142351315, 11774.529018839321, 11926.701832454084, 7554.66004029809, 9805.950970245103, 9820.459874706818, 9909.851352229722, 9945.701628951143, 9794.706158815246, 11287.153463490107, 11302.773944521228, 11389.715257118472, 11445.828312575319, 3736.7197460190596, 3977.602239776057, 4088.0, 4288.678349123828, 4303.859477238534, 932.5943214701626, 961.8172547216006, 964.0879717837746, 973.5181085362791, 978.3005151316843, 19697.606047878962, 19906.203751849764, 20001.09995050197, 20101.597968040558, 20349.491262718253, 986.6990567876867, 1027.091024443373, 1041.8645576075062, 1041.9687409377732, 1051.663467690347, 10866.348019118877, 10909.18181136418, 10983.505742241618, 11088.279261848116, 11140.554377824958, 556640.0, 570831.0, 581294.0, 584405.0, 589187.0, 123473.0, 124118.0, 124985.37507312588, 126684.0, 127002.0, 1201.4833621542646, 1214.332117335462, 1223.4555622747791, 1223.5839814463304, 1227.183922691418, 16908.492745217172, 17872.463826085237, 17921.641567168845, 21309.25417610376, 22633.321000370015, 59843.0, 59953.0, 60910.390896090474, 62460.06309905446, 63591.0, 71627.28372716207, 72942.0, 73428.63285683538, 73615.26384736298, 73735.0, 14269.571912842632, 14614.269286535844, 14684.118952862435, 14691.265436728334, 14694.0, 23503.17738879131, 24697.87651061733, 24790.628140578265, 24834.50330993406, 24862.875685621457, 19093.61812763765, 19159.233630654617, 19327.03364831781, 19338.51653708612, 19674.80325196787, 16869.578260543585, 16918.32326706967, 16924.74612880783, 17157.91421042887, 17682.381116660854, 19610.80389196147, 19716.01419928969, 19719.802801971797, 19924.70112948336, 19946.102425391182, 14438.422463101359, 14528.27358632043, 14532.782008270096, 14545.200013999332, 14659.853401465849, 18287.0, 18550.628987420445, 18630.254789808798, 19244.422667319443, 19283.90358048257], "value": 12939.61596945611, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.8829205371517, 1345.199606234291, 1351.49319005373, 1361.4758318047543, 1366.6446723851802, 14423.567292981214, 15030.323635436403, 15109.546713598582, 15147.92426037871, 15156.924215378935, 14841.628959276024, 15411.537653870373, 15424.305906234218, 15425.845741542582, 15471.22643867805, 2739.260399692082, 2813.5920291557713, 2814.268290244531, 2819.0978886756247, 2820.844853533055, 2638.511875303075, 2671.7461841125064, 2682.570788673813, 2769.709180536045, 2776.9305767355877, 2636.0773729194725, 2675.933101672454, 2684.8254863433913, 2685.1810197889636, 2685.5837345211526, 2571.4985577812336, 2606.609008648699, 2636.854972976495, 2636.934076648099, 2640.3267166872324, 2573.755493228159, 2603.401217719935, 2629.513539995107, 2631.5263252614523, 2632.0524611139977, 14366.0, 14657.853421465756, 14677.706445871023, 14696.706065878729, 14742.262886855722, 7743.806404839867, 7965.362770978308, 7975.0, 7982.401319901031, 8028.237317454832, 5254.343207099113, 5291.259223708684, 5411.675299482044, 5535.58483113768, 5587.972060139714, 1839.4389711138097, 1842.0789605197401, 1855.434092601756, 1857.1271502393854, 1887.7829049659329, 25213.62179567308, 25241.24276271715, 25500.23499295024, 25512.23463296104, 25535.233942981555, 6482.35176482348, 6483.157189565326, 6538.346165383424, 6618.404343609002, 6796.490263230277, 16361.672766544603, 16406.83593164077, 16432.178391080397, 16478.917605412134, 16541.090240036672, 7494.737684181027, 7633.427492938052, 7638.579878106758, 7642.082950045919, 7642.7325043623205, 5736.62711923732, 5800.0, 5822.243108395882, 6021.789237376671, 7025.086738723933, 4972.602191824645, 5072.188449848007, 5115.590752739772, 5128.948710512922, 5142.948570514247, 1395.665040390299, 1434.0496924183103, 1443.4731323067006, 1445.0968144909432, 1452.978205326921, 860.694453469022, 868.9739307820776, 880.9427387219811, 886.0, 889.3685483306924, 7392.593407362647, 7528.247175282428, 8029.197080291924, 8376.623051962677, 8402.873956890651, 968.675493709601, 1002.4837208837455, 1002.5789168549227, 1014.949252537385, 1016.9796604067877, 4503.414556107685, 4754.595859351994, 4772.427308722976, 4873.853784386474, 5000.924986125211, 20578.0, 21013.264535741175, 21415.678764818542, 21438.892805535874, 21451.570968580854, 19549.21803127859, 20554.897225514076, 20772.376828695164, 20806.791932080487, 20844.270450534157, 10765.0, 11051.226414150933, 11053.0, 11063.059639930696, 11065.612703555336, 7737.883931741141, 7912.208779122042, 8252.876206857021, 8395.370347223861, 8445.788855278668, 17479.563010924827, 17529.123543823014, 17560.473185804447, 17595.20821563007, 17841.286348546433, 18147.0, 18238.361657341928, 18297.0, 18303.816961830213, 18363.724544132117, 13539.458421663023, 13809.447622095406, 13829.792553111913, 13835.723285534434, 13843.515476958259, 13426.597202083953, 13524.256165910969, 13799.965002624642, 13836.861631383557, 13863.72272554523, 10894.836577451186, 10967.122630189726, 11052.392118433563, 11112.666620001413, 11235.606753763579, 10336.638217662347, 10350.482475876326, 10550.630727924487, 10553.261271710906, 10790.028897399272, 9293.210077143249, 9328.440293582407, 9361.344705870524, 9366.718998430058, 9436.103570160823, 6891.241963384124, 6995.055667484824, 7081.433485321265, 7089.680964356513, 7129.572225666477, 6611.206655201407, 6644.568103073285, 6715.3620406061345, 6718.865622687421, 6764.053032575542], "value": 6503.646965585871, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.38202819517915, 507.5356049214967, 509.05570167339584, 525.5847880174662, 527.2039220776626, 6260.123582698426, 6378.0, 6386.393292637198, 6396.488280937526, 6404.519661025419, 6213.2233470816145, 6236.688165591725, 6270.773875274269, 6273.7490500380045, 6292.182016337881, 4809.086273608021, 5026.321446604717, 5049.77276022578, 5071.467495912932, 5080.974595127019, 5025.246213068032, 5045.243213517965, 5055.191169412895, 5063.670861393997, 5077.873053173664, 4898.583620392271, 4968.764056174736, 5014.974925125369, 5017.573506251954, 5023.572996295319, 4973.4280557735965, 5000.82497112603, 5013.298138260647, 5025.1708468102715, 5032.572231360339, 4507.5267096955, 4519.525449827754, 4522.163399771053, 4530.433695788027, 4543.454785425737, 6061.757149854538, 6135.233095863017, 6175.2589689237575, 6185.319614842364, 6253.906191407133, 5598.748056337475, 5626.240457538221, 5644.57665675076, 5647.971760141214, 5709.343425506078, 6513.723883654208, 6574.605523668595, 6606.60360378374, 6616.172978377703, 6639.435647969928, 4831.816205029791, 4874.341963834872, 4877.707337559757, 4890.486498917634, 4914.70511769295, 6608.306127856602, 6822.727090916308, 6844.178698556156, 6845.828854278633, 7030.629027339671, 4996.850094497171, 5009.774560144802, 5023.14606516893, 5035.169197082514, 5035.77339019745, 5090.949090509048, 5091.974540127349, 5118.208626980601, 5118.3090282811345, 5122.692638441706, 5688.77244910199, 5690.2033715279895, 5701.885962280731, 5732.885342293131, 5756.971215143897, 6670.966645166744, 6683.696679147617, 6701.32986701326, 6708.563943343667, 6751.729930802713, 1747.938822141219, 1761.383515769496, 1762.7444020616938, 1764.9647007059787, 1775.343123044482, 1169.8069818480026, 1232.0944106082022, 1232.7657745028416, 1240.3364200152876, 1242.9192102513489, 5933.8550568198925, 5940.61386009908, 5960.284765828128, 5968.432998865101, 5973.104034394789, 6808.727650894008, 6812.795616131524, 6835.384815366641, 6852.623105729133, 6878.896816547755, 5829.562782791308, 5898.528117750569, 5920.437558431942, 5934.228550288522, 5934.554908381802, 3614.927701446009, 3625.510556074949, 3635.6182600826533, 3666.7983260920905, 3676.7058635309113, 6490.837729056811, 6508.60948343101, 6537.280899101095, 6555.278919318871, 6567.540272180901, 6600.0596904622735, 6900.55088431435, 6909.170899492093, 6981.546199497118, 7048.0, 6449.097126402214, 6501.674916254263, 6520.706568204348, 6533.640649764308, 6536.0, 6724.428423583854, 6751.49363797727, 6755.864882702417, 6787.253402125663, 6889.00109484132, 6676.265610782712, 6698.263191049078, 6709.597424154371, 6736.34959434922, 6738.225104112894, 6396.168498095404, 6421.0, 6432.742690292524, 6444.226692796707, 6450.548626558987, 4957.876053098701, 4960.603151747924, 4968.0, 4999.175136102648, 5024.472430394756, 6505.284418714033, 6537.901931471127, 6557.049227861838, 6623.437007854386, 6766.8646627068165, 6339.873202535831, 6525.738970441319, 6577.50668699859, 6586.80239592813, 6632.767853125118, 6525.934740652533, 6529.314421985813, 6581.769638062645, 6645.0, 6645.169353830771, 6453.45145662624, 6473.967630161819, 6477.222733272038, 6560.457353029311, 6567.934320656732, 6426.196725409324, 6465.709043092978, 6519.8044058678315, 6585.96707016481, 6611.537192396679, 6301.494431481513, 6306.495480361468, 6308.589941653778, 6387.968060159669, 6455.61266324022], "value": 5087.332421440213, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [868.7306934850201, 881.5636260051274, 895.5656506594305, 899.9370044096912, 907.2424525521192, 16436.424725134624, 17631.47105586833, 17641.735373969386, 17735.20191591378, 18186.0, 17605.91197044016, 18513.814861851377, 18715.3449629263, 18743.53141171468, 18821.153048112832, 909.9454032758056, 927.3369540778342, 927.8097989912086, 929.1080562659855, 930.6184464369576, 881.8544940084878, 931.1061381074177, 936.4147407870081, 946.7775072857872, 952.471378384996, 992.2855544008303, 995.348047029197, 996.0885789502596, 996.217968894418, 996.5615129343066, 983.7245571240064, 984.7045886234173, 990.2672022703184, 990.3859607043643, 990.990090099097, 724.5942272327461, 725.1081170160679, 726.259215600085, 728.0862517540505, 728.8688036153491, 16842.0, 17142.142892855307, 17155.742663860052, 17174.0, 17278.13609319529, 20720.58558828815, 20781.5843683127, 21311.68032479514, 21358.359249222394, 21642.0, 16831.663366732595, 17048.147592620444, 17304.91347543267, 17321.393751218646, 17334.653306933917, 3688.1517251032415, 3730.08612889841, 3732.9813350933073, 3759.3233218020746, 3763.473113764075, 118649.0, 119678.40160799227, 120602.39698801537, 121211.0, 123051.38474307572, 15909.284082216329, 15981.760273595906, 16264.942778719345, 16277.430289940032, 16374.263158157912, 29019.12942611725, 29039.0, 29118.56322155127, 29228.0, 29290.853545732134, 8006.079300880473, 8094.919050809534, 8185.508869467851, 8231.835363292821, 8251.628676709563, 3729.7948612826553, 4173.833046678099, 4203.516595591485, 4217.072244106292, 4274.935875961863, 4114.423980642712, 5336.599755018389, 5537.086380747212, 5544.140658197985, 5555.8888822223325, 1032.339302846179, 1057.9576816927238, 1073.6027669762238, 1073.88187299397, 1075.0485820049312, 663.8805015097232, 680.8059702984625, 686.0909295183901, 691.6749127909874, 694.979150625482, 11445.656630301104, 11985.580504682293, 12004.459799309052, 12216.389180540937, 12812.0, 652.1717418878093, 661.5964261800298, 662.2880403566186, 680.7004917836235, 700.929907009295, 5319.0, 5651.237082993825, 5750.108733146369, 5758.88482230353, 6138.987067133873, 105630.0, 106414.46792765986, 110611.0, 110795.0, 113408.0, 94191.52904235435, 95463.0, 97166.0, 97558.0, 97819.0, 81890.0, 84123.0, 85258.0, 85356.0, 87616.56191719128, 12562.434690439135, 12745.61763147107, 12799.232046077264, 12851.550195743104, 12970.610881673565, 39844.60155398409, 40001.59998399979, 40711.59288407078, 41702.374464382454, 43729.0, 48982.0, 49272.75363623159, 50129.0, 50253.497465024884, 51360.0, 16283.755743664091, 16648.500544983668, 16693.83306166923, 16759.91620041892, 16864.0, 19966.90016549908, 20152.697709534663, 20224.393268201977, 20406.89796551067, 20414.18343266253, 18259.908700456413, 18549.258029678662, 18551.0, 18672.90663546728, 18713.345032923782, 18201.544961376072, 18383.90808045951, 18396.724049139008, 18481.90759046196, 18500.259989600803, 17129.743053853952, 17279.0, 17312.740308895627, 17408.0, 17444.302227910743, 11914.0, 12006.819897701367, 12104.939475302566, 12186.451609677408, 12235.571754988532, 12917.741645166856, 13118.540851070167, 13164.275964822027, 13220.603381898558, 13247.271400073088], "value": 8808.714007378714, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1496.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 107.0, 100.0, 108.0, 108.0, 112.0, 108.0, 97.0, 110.0, 109.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 149.0, 177.0, 119.0, 150.0, 148.0, 118.0, 181.0, 118.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 163.0, 164.0, 166.0, 168.0, 164.0, 172.0, 169.0, 166.0], "value": 166.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 180.0, 76.0, 75.0, 74.0, 74.0, 161.0, 75.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 136.0, 79.0, 78.0, 78.0, 135.0, 79.0, 80.0, 128.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 115.0, 117.0, 114.0, 114.0, 119.0, 115.0, 114.0, 117.0], "value": 115.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 148.0, 149.0, 150.0, 148.0, 150.0, 151.0, 148.0, 148.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 52.0, 53.0, 52.0, 52.0, 52.0, 53.0, 52.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 38.0, 39.0, 38.0, 39.0, 39.0, 39.0, 39.0, 38.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 75.0, 78.0, 77.0, 73.0, 74.0, 74.0, 77.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 130.0, 132.0, 118.0, 125.0, 119.0, 126.0, 125.0, 118.0, 158.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 171.0, 169.0, 173.0, 172.0, 170.0, 170.0, 179.0, 171.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 66.0, 66.0, 63.0, 65.0, 71.0, 65.0, 66.0, 64.0], "value": 65.8, "unit": "score"}]}]} 18:40:52 INFO - Return code: 0 18:40:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:40:52 INFO - # TBPL SUCCESS # 18:40:52 INFO - Running post-action listener: _package_coverage_data 18:40:52 INFO - Running post-action listener: _resource_record_post_action 18:40:52 INFO - [mozharness: 2017-08-28 01:40:52.722047Z] Finished run-tests step (success) 18:40:52 INFO - Running post-run listener: _resource_record_post_run 18:40:52 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 5586944; Write bytes: 440061952; Read time: 1980; Write time: 395508 18:40:52 INFO - TinderboxPrint: CPU usage
13.2% 18:40:52 INFO - TinderboxPrint: I/O read bytes / time
5,586,944 / 1,980 18:40:52 INFO - TinderboxPrint: I/O write bytes / time
440,061,952 / 395,508 18:40:52 INFO - TinderboxPrint: CPU idle
8,257.4 (86.8%) 18:40:52 INFO - TinderboxPrint: CPU user
1,197.1 (12.6%) 18:40:52 INFO - TinderboxPrint: Swap in / out
0 / 0 18:40:52 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 114700288; Read time: 0; Write time: 87652 18:40:52 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:40:52 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 5586944; Write bytes: 325361664; Read time: 1980; Write time: 307856 18:40:53 INFO - Running post-run listener: _upload_blobber_files 18:40:53 INFO - Blob upload gear active. 18:40:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:40:53 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 18:40:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:40:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:40:53 INFO - (blobuploader) - INFO - Open directory for files ... 18:40:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:40:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:40:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:54 INFO - (blobuploader) - INFO - Done attempting. 18:40:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:40:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:40:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:40:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:40:55 INFO - (blobuploader) - INFO - Done attempting. 18:40:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:40:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:40:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:41:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:03 INFO - (blobuploader) - INFO - Done attempting. 18:41:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:41:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:41:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:04 INFO - (blobuploader) - INFO - Done attempting. 18:41:04 INFO - (blobuploader) - INFO - Iteration through files over. 18:41:04 INFO - Return code: 0 18:41:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:41:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:41:04 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be225369cdfc164c0423eab1a7e4544ae813e0038468514b5a9b68ec71967a2f117b8a6135ad7514f8f6b2b5330597b207e7da29739e0ae08442641704d99ac", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/422e28559a352ced4ebb1f2c946762bf7849f12ab00f645cf45494a37e2244f897bb3bd1801897d213201686087f78842f77546fb98f5cd8ae7784c0fd775292", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4645efefb6a375b941425427acef021028471652dbabab2effe76b40d26495ba95a004220338d65f5bd5739fae65a7ecec71f8be94c8ab54550eaa1b0e86b028", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e3be97c827ecbc278a3eaafb0bf6bda22c8f79434a43b5c3c608cc766154a3911877d22d4d6a96edfee249f3132491d1468175923ab952d095abba9c6523a8b"} 18:41:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:41:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:41:04 INFO - Contents: 18:41:04 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be225369cdfc164c0423eab1a7e4544ae813e0038468514b5a9b68ec71967a2f117b8a6135ad7514f8f6b2b5330597b207e7da29739e0ae08442641704d99ac", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/422e28559a352ced4ebb1f2c946762bf7849f12ab00f645cf45494a37e2244f897bb3bd1801897d213201686087f78842f77546fb98f5cd8ae7784c0fd775292", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4645efefb6a375b941425427acef021028471652dbabab2effe76b40d26495ba95a004220338d65f5bd5739fae65a7ecec71f8be94c8ab54550eaa1b0e86b028", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e3be97c827ecbc278a3eaafb0bf6bda22c8f79434a43b5c3c608cc766154a3911877d22d4d6a96edfee249f3132491d1468175923ab952d095abba9c6523a8b"} 18:41:04 INFO - Running post-run listener: copy_logs_to_upload_dir 18:41:04 INFO - Copying logs to upload dir... 18:41:04 INFO - mkdir: /builds/slave/test/build/upload/logs 18:41:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.079709 ========= master_lag: 0.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-08-27 18:41:04.312193) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 18:41:04.315367) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be225369cdfc164c0423eab1a7e4544ae813e0038468514b5a9b68ec71967a2f117b8a6135ad7514f8f6b2b5330597b207e7da29739e0ae08442641704d99ac", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/422e28559a352ced4ebb1f2c946762bf7849f12ab00f645cf45494a37e2244f897bb3bd1801897d213201686087f78842f77546fb98f5cd8ae7784c0fd775292", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4645efefb6a375b941425427acef021028471652dbabab2effe76b40d26495ba95a004220338d65f5bd5739fae65a7ecec71f8be94c8ab54550eaa1b0e86b028", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e3be97c827ecbc278a3eaafb0bf6bda22c8f79434a43b5c3c608cc766154a3911877d22d4d6a96edfee249f3132491d1468175923ab952d095abba9c6523a8b"} build_url:https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011202 build_url: 'https://queue.taskcluster.net/v1/task/FgF7GK1MTSiueBqY-_8-1Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0be225369cdfc164c0423eab1a7e4544ae813e0038468514b5a9b68ec71967a2f117b8a6135ad7514f8f6b2b5330597b207e7da29739e0ae08442641704d99ac", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/422e28559a352ced4ebb1f2c946762bf7849f12ab00f645cf45494a37e2244f897bb3bd1801897d213201686087f78842f77546fb98f5cd8ae7784c0fd775292", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4645efefb6a375b941425427acef021028471652dbabab2effe76b40d26495ba95a004220338d65f5bd5739fae65a7ecec71f8be94c8ab54550eaa1b0e86b028", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e3be97c827ecbc278a3eaafb0bf6bda22c8f79434a43b5c3c608cc766154a3911877d22d4d6a96edfee249f3132491d1468175923ab952d095abba9c6523a8b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 18:41:04.344994) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:41:04.345323) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1503883202.820159-140312292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004423 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:41:04.431073) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 18:41:04.431516) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 18:41:04.431899) ========= ========= Total master_lag: 0.34 =========