builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-007 starttime: 1506005646.25 results: success (0) revision: 06d4034a8a035cfefeec3b96164d5c66198666cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:06.248416) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:06.248852) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:06.249121) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:06.296169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:06.296453) ========= 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=923d835db2ba3686ebe126b700000192-1506005577.607800-164198643 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 07:54:06-- 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% 225M=0s 2017-09-21 07:54:06 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.216636 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:06.571114) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:06.571463) ========= 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=923d835db2ba3686ebe126b700000192-1506005577.607800-164198643 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016683 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:06.622162) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:06.622601) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 06d4034a8a035cfefeec3b96164d5c66198666cd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 06d4034a8a035cfefeec3b96164d5c66198666cd --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=923d835db2ba3686ebe126b700000192-1506005577.607800-164198643 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 07:54:06,676 truncating revision to first 12 chars 2017-09-21 07:54:06,676 Setting DEBUG logging. 2017-09-21 07:54:06,676 attempt 1/10 2017-09-21 07:54:06,677 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/06d4034a8a03?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-21 07:54:07,248 unpacking tar archive at: mozilla-beta-06d4034a8a03/testing/mozharness/ program finished with exit code 0 elapsedTime=0.869298 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:07.507691) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:07.507979) ========= script_repo_revision: 06d4034a8a035cfefeec3b96164d5c66198666cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:07.508350) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:07.508688) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 07:54:07.521974) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2017-09-21 07:54:07.522322) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=923d835db2ba3686ebe126b700000192-1506005577.607800-164198643 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:54:07 INFO - MultiFileLogger online at 20170921 07:54:07 in /builds/slave/test 07:54:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 07:54:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:54:07 INFO - {'append_to_log': False, 07:54:07 INFO - 'base_work_dir': '/builds/slave/test', 07:54:07 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 07:54:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:54:07 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 07:54:07 INFO - 'buildbot_json_path': 'buildprops.json', 07:54:07 INFO - 'code_coverage': False, 07:54:07 INFO - 'config_files': ('talos/linux_config.py',), 07:54:07 INFO - 'default_actions': ('clobber', 07:54:07 INFO - 'read-buildbot-config', 07:54:07 INFO - 'download-and-extract', 07:54:07 INFO - 'populate-webroot', 07:54:07 INFO - 'create-virtualenv', 07:54:07 INFO - 'install', 07:54:07 INFO - 'setup-mitmproxy', 07:54:07 INFO - 'run-tests'), 07:54:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:54:07 INFO - 'disable_ccov_upload': False, 07:54:07 INFO - 'disable_stylo': False, 07:54:07 INFO - 'download_minidump_stackwalk': True, 07:54:07 INFO - 'download_symbols': 'ondemand', 07:54:07 INFO - 'enable_stylo': False, 07:54:07 INFO - 'enable_webrender': False, 07:54:07 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 07:54:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:54:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:54:07 INFO - 'gecko_profile': False, 07:54:07 INFO - 'gecko_profile_interval': 0, 07:54:07 INFO - 'installer_path': 'installer.exe', 07:54:07 INFO - 'log_level': 'info', 07:54:07 INFO - 'log_name': 'talos', 07:54:07 INFO - 'log_to_console': True, 07:54:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:54:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:54:07 INFO - 'opt_config_files': (), 07:54:07 INFO - 'pip_index': False, 07:54:07 INFO - 'suite': 'dromaeojs-e10s', 07:54:07 INFO - 'system_bits': '32', 07:54:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:54:07 INFO - 'title': 'talos-linux64-ix-007', 07:54:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:54:07 INFO - 'use_talos_json': True, 07:54:07 INFO - 'verify': 'False', 07:54:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:54:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:54:07 INFO - 'work_dir': 'build'} 07:54:07 INFO - [mozharness: 2017-09-21 14:54:07.800639Z] Running clobber step. 07:54:07 INFO - Running pre-action listener: _resource_record_pre_action 07:54:07 INFO - Running main action method: clobber 07:54:07 INFO - rmtree: /builds/slave/test/build 07:54:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:54:08 INFO - Running post-action listener: _resource_record_post_action 07:54:08 INFO - [mozharness: 2017-09-21 14:54:08.740430Z] Finished clobber step (success) 07:54:08 INFO - [mozharness: 2017-09-21 14:54:08.740527Z] Running read-buildbot-config step. 07:54:08 INFO - Running pre-action listener: _resource_record_pre_action 07:54:08 INFO - Running main action method: read_buildbot_config 07:54:08 INFO - Using buildbot properties: 07:54:08 INFO - { 07:54:08 INFO - "project": "", 07:54:08 INFO - "product": "firefox", 07:54:08 INFO - "who": "ryanvm@gmail.com", 07:54:08 INFO - "installer_path": "public/build/target.tar.bz2", 07:54:08 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 07:54:08 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 07:54:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 07:54:08 INFO - "stage_platform": "linux64", 07:54:08 INFO - "basedir": "/builds/slave/test", 07:54:08 INFO - "buildnumber": 111, 07:54:08 INFO - "platform": "ubuntu64_hw", 07:54:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:54:08 INFO - "slavebuilddir": "test", 07:54:08 INFO - "taskId": "TxWjUp4eTgSWmkZPyVQm8Q", 07:54:08 INFO - "branch": "mozilla-beta", 07:54:08 INFO - "script_repo_revision": "production", 07:54:08 INFO - "revision": "06d4034a8a035cfefeec3b96164d5c66198666cd", 07:54:08 INFO - "slavename": "talos-linux64-ix-007", 07:54:08 INFO - "repo_path": "releases/mozilla-beta" 07:54:08 INFO - } 07:54:08 INFO - Finding installer, test and symbols from parent task. 07:54:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TxWjUp4eTgSWmkZPyVQm8Q'}, attempt #1 07:54:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TxWjUp4eTgSWmkZPyVQm8Q'}, attempt #1 07:54:09 INFO - Task dependencies: AWIRF7hySD62YZ9nvxMw9Q 07:54:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q'}, attempt #1 07:54:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.tar.bz2 07:54:10 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.test_packages.json 07:54:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.crashreporter-symbols.zip 07:54:10 INFO - Running post-action listener: _resource_record_post_action 07:54:10 INFO - [mozharness: 2017-09-21 14:54:10.183520Z] Finished read-buildbot-config step (success) 07:54:10 INFO - [mozharness: 2017-09-21 14:54:10.183879Z] Running download-and-extract step. 07:54:10 INFO - Running pre-action listener: _resource_record_pre_action 07:54:10 INFO - Running main action method: download_and_extract 07:54:10 INFO - mkdir: /builds/slave/test/build/tests 07:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:10 INFO - trying https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.test_packages.json 07:54:10 INFO - Downloading https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:54:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:54:10 INFO - Downloaded 989 bytes. 07:54:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:54:10 INFO - Using the following test package requirements: 07:54:10 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 07:54:10 INFO - u'common': [u'target.common.tests.zip'], 07:54:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:54:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:54:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:54:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:54:10 INFO - u'mozbase': [u'target.common.tests.zip'], 07:54:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:54:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:54:10 INFO - u'web-platform': [u'target.common.tests.zip', 07:54:10 INFO - u'target.web-platform.tests.tar.gz'], 07:54:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:54:10 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 07:54:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.common.tests.zip 07:54:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:54:10 INFO - Fetch https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.common.tests.zip into memory 07:54:12 INFO - Content-Length response header: 55179474 07:54:12 INFO - Bytes received: 55179474 07:54:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.talos.tests.zip 07:54:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:54:23 INFO - Fetch https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.talos.tests.zip into memory 07:54:25 INFO - Content-Length response header: 14509184 07:54:25 INFO - Bytes received: 14509184 07:54:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:25 INFO - trying https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.tar.bz2 07:54:25 INFO - Downloading https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:54:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:54:28 INFO - Downloaded 53380923 bytes. 07:54:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.tar.bz2 07:54:28 INFO - mkdir: /builds/slave/test/properties 07:54:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:54:28 INFO - Writing to file /builds/slave/test/properties/build_url 07:54:28 INFO - Contents: 07:54:28 INFO - build_url:https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.tar.bz2 07:54:28 INFO - Running post-action listener: _resource_record_post_action 07:54:28 INFO - Running post-action listener: find_tests_for_verification 07:54:28 INFO - Running post-action listener: set_extra_try_arguments 07:54:28 INFO - [mozharness: 2017-09-21 14:54:28.752830Z] Finished download-and-extract step (success) 07:54:28 INFO - [mozharness: 2017-09-21 14:54:28.753057Z] Running populate-webroot step. 07:54:28 INFO - Running pre-action listener: _resource_record_pre_action 07:54:28 INFO - Running main action method: populate_webroot 07:54:28 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:54:28 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['tresize', 07:54:28 INFO - 'tcanvasmark']}, 07:54:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:54:28 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['dromaeo_css', 07:54:28 INFO - 'kraken']}, 07:54:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:54:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:54:28 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:54:28 INFO - 'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:54:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:54:28 INFO - 'tests': ['damp', 'tps']}, 07:54:28 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:54:28 INFO - 'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['damp', 'tps']}, 07:54:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:54:28 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['dromaeo_dom']}, 07:54:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:54:28 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['basic_compositor_video', 07:54:28 INFO - 'glvideo']}, 07:54:28 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:54:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:54:28 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:54:28 INFO - 'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['ts_paint_webext', 07:54:28 INFO - 'tp5o_webext']}, 07:54:28 INFO - 'other-e10s': {'tests': ['a11yr', 07:54:28 INFO - 'ts_paint', 07:54:28 INFO - 'tpaint', 07:54:28 INFO - 'sessionrestore', 07:54:28 INFO - 'sessionrestore_many_windows', 07:54:28 INFO - 'sessionrestore_no_auto_restore', 07:54:28 INFO - 'tabpaint']}, 07:54:28 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['a11yr', 07:54:28 INFO - 'ts_paint', 07:54:28 INFO - 'tpaint', 07:54:28 INFO - 'sessionrestore', 07:54:28 INFO - 'sessionrestore_many_windows', 07:54:28 INFO - 'sessionrestore_no_auto_restore', 07:54:28 INFO - 'tabpaint']}, 07:54:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 07:54:28 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 07:54:28 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['perf_reftest_singletons']}, 07:54:28 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['bloom_basic']}, 07:54:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:54:28 INFO - 'tsvgr_opacity', 07:54:28 INFO - 'tart', 07:54:28 INFO - 'tscrollx', 07:54:28 INFO - 'tsvg_static']}, 07:54:28 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['tsvgx', 07:54:28 INFO - 'tsvgr_opacity', 07:54:28 INFO - 'tart', 07:54:28 INFO - 'tscrollx', 07:54:28 INFO - 'tsvg_static']}, 07:54:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:54:28 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:54:28 INFO - 'talos_options': ['--disable-stylo'], 07:54:28 INFO - 'tests': ['tp5o']}, 07:54:28 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:54:28 INFO - 'talos_options': ['--mitmproxy', 07:54:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:54:28 INFO - '--firstNonBlankPaint'], 07:54:28 INFO - 'tests': ['tp6_google', 07:54:28 INFO - 'tp6_youtube', 07:54:28 INFO - 'tp6_amazon', 07:54:28 INFO - 'tp6_facebook']}, 07:54:28 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:54:28 INFO - 'talos_options': ['--disable-stylo', 07:54:28 INFO - '--mitmproxy', 07:54:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:54:28 INFO - '--firstNonBlankPaint'], 07:54:28 INFO - 'tests': ['tp6_google', 07:54:28 INFO - 'tp6_youtube', 07:54:28 INFO - 'tp6_amazon', 07:54:28 INFO - 'tp6_facebook']}, 07:54:28 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:54:28 INFO - 'talos_options': ['--stylo-threads=1', 07:54:28 INFO - '--mitmproxy', 07:54:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:54:28 INFO - '--firstNonBlankPaint'], 07:54:28 INFO - 'tests': ['tp6_google', 07:54:28 INFO - 'tp6_youtube', 07:54:28 INFO - 'tp6_amazon', 07:54:28 INFO - 'tp6_facebook']}, 07:54:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:54:28 INFO - 'talos_options': ['--xperf_path', 07:54:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:54:28 INFO - 'tests': ['tp5n']}, 07:54:28 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:54:28 INFO - 'talos_options': ['--disable-stylo', 07:54:28 INFO - '--xperf_path', 07:54:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:54:28 INFO - 'tests': ['tp5n']}}} 07:54:28 INFO - Running post-action listener: _resource_record_post_action 07:54:28 INFO - [mozharness: 2017-09-21 14:54:28.772115Z] Finished populate-webroot step (success) 07:54:28 INFO - [mozharness: 2017-09-21 14:54:28.772203Z] Running create-virtualenv step. 07:54:28 INFO - Running pre-action listener: _resource_record_pre_action 07:54:28 INFO - Running main action method: create_virtualenv 07:54:28 INFO - Creating virtualenv /builds/slave/test/build/venv 07:54:28 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:54:28 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 07:54:28 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:54:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:54:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:54:29 INFO - Installing distribute.............................................................................................................................................................................................done. 07:54:29 INFO - Installing pip...............done. 07:54:29 INFO - Return code: 0 07:54:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:54:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:54:29 INFO - Reading from file tmpfile_stdout 07:54:29 INFO - Output received: 07:54:29 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 07:54:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:54:29 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')]} 07:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:29 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')]} 07:54:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:29 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2279a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, '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': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', '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 07:54:29 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 07:54:29 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 07:54:29 INFO - Using env: {'DISPLAY': ':0', 07:54:29 INFO - 'HOME': '/home/cltbld', 07:54:29 INFO - 'LANG': 'en_US.UTF-8', 07:54:29 INFO - 'LANGUAGE': 'en_US:en', 07:54:29 INFO - 'LOGNAME': 'cltbld', 07:54:29 INFO - 'MAIL': '/var/mail/cltbld', 07:54:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:29 INFO - 'MOZ_NO_REMOTE': '1', 07:54:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:54:29 INFO - 'NO_EM_RESTART': '1', 07:54:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:54:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:29 INFO - 'PWD': '/builds/slave/test', 07:54:29 INFO - 'SHELL': '/bin/bash', 07:54:29 INFO - 'SHLVL': '1', 07:54:29 INFO - 'TERM': 'linux', 07:54:29 INFO - 'TMOUT': '86400', 07:54:29 INFO - 'USER': 'cltbld', 07:54:29 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:54:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:29 INFO - '_': '/tools/buildbot/bin/python'} 07:54:31 INFO - Ignoring indexes: http://pypi.python.org/simple/ 07:54:31 INFO - Downloading/unpacking pip>=1.5 07:54:31 INFO - Running setup.py egg_info for package pip 07:54:31 INFO - warning: no files found matching 'pip/cacert.pem' 07:54:31 INFO - warning: no files found matching '*.html' under directory 'docs' 07:54:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:54:31 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:54:31 INFO - Installing collected packages: pip 07:54:31 INFO - Found existing installation: pip 1.1 07:54:31 INFO - Uninstalling pip: 07:54:31 INFO - Successfully uninstalled pip 07:54:31 INFO - Running setup.py install for pip 07:54:31 INFO - warning: no files found matching 'pip/cacert.pem' 07:54:31 INFO - warning: no files found matching '*.html' under directory 'docs' 07:54:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:54:31 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:54:31 INFO - Installing pip script to /builds/slave/test/build/venv/bin 07:54:31 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 07:54:31 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 07:54:31 INFO - Successfully installed pip 07:54:31 INFO - Cleaning up... 07:54:31 INFO - Return code: 0 07:54:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2279a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, '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': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', '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 07:54:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:54:31 INFO - Using env: {'DISPLAY': ':0', 07:54:31 INFO - 'HOME': '/home/cltbld', 07:54:31 INFO - 'LANG': 'en_US.UTF-8', 07:54:31 INFO - 'LANGUAGE': 'en_US:en', 07:54:31 INFO - 'LOGNAME': 'cltbld', 07:54:31 INFO - 'MAIL': '/var/mail/cltbld', 07:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:31 INFO - 'MOZ_NO_REMOTE': '1', 07:54:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:54:31 INFO - 'NO_EM_RESTART': '1', 07:54:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:31 INFO - 'PWD': '/builds/slave/test', 07:54:31 INFO - 'SHELL': '/bin/bash', 07:54:31 INFO - 'SHLVL': '1', 07:54:31 INFO - 'TERM': 'linux', 07:54:31 INFO - 'TMOUT': '86400', 07:54:31 INFO - 'USER': 'cltbld', 07:54:31 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:31 INFO - '_': '/tools/buildbot/bin/python'} 07:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:31 INFO - Downloading/unpacking psutil>=3.1.1 07:54:31 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 07:54:31 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 07:54:31 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 07:54:31 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 07:54:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:54:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:54:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:54:33 INFO - Installing collected packages: psutil 07:54:33 INFO - Running setup.py install for psutil 07:54:33 INFO - building 'psutil._psutil_linux' extension 07:54:33 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 07:54:34 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 07:54:34 INFO - building 'psutil._psutil_posix' extension 07:54:34 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 07:54:34 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 07:54:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:54:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:54:34 INFO - Successfully installed psutil 07:54:34 INFO - Cleaning up... 07:54:34 INFO - Return code: 0 07:54:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:54:34 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')]} 07:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:34 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')]} 07:54:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:34 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2279a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, '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': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', '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 07:54:34 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 07:54:34 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 07:54:34 INFO - Using env: {'DISPLAY': ':0', 07:54:34 INFO - 'HOME': '/home/cltbld', 07:54:34 INFO - 'LANG': 'en_US.UTF-8', 07:54:34 INFO - 'LANGUAGE': 'en_US:en', 07:54:34 INFO - 'LOGNAME': 'cltbld', 07:54:34 INFO - 'MAIL': '/var/mail/cltbld', 07:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:34 INFO - 'MOZ_NO_REMOTE': '1', 07:54:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:54:34 INFO - 'NO_EM_RESTART': '1', 07:54:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:34 INFO - 'PWD': '/builds/slave/test', 07:54:34 INFO - 'SHELL': '/bin/bash', 07:54:34 INFO - 'SHLVL': '1', 07:54:34 INFO - 'TERM': 'linux', 07:54:34 INFO - 'TMOUT': '86400', 07:54:34 INFO - 'USER': 'cltbld', 07:54:34 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:34 INFO - '_': '/tools/buildbot/bin/python'} 07:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:54:35 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 07:54:35 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 07:54:35 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 07:54:35 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 07:54:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:54:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:54:36 INFO - Installing collected packages: mozsystemmonitor 07:54:36 INFO - Running setup.py install for mozsystemmonitor 07:54:36 INFO - Successfully installed mozsystemmonitor 07:54:36 INFO - Cleaning up... 07:54:36 INFO - Return code: 0 07:54:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:36 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2279a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, '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': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', '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 07:54:36 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 07:54:36 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 07:54:36 INFO - Using env: {'DISPLAY': ':0', 07:54:36 INFO - 'HOME': '/home/cltbld', 07:54:36 INFO - 'LANG': 'en_US.UTF-8', 07:54:36 INFO - 'LANGUAGE': 'en_US:en', 07:54:36 INFO - 'LOGNAME': 'cltbld', 07:54:36 INFO - 'MAIL': '/var/mail/cltbld', 07:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:36 INFO - 'MOZ_NO_REMOTE': '1', 07:54:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:54:36 INFO - 'NO_EM_RESTART': '1', 07:54:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:36 INFO - 'PWD': '/builds/slave/test', 07:54:36 INFO - 'SHELL': '/bin/bash', 07:54:36 INFO - 'SHLVL': '1', 07:54:36 INFO - 'TERM': 'linux', 07:54:36 INFO - 'TMOUT': '86400', 07:54:36 INFO - 'USER': 'cltbld', 07:54:36 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:36 INFO - '_': '/tools/buildbot/bin/python'} 07:54:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:36 INFO - Downloading/unpacking jsonschema==2.5.1 07:54:36 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 07:54:36 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 07:54:36 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 07:54:36 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 07:54:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:54:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:37 INFO - Downloading functools32-3.2.3-2.tar.gz 07:54:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:54:37 INFO - warning: no files found matching '*.txt' 07:54:37 INFO - no previously-included directories found matching 'build' 07:54:37 INFO - no previously-included directories found matching 'dist' 07:54:37 INFO - no previously-included directories found matching '.git*' 07:54:37 INFO - Installing collected packages: jsonschema, functools32 07:54:38 INFO - Running setup.py install for functools32 07:54:38 INFO - warning: no files found matching '*.txt' 07:54:38 INFO - no previously-included directories found matching 'build' 07:54:38 INFO - no previously-included directories found matching 'dist' 07:54:38 INFO - no previously-included directories found matching '.git*' 07:54:38 INFO - Successfully installed jsonschema functools32 07:54:38 INFO - Cleaning up... 07:54:38 INFO - Return code: 0 07:54:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:54:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2279a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, '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': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', '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 07:54:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:54:38 INFO - Using env: {'DISPLAY': ':0', 07:54:38 INFO - 'HOME': '/home/cltbld', 07:54:38 INFO - 'LANG': 'en_US.UTF-8', 07:54:38 INFO - 'LANGUAGE': 'en_US:en', 07:54:38 INFO - 'LOGNAME': 'cltbld', 07:54:38 INFO - 'MAIL': '/var/mail/cltbld', 07:54:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:38 INFO - 'MOZ_NO_REMOTE': '1', 07:54:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:54:38 INFO - 'NO_EM_RESTART': '1', 07:54:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:54:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:38 INFO - 'PWD': '/builds/slave/test', 07:54:38 INFO - 'SHELL': '/bin/bash', 07:54:38 INFO - 'SHLVL': '1', 07:54:38 INFO - 'TERM': 'linux', 07:54:38 INFO - 'TMOUT': '86400', 07:54:38 INFO - 'USER': 'cltbld', 07:54:38 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:54:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:38 INFO - '_': '/tools/buildbot/bin/python'} 07:54:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:54:38 INFO - Cleaning up... 07:54:38 INFO - Return code: 0 07:54:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:54:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2279a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, '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': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', '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 07:54:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:54:38 INFO - Using env: {'DISPLAY': ':0', 07:54:38 INFO - 'HOME': '/home/cltbld', 07:54:38 INFO - 'LANG': 'en_US.UTF-8', 07:54:38 INFO - 'LANGUAGE': 'en_US:en', 07:54:38 INFO - 'LOGNAME': 'cltbld', 07:54:38 INFO - 'MAIL': '/var/mail/cltbld', 07:54:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:38 INFO - 'MOZ_NO_REMOTE': '1', 07:54:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:54:38 INFO - 'NO_EM_RESTART': '1', 07:54:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:54:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:38 INFO - 'PWD': '/builds/slave/test', 07:54:38 INFO - 'SHELL': '/bin/bash', 07:54:38 INFO - 'SHLVL': '1', 07:54:38 INFO - 'TERM': 'linux', 07:54:38 INFO - 'TMOUT': '86400', 07:54:38 INFO - 'USER': 'cltbld', 07:54:38 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:54:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:38 INFO - '_': '/tools/buildbot/bin/python'} 07:54:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:38 INFO - Downloading/unpacking blobuploader==1.2.4 07:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:39 INFO - Downloading blobuploader-1.2.4.tar.gz 07:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:54:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:54:39 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 07:54:39 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 07:54:39 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 07:54:39 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 07:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:54:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:40 INFO - Downloading docopt-0.6.1.tar.gz 07:54:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:54:40 INFO - Installing collected packages: blobuploader, requests, docopt 07:54:40 INFO - Running setup.py install for blobuploader 07:54:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:54:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:54:40 INFO - Running setup.py install for requests 07:54:40 INFO - Running setup.py install for docopt 07:54:40 INFO - Successfully installed blobuploader requests docopt 07:54:40 INFO - Cleaning up... 07:54:40 INFO - Return code: 0 07:54:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54: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')]} 07:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54: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')]} 07:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:40 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2279a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, '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': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', '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 07:54:40 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 07:54:40 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 07:54:40 INFO - Using env: {'DISPLAY': ':0', 07:54:40 INFO - 'HOME': '/home/cltbld', 07:54:40 INFO - 'LANG': 'en_US.UTF-8', 07:54:40 INFO - 'LANGUAGE': 'en_US:en', 07:54:40 INFO - 'LOGNAME': 'cltbld', 07:54:40 INFO - 'MAIL': '/var/mail/cltbld', 07:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:40 INFO - 'MOZ_NO_REMOTE': '1', 07:54:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:54:40 INFO - 'NO_EM_RESTART': '1', 07:54:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:40 INFO - 'PWD': '/builds/slave/test', 07:54:40 INFO - 'SHELL': '/bin/bash', 07:54:40 INFO - 'SHLVL': '1', 07:54:40 INFO - 'TERM': 'linux', 07:54:40 INFO - 'TMOUT': '86400', 07:54:40 INFO - 'USER': 'cltbld', 07:54:40 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:40 INFO - '_': '/tools/buildbot/bin/python'} 07:54:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:54:41 INFO - Running setup.py (path:/tmp/pip-GRtF4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:54:41 INFO - Running setup.py (path:/tmp/pip-H_YLp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:54:41 INFO - Running setup.py (path:/tmp/pip-Gb7GrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:54:41 INFO - Running setup.py (path:/tmp/pip-Y91aDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:54:41 INFO - Running setup.py (path:/tmp/pip-cz7y0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:54:41 INFO - Running setup.py (path:/tmp/pip-ywy6FR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:54:41 INFO - Running setup.py (path:/tmp/pip-_U2_hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:54:41 INFO - Running setup.py (path:/tmp/pip-CZe3CV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:54:41 INFO - Running setup.py (path:/tmp/pip-W6ZM1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:54:41 INFO - Running setup.py (path:/tmp/pip-t7wiJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:54:41 INFO - Running setup.py (path:/tmp/pip-uEuOF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:54:41 INFO - Running setup.py (path:/tmp/pip-5ism2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:54:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:54:41 INFO - Running setup.py (path:/tmp/pip-mXmKMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:54:42 INFO - Running setup.py (path:/tmp/pip-T8kG7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:42 INFO - Running setup.py (path:/tmp/pip-f8S07l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:54:42 INFO - Running setup.py (path:/tmp/pip-lT1ApO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:54:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:54:42 INFO - Running setup.py (path:/tmp/pip-Ww0oVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:54:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:54:42 INFO - Running setup.py install for manifestparser 07:54:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:54:42 INFO - Running setup.py install for mozcrash 07:54:42 INFO - Running setup.py install for mozdebug 07:54:42 INFO - Running setup.py install for mozdevice 07:54:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:54:42 INFO - Running setup.py install for mozfile 07:54:42 INFO - Running setup.py install for mozhttpd 07:54:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:54:42 INFO - Running setup.py install for mozinfo 07:54:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:54:42 INFO - Running setup.py install for mozInstall 07:54:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:54:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:54:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:54:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:54:43 INFO - Running setup.py install for mozleak 07:54:43 INFO - Running setup.py install for mozlog 07:54:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:54:43 INFO - Running setup.py install for moznetwork 07:54:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:54:43 INFO - Running setup.py install for mozprocess 07:54:43 INFO - Running setup.py install for mozprofile 07:54:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:54:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:54:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:54:43 INFO - Running setup.py install for mozrunner 07:54:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:54:43 INFO - Running setup.py install for mozscreenshot 07:54:43 INFO - Running setup.py install for moztest 07:54:43 INFO - Running setup.py install for mozversion 07:54:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:54:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:54:44 INFO - Cleaning up... 07:54:44 INFO - Return code: 0 07:54:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54: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')]} 07:54:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54: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')]} 07:54:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:44 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2279a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, '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': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', '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 07:54:44 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 07:54:44 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 07:54:44 INFO - Using env: {'DISPLAY': ':0', 07:54:44 INFO - 'HOME': '/home/cltbld', 07:54:44 INFO - 'LANG': 'en_US.UTF-8', 07:54:44 INFO - 'LANGUAGE': 'en_US:en', 07:54:44 INFO - 'LOGNAME': 'cltbld', 07:54:44 INFO - 'MAIL': '/var/mail/cltbld', 07:54:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:44 INFO - 'MOZ_NO_REMOTE': '1', 07:54:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:54:44 INFO - 'NO_EM_RESTART': '1', 07:54:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:54:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:44 INFO - 'PWD': '/builds/slave/test', 07:54:44 INFO - 'SHELL': '/bin/bash', 07:54:44 INFO - 'SHLVL': '1', 07:54:44 INFO - 'TERM': 'linux', 07:54:44 INFO - 'TMOUT': '86400', 07:54:44 INFO - 'USER': 'cltbld', 07:54:44 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:54:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:44 INFO - '_': '/tools/buildbot/bin/python'} 07:54:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:54:44 INFO - Running setup.py (path:/tmp/pip-BDIRz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:54:44 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)) 07:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:54:44 INFO - Running setup.py (path:/tmp/pip-qGYIpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:54:44 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)) 07:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:54:44 INFO - Running setup.py (path:/tmp/pip-APS5SK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:54:44 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)) 07:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:54:44 INFO - Running setup.py (path:/tmp/pip-JXhVKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:54:44 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)) 07:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:54:44 INFO - Running setup.py (path:/tmp/pip-QH1z25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:54:44 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)) 07:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:54:44 INFO - Running setup.py (path:/tmp/pip-HOog2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:54:44 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)) 07:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:54:44 INFO - Running setup.py (path:/tmp/pip-qw074c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:54:44 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)) 07:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:54:44 INFO - Running setup.py (path:/tmp/pip-Zflj0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:54:44 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)) 07:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:54:44 INFO - Running setup.py (path:/tmp/pip-LPxTH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:54:44 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)) 07:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:54:44 INFO - Running setup.py (path:/tmp/pip-utsvLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:54:44 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)) 07:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:54:44 INFO - Running setup.py (path:/tmp/pip-srT6Aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:54:45 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)) 07:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:54:45 INFO - Running setup.py (path:/tmp/pip-qEwkb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:54:45 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)) 07:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:54:45 INFO - Running setup.py (path:/tmp/pip-MLhW_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:54:45 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)) 07:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:54:45 INFO - Running setup.py (path:/tmp/pip-esZCZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:54:45 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)) 07:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:45 INFO - Running setup.py (path:/tmp/pip-CzC1sA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:45 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)) 07:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:54:45 INFO - Running setup.py (path:/tmp/pip-J1V4cD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:54:45 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)) 07:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:54:45 INFO - Running setup.py (path:/tmp/pip-qriNSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:54:45 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)) 07:54:45 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)) 07:54:45 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)) 07:54:45 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)) 07:54:45 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)) 07:54:45 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)) 07:54:45 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)) 07:54:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:54: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 07:54: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 07:54: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 07:54: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 07:54:46 INFO - HTTP error 403 while getting http://pypi.pvt.build.mozilla.org/pub/blessings-1.6.tar.gz (from http://pypi.pvt.build.mozilla.org/pub/) 07:54:46 INFO - Cleaning up... 07:54:46 INFO - Exception: 07:54:46 ERROR - Traceback (most recent call last): 07:54:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/basecommand.py", line 122, in main 07:54:46 INFO - status = self.run(options, args) 07:54:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/commands/install.py", line 278, in run 07:54:46 INFO - requirement_set.prepare_files(finder, force_root_egg_info=self.bundle, bundle=self.bundle) 07:54:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/req.py", line 1197, in prepare_files 07:54:46 INFO - do_download, 07:54:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/req.py", line 1375, in unpack_url 07:54:46 INFO - self.session, 07:54:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/download.py", line 547, in unpack_http_url 07:54:46 INFO - resp.raise_for_status() 07:54:46 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/_vendor/requests/models.py", line 773, in raise_for_status 07:54:46 INFO - raise HTTPError(http_error_msg, response=self) 07:54:46 INFO - HTTPError: 403 Client Error: Forbidden 07:54:46 INFO - Storing debug log for failure in /home/cltbld/.pip/pip.log 07:54:46 WARNING - Return code: 2 07:54:46 INFO - retry: Failed, sleeping 60 seconds before retrying 07:55:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2279a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, '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': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', '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 #2 07:55:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:55:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:55:46 INFO - Using env: {'DISPLAY': ':0', 07:55:46 INFO - 'HOME': '/home/cltbld', 07:55:46 INFO - 'LANG': 'en_US.UTF-8', 07:55:46 INFO - 'LANGUAGE': 'en_US:en', 07:55:46 INFO - 'LOGNAME': 'cltbld', 07:55:46 INFO - 'MAIL': '/var/mail/cltbld', 07:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:46 INFO - 'MOZ_NO_REMOTE': '1', 07:55:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:55:46 INFO - 'NO_EM_RESTART': '1', 07:55:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:46 INFO - 'PWD': '/builds/slave/test', 07:55:46 INFO - 'SHELL': '/bin/bash', 07:55:46 INFO - 'SHLVL': '1', 07:55:46 INFO - 'TERM': 'linux', 07:55:46 INFO - 'TMOUT': '86400', 07:55:46 INFO - 'USER': 'cltbld', 07:55:46 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:46 INFO - '_': '/tools/buildbot/bin/python'} 07:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:55:46 INFO - Running setup.py (path:/tmp/pip-B6U8mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:55:47 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)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:55:47 INFO - Running setup.py (path:/tmp/pip-vpRE7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:55:47 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)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:55:47 INFO - Running setup.py (path:/tmp/pip-fvYXu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:55:47 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)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:55:47 INFO - Running setup.py (path:/tmp/pip-OvkjjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:55:47 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)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:55:47 INFO - Running setup.py (path:/tmp/pip-gM3LEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:55:47 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)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:55:47 INFO - Running setup.py (path:/tmp/pip-A3o56p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:55:47 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)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:55:47 INFO - Running setup.py (path:/tmp/pip-Uwl4Cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:55:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:55:47 INFO - Running setup.py (path:/tmp/pip-3m5aVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:55:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:55:47 INFO - Running setup.py (path:/tmp/pip-L3WLdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:55:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:55:47 INFO - Running setup.py (path:/tmp/pip-hViuKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:55:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:55:47 INFO - Running setup.py (path:/tmp/pip-pmo5k6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:55:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:55:47 INFO - Running setup.py (path:/tmp/pip-pslnV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:55:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:55:47 INFO - Running setup.py (path:/tmp/pip-UsqBdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:55:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:55:47 INFO - Running setup.py (path:/tmp/pip-63Xs7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:55:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:55:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:55:47 INFO - Running setup.py (path:/tmp/pip-P_iwWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:55:48 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)) 07:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:55:48 INFO - Running setup.py (path:/tmp/pip-Ue8La6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:55:48 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)) 07:55:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:55:48 INFO - Running setup.py (path:/tmp/pip-Sar4x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:55:48 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)) 07:55:48 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)) 07:55:48 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)) 07:55:48 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)) 07:55:48 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)) 07:55:48 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)) 07:55:48 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)) 07:55:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:55:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:55:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:55:49 INFO - Downloading blessings-1.6.tar.gz 07:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:55:49 INFO - Installing collected packages: blessings 07:55:49 INFO - Running setup.py install for blessings 07:55:49 INFO - Successfully installed blessings 07:55:49 INFO - Cleaning up... 07:55:49 INFO - Return code: 0 07:55:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:55:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:55:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:55:49 INFO - Reading from file tmpfile_stdout 07:55:49 INFO - Current package versions: 07:55:49 INFO - argparse == 1.2.1 07:55:49 INFO - blessings == 1.6 07:55:49 INFO - blobuploader == 1.2.4 07:55:49 INFO - docopt == 0.6.1 07:55:49 INFO - functools32 == 3.2.3-2 07:55:49 INFO - jsonschema == 2.5.1 07:55:49 INFO - manifestparser == 1.1 07:55:49 INFO - mozInstall == 1.13 07:55:49 INFO - mozcrash == 1.0 07:55:49 INFO - mozdebug == 0.1 07:55:49 INFO - mozdevice == 0.51 07:55:49 INFO - mozfile == 1.2 07:55:49 INFO - mozhttpd == 0.7 07:55:49 INFO - mozinfo == 0.10 07:55:49 INFO - mozleak == 0.1 07:55:49 INFO - mozlog == 3.5 07:55:49 INFO - moznetwork == 0.27 07:55:49 INFO - mozprocess == 0.25 07:55:49 INFO - mozprofile == 0.28 07:55:49 INFO - mozrunner == 6.13 07:55:49 INFO - mozscreenshot == 0.1 07:55:49 INFO - mozsystemmonitor == 0.3 07:55:49 INFO - moztest == 0.8 07:55:49 INFO - mozversion == 1.4 07:55:49 INFO - psutil == 3.1.1 07:55:49 INFO - requests == 1.2.3 07:55:49 INFO - wsgiref == 0.1.2 07:55:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:55:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:55:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2279a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, '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': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', '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 07:55:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:55:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:55:49 INFO - Using env: {'DISPLAY': ':0', 07:55:49 INFO - 'HOME': '/home/cltbld', 07:55:49 INFO - 'LANG': 'en_US.UTF-8', 07:55:49 INFO - 'LANGUAGE': 'en_US:en', 07:55:49 INFO - 'LOGNAME': 'cltbld', 07:55:49 INFO - 'MAIL': '/var/mail/cltbld', 07:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:49 INFO - 'MOZ_NO_REMOTE': '1', 07:55:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:55:49 INFO - 'NO_EM_RESTART': '1', 07:55:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:49 INFO - 'PWD': '/builds/slave/test', 07:55:49 INFO - 'SHELL': '/bin/bash', 07:55:49 INFO - 'SHLVL': '1', 07:55:49 INFO - 'TERM': 'linux', 07:55:49 INFO - 'TMOUT': '86400', 07:55:49 INFO - 'USER': 'cltbld', 07:55:49 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:49 INFO - '_': '/tools/buildbot/bin/python'} 07:55:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:55:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:55:49 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:55: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 07:55: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 07:55: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 07:55: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 07:55:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 07:55:51 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)) 07:55:51 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)) 07:55:51 INFO - Installing collected packages: simplejson 07:55:51 INFO - Running setup.py install for simplejson 07:55:51 INFO - building 'simplejson._speedups' extension 07:55:51 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 07:55:52 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 07:55:52 INFO - Successfully installed simplejson 07:55:52 INFO - Cleaning up... 07:55:52 INFO - Return code: 0 07:55:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:55:52 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')]} 07:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:55:52 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')]} 07:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:55:52 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2279a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x241ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, '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': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', '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 07:55:52 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 07:55:52 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 07:55:52 INFO - Using env: {'DISPLAY': ':0', 07:55:52 INFO - 'HOME': '/home/cltbld', 07:55:52 INFO - 'LANG': 'en_US.UTF-8', 07:55:52 INFO - 'LANGUAGE': 'en_US:en', 07:55:52 INFO - 'LOGNAME': 'cltbld', 07:55:52 INFO - 'MAIL': '/var/mail/cltbld', 07:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:52 INFO - 'MOZ_NO_REMOTE': '1', 07:55:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:55:52 INFO - 'NO_EM_RESTART': '1', 07:55:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:52 INFO - 'PWD': '/builds/slave/test', 07:55:52 INFO - 'SHELL': '/bin/bash', 07:55:52 INFO - 'SHLVL': '1', 07:55:52 INFO - 'TERM': 'linux', 07:55:52 INFO - 'TMOUT': '86400', 07:55:52 INFO - 'USER': 'cltbld', 07:55:52 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:52 INFO - '_': '/tools/buildbot/bin/python'} 07:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:55:52 INFO - Cleaning up... 07:55:52 INFO - Return code: 0 07:55:52 INFO - Running post-action listener: _resource_record_post_action 07:55:52 INFO - Running post-action listener: _start_resource_monitoring 07:55:52 INFO - Starting resource monitoring. 07:55:52 INFO - [mozharness: 2017-09-21 14:55:52.497643Z] Finished create-virtualenv step (success) 07:55:52 INFO - [mozharness: 2017-09-21 14:55:52.498640Z] Running install step. 07:55:52 INFO - Running pre-action listener: _resource_record_pre_action 07:55:52 INFO - Running main action method: install 07:55:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:55:52 INFO - Reading from file tmpfile_stdout 07:55:52 INFO - Detecting whether we're running mozinstall >=1.0... 07:55:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:55:52 INFO - Reading from file tmpfile_stdout 07:55:52 INFO - Output received: 07:55:52 INFO - Usage: mozinstall [options] installer 07:55:52 INFO - Options: 07:55:52 INFO - -h, --help show this help message and exit 07:55:52 INFO - -d DEST, --destination=DEST 07:55:52 INFO - Directory to install application into. [default: 07:55:52 INFO - "/builds/slave/test"] 07:55:52 INFO - --app=APP Application being installed. [default: firefox] 07:55:52 INFO - mkdir: /builds/slave/test/build/application 07:55:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:56:07 INFO - Reading from file tmpfile_stdout 07:56:07 INFO - Output received: 07:56:07 INFO - /builds/slave/test/build/application/firefox/firefox 07:56:07 INFO - Running post-action listener: _resource_record_post_action 07:56:07 INFO - [mozharness: 2017-09-21 14:56:07.415335Z] Finished install step (success) 07:56:07 INFO - [mozharness: 2017-09-21 14:56:07.415584Z] Running setup-mitmproxy step. 07:56:07 INFO - Running pre-action listener: _resource_record_pre_action 07:56:07 INFO - Running main action method: setup_mitmproxy 07:56:07 INFO - Skipping: mitmproxy is not required 07:56:07 INFO - Running post-action listener: _resource_record_post_action 07:56:07 INFO - [mozharness: 2017-09-21 14:56:07.416771Z] Finished setup-mitmproxy step (success) 07:56:07 INFO - [mozharness: 2017-09-21 14:56:07.416990Z] Running run-tests step. 07:56:07 INFO - Running pre-action listener: _resource_record_pre_action 07:56:07 INFO - Running pre-action listener: _set_gcov_prefix 07:56:07 INFO - Running main action method: run_tests 07:56:07 WARNING - Try message not found. 07:56:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:56:07 INFO - Python 2.7.3 07:56:07 INFO - Return code: 0 07:56:07 INFO - grabbing minidump binary from tooltool 07:56: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')]} 07:56:07 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 0x2420090>, '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 0x2420d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x241af90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:56:07 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 07:56:07 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 07:56:07 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 07:56:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:56:07 INFO - Return code: 0 07:56:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:56:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:56:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:56:07 INFO - ENV: RUST_BACKTRACE is now full 07:56:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:56:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:56:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 07:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 07:56:07 INFO - Using env: {'DISPLAY': ':0', 07:56:07 INFO - 'HOME': '/home/cltbld', 07:56:07 INFO - 'LANG': 'en_US.UTF-8', 07:56:07 INFO - 'LANGUAGE': 'en_US:en', 07:56:07 INFO - 'LOGNAME': 'cltbld', 07:56:07 INFO - 'MAIL': '/var/mail/cltbld', 07:56:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:56:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:07 INFO - 'MOZ_NO_REMOTE': '1', 07:56:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:56:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:56:07 INFO - 'NO_EM_RESTART': '1', 07:56:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:07 INFO - 'PWD': '/builds/slave/test', 07:56:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:56:07 INFO - 'RUST_BACKTRACE': 'full', 07:56:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:56:07 INFO - 'SHELL': '/bin/bash', 07:56:07 INFO - 'SHLVL': '1', 07:56:07 INFO - 'TERM': 'linux', 07:56:07 INFO - 'TMOUT': '86400', 07:56:07 INFO - 'USER': 'cltbld', 07:56:07 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:07 INFO - '_': '/tools/buildbot/bin/python'} 07:56:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 07:56:08 INFO - mozversion application_buildid: 20170921133955 07:56:08 INFO - mozversion application_changeset: 06d4034a8a035cfefeec3b96164d5c66198666cd 07:56:08 INFO - mozversion application_display_name: Firefox 07:56:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:56:08 INFO - mozversion application_name: Firefox 07:56:08 INFO - mozversion application_remotingname: firefox 07:56:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:56:08 INFO - mozversion application_vendor: Mozilla 07:56:08 INFO - mozversion application_version: 57.0 07:56:08 INFO - mozversion platform_buildid: 20170921133955 07:56:08 INFO - mozversion platform_changeset: 06d4034a8a035cfefeec3b96164d5c66198666cd 07:56:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:56:08 INFO - mozversion platform_version: 57.0 07:56:08 INFO - using testdate: 1506005768 07:56:08 INFO - actual date: 1506005768 07:56:08 INFO - starting webserver on 'localhost:46297' 07:56:08 INFO - SUITE-START | Running 2 tests 07:56:08 INFO - TEST-START | dromaeo_css 07:56:08 INFO - Initialising browser for dromaeo_css test... 07:56:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46297/getInfo.html -profile /tmp/tmpiX5oQb/profile 07:56:08 INFO - TEST-INFO | started process 3577 (/builds/slave/test/build/application/firefox/firefox http://localhost:46297/getInfo.html) 07:56:14 INFO - TEST-INFO | 3577: exit 0 07:56:14 INFO - Browser initialized. 07:56:14 INFO - Running cycle 1/1 for dromaeo_css test... 07:56:14 INFO - Using env: {'DISPLAY': ':0', 07:56:14 INFO - 'HOME': '/home/cltbld', 07:56:14 INFO - 'JSGC_DISABLE_POISONING': '1', 07:56:14 INFO - 'LANG': 'en_US.UTF-8', 07:56:14 INFO - 'LANGUAGE': 'en_US:en', 07:56:14 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:56:14 INFO - 'LOGNAME': 'cltbld', 07:56:14 INFO - 'MAIL': '/var/mail/cltbld', 07:56:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:56:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:56:14 INFO - 'MOZ_CRASHREPORTER': '1', 07:56:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:14 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:56:14 INFO - 'MOZ_NO_REMOTE': '1', 07:56:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:56:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:56:14 INFO - 'NO_EM_RESTART': '1', 07:56:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:56:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:14 INFO - 'PWD': '/builds/slave/test', 07:56:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:56:14 INFO - 'RUST_BACKTRACE': 'full', 07:56:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:56:14 INFO - 'SHELL': '/bin/bash', 07:56:14 INFO - 'SHLVL': '1', 07:56:14 INFO - 'TERM': 'linux', 07:56:14 INFO - 'TMOUT': '86400', 07:56:14 INFO - 'USER': 'cltbld', 07:56:14 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 07:56:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:14 INFO - '_': '/tools/buildbot/bin/python'} 07:56:14 INFO - TEST-INFO | started process 3766 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiX5oQb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:56:25 INFO - PID 3766 | RSS: Main: 144142336 07:56:25 INFO - PID 3766 | 07:59:22 INFO - PID 3766 | Cycle 1(1): loaded http://localhost:46297/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46297/tests/dromaeo/cssquery-ext.html) 07:59:23 INFO - PID 3766 | RSS: Main: 147492864 07:59:23 INFO - PID 3766 | 08:02:25 INFO - PID 3766 | Cycle 1(1): loaded http://localhost:46297/tests/dromaeo/cssquery-ext.html (next: http://localhost:46297/tests/dromaeo/cssquery-jquery.html) 08:02:26 INFO - PID 3766 | RSS: Main: 146845696 08:02:26 INFO - PID 3766 | 08:05:27 INFO - PID 3766 | Cycle 1(1): loaded http://localhost:46297/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46297/tests/dromaeo/cssquery-mootools.html) 08:05:28 INFO - PID 3766 | RSS: Main: 147226624 08:05:28 INFO - PID 3766 | 08:08:29 INFO - PID 3766 | Cycle 1(1): loaded http://localhost:46297/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46297/tests/dromaeo/cssquery-prototype.html) 08:08:30 INFO - PID 3766 | RSS: Main: 147075072 08:08:30 INFO - PID 3766 | 08:11:33 INFO - PID 3766 | Cycle 1(1): loaded http://localhost:46297/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46297/tests/dromaeo/cssquery-yui.html) 08:11:33 INFO - PID 3766 | RSS: Main: 148430848 08:11:33 INFO - PID 3766 | 08:14:35 INFO - PID 3766 | Cycle 1(1): loaded http://localhost:46297/tests/dromaeo/cssquery-yui.html (next: http://localhost:46297/tests/dromaeo/cssquery-dojo.html) 08:14:35 INFO - PID 3766 | RSS: Main: 151552000 08:14:35 INFO - PID 3766 | 08:14:35 INFO - PID 3766 | __start_tp_report 08:14:35 INFO - PID 3766 | _x_x_mozilla_page_load 08:14:35 INFO - PID 3766 | _x_x_mozilla_page_load_details 08:14:35 INFO - PID 3766 | |i|pagename|runs| 08:14:35 INFO - PID 3766 | |0;dojo.html;4018.2566225248333;4043.353063509838;4057.431959525666;4071.0840060986284;4092.7339722918027;6563.343665633434;6565.080888675583;6635.369639884209;6668.333166683336;6688.732450701966;6585.736570537185;6652.700628471718;6668.199816022072;6683.398494135527;6716.832079198022;5927.347991720929;5937.910931336012;6014.097885317205;6025.3372129065765;6059.454649081582;5929.407059294079;5996.460318571328;5999.730012149442;6090.634561926298;6098.054801505774;6059.212302400704;6097.878042439149;6102.72537735803;6121.602095863755;6142.84642883927;6026.849328766794;6048.032314829607;6059.091136329542;6106.48094911933;6132.018876979707;5881.9705901470215;5910.734016969247;5984.521238300926;6014.699265036775;6022.277326720821;6576.243731970789;6577.342265773432;6583.835404114888;6602.537822352438;6639.834004149935;7214.783556493314;7280.781576552659;7313.634318284117;7315.597642129681;7341.045664063638;7248.963755181243;7251.383632391253;7266.091738532684;7266.091738532684;7354.043974283362;6557.213134423852;6683.030960510748;6692.196936367618;6764.019217213477;6764.289749576273;7354.374878135419;7403.22266162056;7403.333699967029;7449.478536502501;7450.739224127129;7071.504994650325;7077.186123595749;7077.681504332214;7150.713971441084;7157.534760240672;7137.96431017852;7196.424286057103;7242.058532390755;7282.053333066773;7291.052163218854;7107.680154393065;7125.501214914906;7171.462140339496;7188.676509557083;7201.171865235565;7158;7179.605121718356;7240.420766338681;7271.200167981518;7292.161401438902;5315.946840531623;5333.386660534011;5406.405295417501;5415.025295446857;5415.945840541544;4823.445303790038;4836.371271734652;4856.757162141878;4866.270059491105;4867.975660121677;6671.63306018164;6713.160854893138;6765.221999470025;6783.491238157158;6823.38589526935;7093.716251349992;7115.686059165951;7204.53170543913;7260.818479537948;7305.305995930378;6700.899486507707;6758;6833.658317084126;6840;6851.040854280404;6028.879422411527;6058.091286307089;6127.785527506516;6130.233720784902;6132.7546898124465;7478.252174782587;7482.027336446227;7486.513376630502;7581.090269167625;7595.658195381221;7065.187503437171;7204.3155900189395;7222.3860971817985;7232.0598322217775;7237.3486386225495;7363.595002274712;7436.553806771611;7447.478676492594;7451.850962980601;7465.813354666177;7112.50212485121;7115.288471152843;7196.10048743907;7201.459890508128;7217.314355136254;7253.601051941982;7279.308465695751;7295.197528271992;7361.595112268877;7380.298871607189;6880;7008.123984501937;7033.648317584202;7098.219195888345;7108.609026503592;6968;6988.6505674715045;7000;7000.404965577983;7003.684834182576;6905.827354316182;6949;7000.50996430245;7007.754728584475;7007.964960175166;7046.718131274692;7068.222495525591;7070.823229419306;7147.714091436493;7174.892376614459;6764.255931847394;6826.761063362758;6833.077534532775;6926.896096558657;6934 08:14:35 INFO - PID 3766 | |1;ext.html;887.767091692415;894.6209437856852;900.2033200617453;924.3437159616672;934.4066517761224;46888.76555617221;47367.28949065767;47588.52411475884;47612.52387476128;47654.0469190616;47684.523154768445;48786.51213487864;48793;48833.26750098742;48888.2666759998;8885.777855553599;8940.06129356418;8970.775730606754;9000.27997760181;9007.099290071006;8851.114888511129;8943.910560894405;8946.23956963659;9002.54987250638;9005.414648047888;9017.909820901805;9023.278137748965;9066.274698024175;9070.229030532413;9076.36465447419;8859;8954.462732236087;8959.507227102507;8989.955050224773;8999.550022498848;8880.955595222047;8918.464892106495;8951.731448056504;8961.686340978034;8961.924569051691;42560.14879702389;43128.353074703904;43129.13741725179;43190.35214471786;43227.35158972618;3880.825362858678;3925.1953349563555;3925.60743925608;3929.214157168578;3929.76421414716;35644.64355356465;35735;35758.46362304541;35770.56917723314;35859.64140358589;5672.149177623348;5764.8847023059725;5796.855078623026;5810.128480727883;5810.8256752297075;220274;225384.99999999837;226141;231307;234716.82641586853;56147.71926140343;56337.154942675894;56448.43551564432;56478;56493.43506564964;58127;58152.418475815546;58162;58170.41829581735;58206.417935820944;15859.841401586067;15991.920040399724;16025.439109631106;16090.356385744673;16145.677086458205;12905.677358065936;12918.095733298767;12925.289109098901;12933.935330323477;12940.417681204368;14745.483908063381;15043.849561504245;15059.247037648074;15064.171470569005;15271.236438178046;5475.016116216671;5492.945070549244;5497.340319161726;5583.497485226349;5637.633553819071;1120.2102517724984;1127.0194930410596;1128.328644456547;1128.7968165730083;1131.6095946898306;26273.73726262751;26317.73682263153;26376.60435093475;26947.326316842235;26974.460510789675;1228.969275768095;1235.425527129889;1236.394166858251;1236.7526494701096;1240.3612139748036;13915.678010588977;14123.71752564943;14192.219427931357;14229.78655320171;14265.21541315217;293675;294406;294723;303048;308112;64029.67985160137;64431.67784161049;64439;64452;64489;1529.793477880494;1538.7691846222933;1539.8075240594926;1560.9531714048596;1564.9921750391175;30492.390152197277;30543.54184687276;30638.540421894137;30668;30770.692293076783;43558;43663.56336436595;43715.78142109269;43754.34368484539;43806.34290485709;250178;250725;250961;251920;252524;38261.808690956364;38631.80684096562;38634.42048369333;38646.2270754589;38743.41884871673;47856.282155768386;49490;49555.50444495509;50083;50126.49873501364;36246.818765906;36270.63729362673;36408.27183456369;36732.81633591815;36961;37008.25983480369;37069.62930370662;37348.81325593355;37449.43825842669;37663;42555.148897022504;42752.786236069864;42901.14197716091;43042;43165.568344316154;35268.64731352653;35482.41974666877;35766;35773.46339804957;36184.457233140995;26460.735392645827;26587.202383928514;26643.866780665972;26741.33146671271;27067.72932270731 08:14:35 INFO - PID 3766 | |2;jquery.html;640.9662297624913;643.2911479031933;648.7936185527363;653.9363083951687;654.4554850552696;24369.147079852188;25022.624660630107;25095.247142585707;25125.12062077823;25187;27685;27915.16254512368;27940.720592794067;27972.3006924827;27997.86001069997;6434.260060093079;6833.58998460094;6876.312368763109;6881.827954301132;6898.103246577938;6802.829929251783;6914.412274956635;6915.343042410988;6915.412189963859;6925.099737034178;6615;6878;6878.27778083299;6897.586144831301;6898.724051037978;6848.1097457330725;6891.517593768438;6893.310668933092;6895.5173137880365;6939.444844412435;6431.163948686688;6436.613803171825;6453.645049522274;6468.1268028815975;6480.351964803529;30510.237244068852;31438.214044648837;31831.363372732416;31981;32126.839365803255;2362.4330160761465;2413.444907671228;2414.3360575841784;2418.625113107471;2418.7944024757917;20707.5858482831;21209.151633934624;21329.893350533304;21334.146634134617;21337.89331053335;2483.192962287264;2488;2536.708278547954;2542.3898264416594;2550.528152291832;144877;148855;160787;164870;166299;16378.181090945405;16521.83478165227;16837.41069062577;17117.743233851503;17221.827781722022;27162;27172.320691982462;27202.047928322412;27326.316842079108;27370.45259094807;10969.067629251604;11013.83479247812;11069.114470842313;11075.16936229786;11187.049100826522;10120.190384769201;10133.847992280122;10146.137578305927;10169.440680762482;10213.693589192337;10082.848757268646;10134.746631334276;10297.12474439666;10329.74175645615;10356.482175891175;3122.703343182394;3171.682831716856;3182.7294679952465;3184.1084496341064;3243.2702641905385;1067.4769363012224;1068.9625863094755;1094.2449709700281;1097.8353247013013;1097.8902109788958;21812.563748724937;21900.561988760135;22025.889870550545;22030.669539956914;22100.88949555242;1131.2025022359217;1139.6921234500203;1141.8173092305192;1146.9025132863633;1153.3944679043568;11748.70628234301;12219.389030548436;12243.265404075784;12306.323152226534;12331.321777302155;651400;652936;659105;665779;686982;133406;136584;141638.2918085403;141663.2916835409;141676;1249.419020155632;1265.7531781302557;1294.210531575757;1294.9287789171497;1299.6555912683148;19271.22915083437;19362.90318548398;19456.416307510797;19491.220351185795;19640.312589059315;63406.048909265475;63790.3620963803;63827.68086159539;65082;66594;83752.58123709343;84037.57981210055;84792.15207847842;84904;86065.56967215124;16497.09265990382;16530.66938661196;16538.173091345627;16625.91687041557;16634.833651663328;24479.38801529976;24717.25848224484;24854.254372368858;24881.129160479297;24963.875180624706;20312.187512499335;20317.59364812725;20417.183312667326;20424.285150019678;20582.38252852417;18088.909555452137;18186.545336366697;18300.450986470427;18394.448166555027;18651;19359.3224237151;20671.79328206759;20739.792602073787;20829.687554686996;20855.8957205213;16320;16351.836481635031;16413.917930410273;16423.768217383506;16427.260773264992;20067.59864802725;20174.697379539026;20197.19211231593;20556;20585.588288233852 08:14:35 INFO - PID 3766 | |3;mootools.html;1434.512265829618;1508.8038554987857;1511.6145382927352;1521.4218596933163;1524.3445318513036;17477.56306092347;18033.458996230132;18048.45854624362;18225.0887455627;18234.361797337097;17661.293548258087;17833.643327133483;17851.732224016618;17860.732089018642;17861.10694465278;3112.3464072544793;3137.231378312317;3182.10900947735;3185.410699020677;3195.872165113393;3116;3141.3560220154814;3151.243701511632;3154.511050787132;3155.5108958111527;3057.1287183152804;3117.9688203117908;3130.20179854138;3141.732952699012;3148.590683211191;3020.3355261842357;3042.4371491274187;3045.2082458560935;3058.4036112958092;3058.72471477567;3000.744936680385;3017.818930864133;3021.1993821637284;3021.969780302191;3032.181311046027;16833.242504087346;16988.15059247045;17118.31526738929;17242;17318.740218896728;8168;8451.957740211321;8481.321494280442;8496.277816385615;8513.914860851373;5688.715564221772;5715.628484148517;5990.820275391745;5998;6050.334463209044;2138.134055707441;2161.7513985891665;2162.7837216278404;2165.177232651598;2208.1608988584394;28617.427651447062;29597.55603665947;31254.687453125403;32167.356652866813;32196.678033219836;8212.794680132925;8254.628541715638;8310.792230194293;8410.074891761902;8473.406861519758;23870.880645596662;23970.161044363365;24083.518329633658;24108.758912410653;24114.39714007164;8846.734597962071;9057.68298109563;9070.54647267634;9131.77170570741;9136.771580710536;7650.349720273728;7848.7645370638975;7936.087349954829;7992.480488768211;7994.2805147537;6463.06285588587;6501.609903405811;6525.3800888915475;6624.635645039569;6831.146106736658;2113.281484295347;2124.341454149219;2125.288028510435;2125.5005073807642;2137.294692751388;983.2772911909741;983.537737263485;983.6704703924265;1012.3166862367872;1019.8827134879584;8294.917050829536;8392.244697977212;8405.579721013924;8507.447015943944;8513.318934485227;784.705735349244;813.1461964936793;815.8409110223449;817.8631701934316;828.4242451496273;4805.918668299604;4864.127186516364;4940.110780059552;4964.032013757283;5012.899742005213;25239.62140567893;26304.473910521683;26575.734242657327;26600.467990640078;26696.59955100675;24858.875705621354;25895.093671721777;25919.22242332733;26276.605850912252;26359.47281054368;11022.614208502664;11263.211575189822;12233.755324893453;12790.808137877939;12976.221426714428;9630.32587718853;9639.132478077006;9646.035396460298;9776;9828.4102953823;21818.781812181674;21978.340649780534;22410.215642452436;22590;22988.275879309454;23131.537369252856;23286.65070023917;23417.882910585337;23735.762642373356;23982.640260396456;13381.598552043455;13610.319484025957;13625.659358516117;13629.318534073058;13669.931650341685;15929.601759956095;15979.121148336953;16016;16124;16193.75709364384;12749.6175114743;12799.10406271552;12869.35653217354;13065.673358166121;13254.337283135997;12707.36463176856;12710.682232944251;12745.299008554619;12804.551840685905;13095.869041309847;11123;11153.330800152016;11237.38194399316;11303.943480282545;11323.15076369259;7924;7944.602769861599;7980.680772769024;7981.082175549654;8064.475809072392;8514.574271286534;8550.700725474579;8574.31405487572;8638.611262493328;8656.48061116335 08:14:35 INFO - PID 3766 | |4;prototype.html;563.124341648736;582.935877053524;584.1179818474103;607.5139888089528;614.4009590649117;6283.183186185789;6422.454091402231;6429.903551446726;6473.579217350881;6508.609483430998;6252.437280644741;6350.301466838644;6358.205224346957;6367.458766004884;6423.068655045027;5283.630145889799;5317.46825317469;5318.228856815779;5336.332958380202;5337.839864804062;4998.875028124289;5202.141646628302;5247.107991641411;5254.369475662926;5291.3650361956425;5094.898102037957;5117.692938423688;5131.435542090367;5132.820351287705;5135.794568217267;5079.695218286896;5091.261767043758;5109.259157422191;5131.819681473282;5134.127198376266;4594.724316541018;4633.745144017078;4652.325412815157;4660.324252983299;4682.344471773954;6246.906296405557;6248.96875515624;6290.150829638032;6303.716332765037;6308.148399966038;5950.226470558843;5977.760889564455;6021.729022194012;6024.277086749618;6031.728572214261;6631.635260060695;6684.866302673968;6767.763128290486;6770.932290677079;6787.219469760942;4998.775055122528;5193.922091168635;5214.296070030536;5224.686518808883;5242.344706911636;6729.25978142404;6917.204521480044;6949;6981.441484681264;7055.329743674394;5328.280682107943;5344.812931547377;5349.786008559614;5371.892562148735;5388.973055134699;5410.107332290128;5424.349078110652;5426.75579598919;5430.647007944471;5443.564514838803;5951.791687290924;5973.551983601247;5993.61041532299;5994.610350327302;6020.939790602038;6805.625690587065;6812.48906332027;6834.521583489108;6841.486888483384;6849.938259569774;1865.2818664814145;1865.9720104198448;1873.484791682271;1881.6048629787624;1888.8772229805018;1298.2664794391328;1321.6497628128554;1322.6891680455085;1342.9261390623415;1343.43575698207;6289.7484100636375;6297.842553936188;6312.305646378894;6330.873382532415;6345.270293916166;6629.569078009914;6698.96650516754;6721.798346049626;6725.226598941086;6739.797806065826;6177.69111544421;6212.689365531705;6261.843453913598;6359.491240700733;6377.394147555975;3561.893143205708;3611.3138503684218;3613.855445782192;3619.8371073301764;3637.1270894985014;7061.788146355618;7096.822579435556;7136.393406560501;7162.713491460283;7164.92835071653;6969.606078784162;7072.3634872861685;7087.82280442993;7098.574085554883;7119.181294151135;7012.966239790404;7013.368796808311;7014.824629384306;7054.08296921397;7093.255208203168;6756.358145976124;6772.864542709217;6780.627065511444;6787.913933770573;6800.251972283144;6745.966270168814;6759.324067593201;6765;6803.115594972622;6826.8634627308165;7005.474589405917;7068.29317068289;7101.680424380812;7110.395616372666;7126.465515086285;5231.790728370823;5273.6572122811895;5318.4149743527405;5358.946410535845;5398.3791863934575;6513.934860651522;6597.746428178631;6850.561382109613;6853.280405557344;6864.828379290558;6653.434458071119;6688.899666504909;6716.261211266659;6745.291744366967;6767.492438067263;6622.63575503352;6637.535372523877;6665.733370665119;6688.398044176048;6694.59832410057;6321.11504389395;6443.420092191725;6534.673266336569;6609.603423794395;6707.262201157771;6616.636085015178;6700.430463410665;6729.394354508117;6758.662066896733;6808.489363297674;6432.067350234096;6559.934400655932;6597.0764093026055;6612.834679132868;6629.3039230882 08:14:35 INFO - PID 3766 | |5;yui.html;1003.0671475527756;1019.0980981831079;1037.5279247942187;1040.1210976724665;1049.1606714628294;21077.367678969636;21579.892100539495;21699.3490195294;21758;21764.673529897063;17287.049212293317;17550;19439;20395.286164984227;20520.076596553154;1057.9523921423536;1093.1418836213575;1093.4532733633184;1095.780843831233;1098.1050443888246;1072.3244356055675;1105.8396532502782;1107;1109.5894519027931;1118.8937050980144;1175.153889199779;1183.7928362536522;1184.8696643369221;1185.2296007595046;1189.3636904256225;1181.533294348736;1186.4898093819654;1186.7211205366732;1187.1511869013655;1191.1185722565326;870.3472395703222;872.3718922375879;872.5768002518773;872.7425409504211;875.6891303587262;21220.257290994894;21352.893235533877;21504.354869353945;21655;22141.778582214134;23374.53250934972;23405.41486462835;23543.4114147146;23840.284791456284;23851.52296954051;19441.708374374397;19798.307059253002;19818;19946.70079948802;19971.50071248216;4161.583841615814;4200.6219440250525;4205.537390887;4218.8523401680795;4220.556841531626;157214.21392892962;165994.1700291503;167719.1614041934;167968;169018;18162.182701778453;18294.45116646476;18306.63386732258;18337.541561461072;18357.63284734298;34838.65161348405;35717.28565428729;35971.640283596826;36007.4598881017;36042.45936310958;8947.3736838422;9097.499637519868;9103.271738260923;9128.406653567497;9131.041240669634;5033.245013247969;5075.822346217865;5092.898142037213;5104.846854594368;5105.2086926526445;6165.10605962133;6175.59858609189;6224.284207316126;6237.937620623827;6260;1063.1547919404093;1076.6608518316705;1085.4030283344287;1089.4171618184391;1097.040089921319;770.6878714120791;772.8454309138195;791.9247671471201;796.016919104905;800.4156965415164;12929.612111636667;13038.478460861648;13750.862491374959;14330.283485825667;14369.56891293263;730.0983285642228;736.9742059027908;738.7303634173488;739.7152096442908;758.7268583310004;6005.849853753691;6080.118382834553;6128.080787881764;6179.938200618026;6241.313455519889;152652;157305;157964;159282;159437;139661.30169349292;139801;142033;144636;145902;120897;121173.39413303054;121998;123482.38258808649;127884.36057819652;11512.36681982499;11542.422878855856;11658.417079146178;11675.416229188337;11676;58877;59363.40636593752;59507.404925951916;59891.70054149701;60040.972371064694;75365.6231718838;76495;77457.61271193608;79050.60474697589;79543.20456795358;15431.537053888402;15515.534533963999;15759.921200394383;15904.681906362039;15921.601959951095;17450.9127454367;17744.822551774832;17838.19728112212;17964.461066167514;18098.457046288633;17300.65398691994;17548.473545793648;17572.472825814733;17788.46634600964;17793.110344482982;16944.74582881233;16973.830261697225;17019.659606807545;17066.31734730597;17161.914190428968;16297.022178669318;16356.754648680517;16542.255598498337;16685.16574171311;16754;11997.820032699341;12122.939385303018;12192.878071219175;12450.501979920702;12468.940140088194;12165.391730413266;12172.75654486923;12311.938440307742;12409.131360804657;12433.191842530206 08:14:35 INFO - PID 3766 | __end_tp_report 08:14:35 INFO - PID 3766 | __start_cc_report 08:14:35 INFO - PID 3766 | _x_x_mozilla_cycle_collect,257 08:14:35 INFO - PID 3766 | __end_cc_report 08:14:35 INFO - PID 3766 | __startTimestamp1506006875401__endTimestamp 08:14:35 INFO - PID 3766 | 08:14:35 INFO - PID 3766 | ------- Summary: start ------- 08:14:35 INFO - PID 3766 | Number of tests: 6 08:14:35 INFO - PID 3766 | 08:14:35 INFO - PID 3766 | [#0] dojo.html Cycles:165 Average:6671.07 Median:6949.00 stddev:775.77 (11.2%) stddev-sans-first:749.72 08:14:35 INFO - PID 3766 | Values: 4018.3 4043.4 4057.4 4071.1 4092.7 6563.3 6565.1 6635.4 6668.3 6688.7 6585.7 6652.7 6668.2 6683.4 6716.8 5927.3 5937.9 6014.1 6025.3 6059.5 5929.4 5996.5 5999.7 6090.6 6098.1 6059.2 6097.9 6102.7 6121.6 6142.8 6026.8 6048.0 6059.1 6106.5 6132.0 5882.0 5910.7 5984.5 6014.7 6022.3 6576.2 6577.3 6583.8 6602.5 6639.8 7214.8 7280.8 7313.6 7315.6 7341.0 7249.0 7251.4 7266.1 7266.1 7354.0 6557.2 6683.0 6692.2 6764.0 6764.3 7354.4 7403.2 7403.3 7449.5 7450.7 7071.5 7077.2 7077.7 7150.7 7157.5 7138.0 7196.4 7242.1 7282.1 7291.1 7107.7 7125.5 7171.5 7188.7 7201.2 7158.0 7179.6 7240.4 7271.2 7292.2 5315.9 5333.4 5406.4 5415.0 5415.9 4823.4 4836.4 4856.8 4866.3 4868.0 6671.6 6713.2 6765.2 6783.5 6823.4 7093.7 7115.7 7204.5 7260.8 7305.3 6700.9 6758.0 6833.7 6840.0 6851.0 6028.9 6058.1 6127.8 6130.2 6132.8 7478.3 7482.0 7486.5 7581.1 7595.7 7065.2 7204.3 7222.4 7232.1 7237.3 7363.6 7436.6 7447.5 7451.9 7465.8 7112.5 7115.3 7196.1 7201.5 7217.3 7253.6 7279.3 7295.2 7361.6 7380.3 6880.0 7008.1 7033.6 7098.2 7108.6 6968.0 6988.7 7000.0 7000.4 7003.7 6905.8 6949.0 7000.5 7007.8 7008.0 7046.7 7068.2 7070.8 7147.7 7174.9 6764.3 6826.8 6833.1 6926.9 6934.0 08:14:35 INFO - PID 3766 | 08:14:35 INFO - PID 3766 | [#1] ext.html Cycles:180 Average:45066.42 Median:3903.01 stddev:67881.38 (1739.2%) stddev-sans-first:67990.76 08:14:35 INFO - PID 3766 | Values: 887.8 894.6 900.2 924.3 934.4 46888.8 47367.3 47588.5 47612.5 47654.0 47684.5 48786.5 48793.0 48833.3 48888.3 8885.8 8940.1 8970.8 9000.3 9007.1 8851.1 8943.9 8946.2 9002.5 9005.4 9017.9 9023.3 9066.3 9070.2 9076.4 8859.0 8954.5 8959.5 8990.0 8999.6 8881.0 8918.5 8951.7 8961.7 8961.9 42560.1 43128.4 43129.1 43190.4 43227.4 3880.8 3925.2 3925.6 3929.2 3929.8 35644.6 35735.0 35758.5 35770.6 35859.6 5672.1 5764.9 5796.9 5810.1 5810.8 220274.0 225385.0 226141.0 231307.0 234716.8 56147.7 56337.2 56448.4 56478.0 56493.4 58127.0 58152.4 58162.0 58170.4 58206.4 15859.8 15991.9 16025.4 16090.4 16145.7 12905.7 12918.1 12925.3 12933.9 12940.4 14745.5 15043.8 15059.2 15064.2 15271.2 5475.0 5492.9 5497.3 5583.5 5637.6 1120.2 1127.0 1128.3 1128.8 1131.6 26273.7 26317.7 26376.6 26947.3 26974.5 1229.0 1235.4 1236.4 1236.8 1240.4 13915.7 14123.7 14192.2 14229.8 14265.2 293675.0 294406.0 294723.0 303048.0 308112.0 64029.7 64431.7 64439.0 64452.0 64489.0 1529.8 1538.8 1539.8 1561.0 1565.0 30492.4 30543.5 30638.5 30668.0 30770.7 43558.0 43663.6 43715.8 43754.3 43806.3 250178.0 250725.0 250961.0 251920.0 252524.0 38261.8 38631.8 38634.4 38646.2 38743.4 47856.3 49490.0 49555.5 50083.0 50126.5 36246.8 36270.6 36408.3 36732.8 36961.0 37008.3 37069.6 37348.8 37449.4 37663.0 42555.1 42752.8 42901.1 43042.0 43165.6 35268.6 35482.4 35766.0 35773.5 36184.5 26460.7 26587.2 26643.9 26741.3 27067.7 08:14:35 INFO - PID 3766 | 08:14:35 INFO - PID 3766 | [#2] jquery.html Cycles:180 Average:42409.83 Median:21856.56 stddev:110731.22 (506.6%) stddev-sans-first:110997.43 08:14:35 INFO - PID 3766 | Values: 641.0 643.3 648.8 653.9 654.5 24369.1 25022.6 25095.2 25125.1 25187.0 27685.0 27915.2 27940.7 27972.3 27997.9 6434.3 6833.6 6876.3 6881.8 6898.1 6802.8 6914.4 6915.3 6915.4 6925.1 6615.0 6878.0 6878.3 6897.6 6898.7 6848.1 6891.5 6893.3 6895.5 6939.4 6431.2 6436.6 6453.6 6468.1 6480.4 30510.2 31438.2 31831.4 31981.0 32126.8 2362.4 2413.4 2414.3 2418.6 2418.8 20707.6 21209.2 21329.9 21334.1 21337.9 2483.2 2488.0 2536.7 2542.4 2550.5 144877.0 148855.0 160787.0 164870.0 166299.0 16378.2 16521.8 16837.4 17117.7 17221.8 27162.0 27172.3 27202.0 27326.3 27370.5 10969.1 11013.8 11069.1 11075.2 11187.0 10120.2 10133.8 10146.1 10169.4 10213.7 10082.8 10134.7 10297.1 10329.7 10356.5 3122.7 3171.7 3182.7 3184.1 3243.3 1067.5 1069.0 1094.2 1097.8 1097.9 21812.6 21900.6 22025.9 22030.7 22100.9 1131.2 1139.7 1141.8 1146.9 1153.4 11748.7 12219.4 12243.3 12306.3 12331.3 651400.0 652936.0 659105.0 665779.0 686982.0 133406.0 136584.0 141638.3 141663.3 141676.0 1249.4 1265.8 1294.2 1294.9 1299.7 19271.2 19362.9 19456.4 19491.2 19640.3 63406.0 63790.4 63827.7 65082.0 66594.0 83752.6 84037.6 84792.2 84904.0 86065.6 16497.1 16530.7 16538.2 16625.9 16634.8 24479.4 24717.3 24854.3 24881.1 24963.9 20312.2 20317.6 20417.2 20424.3 20582.4 18088.9 18186.5 18300.5 18394.4 18651.0 19359.3 20671.8 20739.8 20829.7 20855.9 16320.0 16351.8 16413.9 16423.8 16427.3 20067.6 20174.7 20197.2 20556.0 20585.6 08:14:35 INFO - PID 3766 | 08:14:35 INFO - PID 3766 | [#3] mootools.html Cycles:180 Average:10924.19 Median:3019.08 stddev:8142.33 (269.7%) stddev-sans-first:8133.95 08:14:35 INFO - PID 3766 | Values: 1434.5 1508.8 1511.6 1521.4 1524.3 17477.6 18033.5 18048.5 18225.1 18234.4 17661.3 17833.6 17851.7 17860.7 17861.1 3112.3 3137.2 3182.1 3185.4 3195.9 3116.0 3141.4 3151.2 3154.5 3155.5 3057.1 3118.0 3130.2 3141.7 3148.6 3020.3 3042.4 3045.2 3058.4 3058.7 3000.7 3017.8 3021.2 3022.0 3032.2 16833.2 16988.2 17118.3 17242.0 17318.7 8168.0 8452.0 8481.3 8496.3 8513.9 5688.7 5715.6 5990.8 5998.0 6050.3 2138.1 2161.8 2162.8 2165.2 2208.2 28617.4 29597.6 31254.7 32167.4 32196.7 8212.8 8254.6 8310.8 8410.1 8473.4 23870.9 23970.2 24083.5 24108.8 24114.4 8846.7 9057.7 9070.5 9131.8 9136.8 7650.3 7848.8 7936.1 7992.5 7994.3 6463.1 6501.6 6525.4 6624.6 6831.1 2113.3 2124.3 2125.3 2125.5 2137.3 983.3 983.5 983.7 1012.3 1019.9 8294.9 8392.2 8405.6 8507.4 8513.3 784.7 813.1 815.8 817.9 828.4 4805.9 4864.1 4940.1 4964.0 5012.9 25239.6 26304.5 26575.7 26600.5 26696.6 24858.9 25895.1 25919.2 26276.6 26359.5 11022.6 11263.2 12233.8 12790.8 12976.2 9630.3 9639.1 9646.0 9776.0 9828.4 21818.8 21978.3 22410.2 22590.0 22988.3 23131.5 23286.7 23417.9 23735.8 23982.6 13381.6 13610.3 13625.7 13629.3 13669.9 15929.6 15979.1 16016.0 16124.0 16193.8 12749.6 12799.1 12869.4 13065.7 13254.3 12707.4 12710.7 12745.3 12804.6 13095.9 11123.0 11153.3 11237.4 11303.9 11323.2 7924.0 7944.6 7980.7 7981.1 8064.5 8514.6 8550.7 8574.3 8638.6 8656.5 08:14:35 INFO - PID 3766 | 08:14:35 INFO - PID 3766 | [#4] prototype.html Cycles:180 Average:5741.34 Median:6347.79 stddev:1581.68 (24.9%) stddev-sans-first:1537.62 08:14:35 INFO - PID 3766 | Values: 563.1 582.9 584.1 607.5 614.4 6283.2 6422.5 6429.9 6473.6 6508.6 6252.4 6350.3 6358.2 6367.5 6423.1 5283.6 5317.5 5318.2 5336.3 5337.8 4998.9 5202.1 5247.1 5254.4 5291.4 5094.9 5117.7 5131.4 5132.8 5135.8 5079.7 5091.3 5109.3 5131.8 5134.1 4594.7 4633.7 4652.3 4660.3 4682.3 6246.9 6249.0 6290.2 6303.7 6308.1 5950.2 5977.8 6021.7 6024.3 6031.7 6631.6 6684.9 6767.8 6770.9 6787.2 4998.8 5193.9 5214.3 5224.7 5242.3 6729.3 6917.2 6949.0 6981.4 7055.3 5328.3 5344.8 5349.8 5371.9 5389.0 5410.1 5424.3 5426.8 5430.6 5443.6 5951.8 5973.6 5993.6 5994.6 6020.9 6805.6 6812.5 6834.5 6841.5 6849.9 1865.3 1866.0 1873.5 1881.6 1888.9 1298.3 1321.6 1322.7 1342.9 1343.4 6289.7 6297.8 6312.3 6330.9 6345.3 6629.6 6699.0 6721.8 6725.2 6739.8 6177.7 6212.7 6261.8 6359.5 6377.4 3561.9 3611.3 3613.9 3619.8 3637.1 7061.8 7096.8 7136.4 7162.7 7164.9 6969.6 7072.4 7087.8 7098.6 7119.2 7013.0 7013.4 7014.8 7054.1 7093.3 6756.4 6772.9 6780.6 6787.9 6800.3 6746.0 6759.3 6765.0 6803.1 6826.9 7005.5 7068.3 7101.7 7110.4 7126.5 5231.8 5273.7 5318.4 5358.9 5398.4 6513.9 6597.7 6850.6 6853.3 6864.8 6653.4 6688.9 6716.3 6745.3 6767.5 6622.6 6637.5 6665.7 6688.4 6694.6 6321.1 6443.4 6534.7 6609.6 6707.3 6616.6 6700.4 6729.4 6758.7 6808.5 6432.1 6559.9 6597.1 6612.8 6629.3 08:14:35 INFO - PID 3766 | 08:14:35 INFO - PID 3766 | [#5] yui.html Cycles:180 Average:29428.44 Median:17658.65 stddev:44894.00 (254.2%) stddev-sans-first:44969.20 08:14:35 INFO - PID 3766 | Values: 1003.1 1019.1 1037.5 1040.1 1049.2 21077.4 21579.9 21699.3 21758.0 21764.7 17287.0 17550.0 19439.0 20395.3 20520.1 1058.0 1093.1 1093.5 1095.8 1098.1 1072.3 1105.8 1107.0 1109.6 1118.9 1175.2 1183.8 1184.9 1185.2 1189.4 1181.5 1186.5 1186.7 1187.2 1191.1 870.3 872.4 872.6 872.7 875.7 21220.3 21352.9 21504.4 21655.0 22141.8 23374.5 23405.4 23543.4 23840.3 23851.5 19441.7 19798.3 19818.0 19946.7 19971.5 4161.6 4200.6 4205.5 4218.9 4220.6 157214.2 165994.2 167719.2 167968.0 169018.0 18162.2 18294.5 18306.6 18337.5 18357.6 34838.7 35717.3 35971.6 36007.5 36042.5 8947.4 9097.5 9103.3 9128.4 9131.0 5033.2 5075.8 5092.9 5104.8 5105.2 6165.1 6175.6 6224.3 6237.9 6260.0 1063.2 1076.7 1085.4 1089.4 1097.0 770.7 772.8 791.9 796.0 800.4 12929.6 13038.5 13750.9 14330.3 14369.6 730.1 737.0 738.7 739.7 758.7 6005.8 6080.1 6128.1 6179.9 6241.3 152652.0 157305.0 157964.0 159282.0 159437.0 139661.3 139801.0 142033.0 144636.0 145902.0 120897.0 121173.4 121998.0 123482.4 127884.4 11512.4 11542.4 11658.4 11675.4 11676.0 58877.0 59363.4 59507.4 59891.7 60041.0 75365.6 76495.0 77457.6 79050.6 79543.2 15431.5 15515.5 15759.9 15904.7 15921.6 17450.9 17744.8 17838.2 17964.5 18098.5 17300.7 17548.5 17572.5 17788.5 17793.1 16944.7 16973.8 17019.7 17066.3 17161.9 16297.0 16356.8 16542.3 16685.2 16754.0 11997.8 12122.9 12192.9 12450.5 12468.9 12165.4 12172.8 12311.9 12409.1 12433.2 08:14:35 INFO - PID 3766 | -------- Summary: end -------- 08:14:35 INFO - PID 3766 | 08:14:35 INFO - TEST-INFO | 3766: exit 0 08:14:35 INFO - TEST-OK | dromaeo_css | took 1107778ms 08:14:35 INFO - TEST-START | kraken 08:14:35 INFO - Initialising browser for kraken test... 08:14:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46297/getInfo.html -profile /tmp/tmpZ1hwk_/profile 08:14:35 INFO - TEST-INFO | started process 4136 (/builds/slave/test/build/application/firefox/firefox http://localhost:46297/getInfo.html) 08:14:40 INFO - TEST-INFO | 4136: exit 0 08:14:40 INFO - Browser initialized. 08:14:40 INFO - Running cycle 1/1 for kraken test... 08:14:40 INFO - Using env: {'DISPLAY': ':0', 08:14:40 INFO - 'HOME': '/home/cltbld', 08:14:40 INFO - 'JSGC_DISABLE_POISONING': '1', 08:14:40 INFO - 'LANG': 'en_US.UTF-8', 08:14:40 INFO - 'LANGUAGE': 'en_US:en', 08:14:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:14:40 INFO - 'LOGNAME': 'cltbld', 08:14:40 INFO - 'MAIL': '/var/mail/cltbld', 08:14:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:14:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:14:40 INFO - 'MOZ_CRASHREPORTER': '1', 08:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:14:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:14:40 INFO - 'MOZ_NO_REMOTE': '1', 08:14:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:14:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:14:40 INFO - 'NO_EM_RESTART': '1', 08:14:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:14:40 INFO - 'PWD': '/builds/slave/test', 08:14:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:14:40 INFO - 'RUST_BACKTRACE': 'full', 08:14:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:14:40 INFO - 'SHELL': '/bin/bash', 08:14:40 INFO - 'SHLVL': '1', 08:14:40 INFO - 'TERM': 'linux', 08:14:40 INFO - 'TMOUT': '86400', 08:14:40 INFO - 'USER': 'cltbld', 08:14:40 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506005577.607800-164198643', 08:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:14:40 INFO - '_': '/tools/buildbot/bin/python'} 08:14:40 INFO - TEST-INFO | started process 4326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ1hwk_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:14:52 INFO - PID 4326 | RSS: Main: 144343040 08:14:52 INFO - PID 4326 | 08:14:54 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46297/tests/kraken/driver.html?testName=audio-beat-detection) 08:14:55 INFO - PID 4326 | RSS: Main: 150913024 08:14:55 INFO - PID 4326 | 08:14:57 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46297/tests/kraken/driver.html?testName=audio-dft) 08:14:58 INFO - PID 4326 | RSS: Main: 151588864 08:14:58 INFO - PID 4326 | 08:15:01 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46297/tests/kraken/driver.html?testName=audio-fft) 08:15:02 INFO - PID 4326 | RSS: Main: 153292800 08:15:02 INFO - PID 4326 | 08:15:04 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46297/tests/kraken/driver.html?testName=audio-oscillator) 08:15:04 INFO - PID 4326 | RSS: Main: 152850432 08:15:04 INFO - PID 4326 | 08:15:06 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46297/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:15:07 INFO - PID 4326 | RSS: Main: 152936448 08:15:07 INFO - PID 4326 | 08:15:10 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46297/tests/kraken/driver.html?testName=imaging-darkroom) 08:15:11 INFO - PID 4326 | RSS: Main: 152838144 08:15:11 INFO - PID 4326 | 08:15:14 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46297/tests/kraken/driver.html?testName=imaging-desaturate) 08:15:15 INFO - PID 4326 | RSS: Main: 155246592 08:15:15 INFO - PID 4326 | 08:15:17 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46297/tests/kraken/driver.html?testName=json-parse-financial) 08:15:18 INFO - PID 4326 | RSS: Main: 155426816 08:15:18 INFO - PID 4326 | 08:15:20 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46297/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:15:20 INFO - PID 4326 | RSS: Main: 155983872 08:15:20 INFO - PID 4326 | 08:15:22 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46297/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:15:22 INFO - PID 4326 | RSS: Main: 155586560 08:15:22 INFO - PID 4326 | 08:15:24 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46297/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:15:25 INFO - PID 4326 | RSS: Main: 152301568 08:15:25 INFO - PID 4326 | 08:15:28 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46297/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:15:28 INFO - PID 4326 | RSS: Main: 152637440 08:15:28 INFO - PID 4326 | 08:15:31 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46297/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:15:31 INFO - PID 4326 | RSS: Main: 152879104 08:15:31 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | Cycle 1(1): loaded http://localhost:46297/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46297/tests/kraken/driver.html?testName=ai-astar) 08:15:33 INFO - PID 4326 | RSS: Main: 154619904 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | __start_tp_report 08:15:33 INFO - PID 4326 | _x_x_mozilla_page_load 08:15:33 INFO - PID 4326 | _x_x_mozilla_page_load_details 08:15:33 INFO - PID 4326 | |i|pagename|runs| 08:15:33 INFO - PID 4326 | |0;ai-astar;105;104;107;106;108;109;105;109;109;106 08:15:33 INFO - PID 4326 | |1;audio-beat-detection;110;112;113;112;110;111;139;109;123;111 08:15:33 INFO - PID 4326 | |2;audio-dft;162;164;162;163;163;162;162;162;163;162 08:15:33 INFO - PID 4326 | |3;audio-fft;73;74;74;73;74;76;73;73;73;73 08:15:33 INFO - PID 4326 | |4;audio-oscillator;78;76;77;78;81;81;80;85;80;82 08:15:33 INFO - PID 4326 | |5;imaging-gaussian-blur;113;114;114;113;114;115;115;115;114;114 08:15:33 INFO - PID 4326 | |6;imaging-darkroom;148;148;149;149;149;148;147;148;148;157 08:15:33 INFO - PID 4326 | |7;imaging-desaturate;93;95;94;94;94;94;94;99;94;94 08:15:33 INFO - PID 4326 | |8;json-parse-financial;45;44;40;41;42;42;43;42;44;44 08:15:33 INFO - PID 4326 | |9;json-stringify-tinderbox;38;37;38;38;38;37;37;39;38;38 08:15:33 INFO - PID 4326 | |10;stanford-crypto-aes;70;74;69;70;72;71;72;70;69;73 08:15:33 INFO - PID 4326 | |11;stanford-crypto-ccm;102;103;107;108;101;107;105;101;104;103 08:15:33 INFO - PID 4326 | |12;stanford-crypto-pbkdf2;155;152;154;165;153;147;159;150;151;155 08:15:33 INFO - PID 4326 | |13;stanford-crypto-sha256-iterative;62;63;60;60;59;58;58;66;59;59 08:15:33 INFO - PID 4326 | __end_tp_report 08:15:33 INFO - PID 4326 | __start_cc_report 08:15:33 INFO - PID 4326 | _x_x_mozilla_cycle_collect,713 08:15:33 INFO - PID 4326 | __end_cc_report 08:15:33 INFO - PID 4326 | __startTimestamp1506006933778__endTimestamp 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | ------- Summary: start ------- 08:15:33 INFO - PID 4326 | Number of tests: 14 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#0] ai-astar Cycles:10 Average:106.80 Median:107.50 stddev:1.87 (1.7%) stddev-sans-first:1.87 08:15:33 INFO - PID 4326 | Values: 105.0 104.0 107.0 106.0 108.0 109.0 105.0 109.0 109.0 106.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#1] audio-beat-detection Cycles:10 Average:115.00 Median:112.00 stddev:9.31 (8.3%) stddev-sans-first:9.70 08:15:33 INFO - PID 4326 | Values: 110.0 112.0 113.0 112.0 110.0 111.0 139.0 109.0 123.0 111.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:0.71 (0.4%) stddev-sans-first:0.73 08:15:33 INFO - PID 4326 | Values: 162.0 164.0 162.0 163.0 163.0 162.0 162.0 162.0 163.0 162.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#3] audio-fft Cycles:10 Average:73.60 Median:73.50 stddev:0.97 (1.3%) stddev-sans-first:1.00 08:15:33 INFO - PID 4326 | Values: 73.0 74.0 74.0 73.0 74.0 76.0 73.0 73.0 73.0 73.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:80.50 stddev:2.66 (3.3%) stddev-sans-first:2.74 08:15:33 INFO - PID 4326 | Values: 78.0 76.0 77.0 78.0 81.0 81.0 80.0 85.0 80.0 82.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 08:15:33 INFO - PID 4326 | Values: 113.0 114.0 114.0 113.0 114.0 115.0 115.0 115.0 114.0 114.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:148.50 stddev:2.85 (1.9%) stddev-sans-first:2.99 08:15:33 INFO - PID 4326 | Values: 148.0 148.0 149.0 149.0 149.0 148.0 147.0 148.0 148.0 157.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:1.65 (1.8%) stddev-sans-first:1.66 08:15:33 INFO - PID 4326 | Values: 93.0 95.0 94.0 94.0 94.0 94.0 94.0 99.0 94.0 94.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#8] json-parse-financial Cycles:10 Average:42.70 Median:43.50 stddev:1.57 (3.6%) stddev-sans-first:1.42 08:15:33 INFO - PID 4326 | Values: 45.0 44.0 40.0 41.0 42.0 42.0 43.0 42.0 44.0 44.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.63 (1.7%) stddev-sans-first:0.67 08:15:33 INFO - PID 4326 | Values: 38.0 37.0 38.0 38.0 38.0 37.0 37.0 39.0 38.0 38.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#10] stanford-crypto-aes Cycles:10 Average:71.00 Median:71.50 stddev:1.70 (2.4%) stddev-sans-first:1.76 08:15:33 INFO - PID 4326 | Values: 70.0 74.0 69.0 70.0 72.0 71.0 72.0 70.0 69.0 73.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#11] stanford-crypto-ccm Cycles:10 Average:104.10 Median:104.50 stddev:2.56 (2.4%) stddev-sans-first:2.60 08:15:33 INFO - PID 4326 | Values: 102.0 103.0 107.0 108.0 101.0 107.0 105.0 101.0 104.0 103.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.10 Median:154.50 stddev:5.02 (3.2%) stddev-sans-first:5.32 08:15:33 INFO - PID 4326 | Values: 155.0 152.0 154.0 165.0 153.0 147.0 159.0 150.0 151.0 155.0 08:15:33 INFO - PID 4326 | 08:15:33 INFO - PID 4326 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:60.00 stddev:2.55 (4.2%) stddev-sans-first:2.64 08:15:33 INFO - PID 4326 | Values: 62.0 63.0 60.0 60.0 59.0 58.0 58.0 66.0 59.0 59.0 08:15:33 INFO - PID 4326 | -------- Summary: end -------- 08:15:33 INFO - PID 4326 | 08:15:34 INFO - PID 4326 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 08:15:34 INFO - TEST-INFO | 4326: exit 0 08:15:34 INFO - TEST-OK | kraken | took 58434ms 08:15:34 INFO - SUITE-END | took 1166s 08:15:34 INFO - Completed test suite (00:19:26) 08:15:34 INFO - javascript benchmark 08:15:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9427.210165942515, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [4018.2566225248333, 4043.353063509838, 4057.431959525666, 4071.0840060986284, 4092.7339722918027, 6563.343665633434, 6565.080888675583, 6635.369639884209, 6668.333166683336, 6688.732450701966, 6585.736570537185, 6652.700628471718, 6668.199816022072, 6683.398494135527, 6716.832079198022, 5927.347991720929, 5937.910931336012, 6014.097885317205, 6025.3372129065765, 6059.454649081582, 5929.407059294079, 5996.460318571328, 5999.730012149442, 6090.634561926298, 6098.054801505774, 6059.212302400704, 6097.878042439149, 6102.72537735803, 6121.602095863755, 6142.84642883927, 6026.849328766794, 6048.032314829607, 6059.091136329542, 6106.48094911933, 6132.018876979707, 5881.9705901470215, 5910.734016969247, 5984.521238300926, 6014.699265036775, 6022.277326720821, 6576.243731970789, 6577.342265773432, 6583.835404114888, 6602.537822352438, 6639.834004149935, 7214.783556493314, 7280.781576552659, 7313.634318284117, 7315.597642129681, 7341.045664063638, 7248.963755181243, 7251.383632391253, 7266.091738532684, 7266.091738532684, 7354.043974283362, 6557.213134423852, 6683.030960510748, 6692.196936367618, 6764.019217213477, 6764.289749576273, 7354.374878135419, 7403.22266162056, 7403.333699967029, 7449.478536502501, 7450.739224127129, 7071.504994650325, 7077.186123595749, 7077.681504332214, 7150.713971441084, 7157.534760240672, 7137.96431017852, 7196.424286057103, 7242.058532390755, 7282.053333066773, 7291.052163218854, 7107.680154393065, 7125.501214914906, 7171.462140339496, 7188.676509557083, 7201.171865235565, 7158.0, 7179.605121718356, 7240.420766338681, 7271.200167981518, 7292.161401438902, 5315.946840531623, 5333.386660534011, 5406.405295417501, 5415.025295446857, 5415.945840541544, 4823.445303790038, 4836.371271734652, 4856.757162141878, 4866.270059491105, 4867.975660121677, 6671.63306018164, 6713.160854893138, 6765.221999470025, 6783.491238157158, 6823.38589526935, 7093.716251349992, 7115.686059165951, 7204.53170543913, 7260.818479537948, 7305.305995930378, 6700.899486507707, 6758.0, 6833.658317084126, 6840.0, 6851.040854280404, 6028.879422411527, 6058.091286307089, 6127.785527506516, 6130.233720784902, 6132.7546898124465, 7478.252174782587, 7482.027336446227, 7486.513376630502, 7581.090269167625, 7595.658195381221, 7065.187503437171, 7204.3155900189395, 7222.3860971817985, 7232.0598322217775, 7237.3486386225495, 7363.595002274712, 7436.553806771611, 7447.478676492594, 7451.850962980601, 7465.813354666177, 7112.50212485121, 7115.288471152843, 7196.10048743907, 7201.459890508128, 7217.314355136254, 7253.601051941982, 7279.308465695751, 7295.197528271992, 7361.595112268877, 7380.298871607189, 6880.0, 7008.123984501937, 7033.648317584202, 7098.219195888345, 7108.609026503592, 6968.0, 6988.6505674715045, 7000.0, 7000.404965577983, 7003.684834182576, 6905.827354316182, 6949.0, 7000.50996430245, 7007.754728584475, 7007.964960175166, 7046.718131274692, 7068.222495525591, 7070.823229419306, 7147.714091436493, 7174.892376614459, 6764.255931847394, 6826.761063362758, 6833.077534532775, 6926.896096558657, 6934.0], "value": 6618.858977407497, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [887.767091692415, 894.6209437856852, 900.2033200617453, 924.3437159616672, 934.4066517761224, 46888.76555617221, 47367.28949065767, 47588.52411475884, 47612.52387476128, 47654.0469190616, 47684.523154768445, 48786.51213487864, 48793.0, 48833.26750098742, 48888.2666759998, 8885.777855553599, 8940.06129356418, 8970.775730606754, 9000.27997760181, 9007.099290071006, 8851.114888511129, 8943.910560894405, 8946.23956963659, 9002.54987250638, 9005.414648047888, 9017.909820901805, 9023.278137748965, 9066.274698024175, 9070.229030532413, 9076.36465447419, 8859.0, 8954.462732236087, 8959.507227102507, 8989.955050224773, 8999.550022498848, 8880.955595222047, 8918.464892106495, 8951.731448056504, 8961.686340978034, 8961.924569051691, 42560.14879702389, 43128.353074703904, 43129.13741725179, 43190.35214471786, 43227.35158972618, 3880.825362858678, 3925.1953349563555, 3925.60743925608, 3929.214157168578, 3929.76421414716, 35644.64355356465, 35735.0, 35758.46362304541, 35770.56917723314, 35859.64140358589, 5672.149177623348, 5764.8847023059725, 5796.855078623026, 5810.128480727883, 5810.8256752297075, 220274.0, 225384.99999999837, 226141.0, 231307.0, 234716.82641586853, 56147.71926140343, 56337.154942675894, 56448.43551564432, 56478.0, 56493.43506564964, 58127.0, 58152.418475815546, 58162.0, 58170.41829581735, 58206.417935820944, 15859.841401586067, 15991.920040399724, 16025.439109631106, 16090.356385744673, 16145.677086458205, 12905.677358065936, 12918.095733298767, 12925.289109098901, 12933.935330323477, 12940.417681204368, 14745.483908063381, 15043.849561504245, 15059.247037648074, 15064.171470569005, 15271.236438178046, 5475.016116216671, 5492.945070549244, 5497.340319161726, 5583.497485226349, 5637.633553819071, 1120.2102517724984, 1127.0194930410596, 1128.328644456547, 1128.7968165730083, 1131.6095946898306, 26273.73726262751, 26317.73682263153, 26376.60435093475, 26947.326316842235, 26974.460510789675, 1228.969275768095, 1235.425527129889, 1236.394166858251, 1236.7526494701096, 1240.3612139748036, 13915.678010588977, 14123.71752564943, 14192.219427931357, 14229.78655320171, 14265.21541315217, 293675.0, 294406.0, 294723.0, 303048.0, 308112.0, 64029.67985160137, 64431.67784161049, 64439.0, 64452.0, 64489.0, 1529.793477880494, 1538.7691846222933, 1539.8075240594926, 1560.9531714048596, 1564.9921750391175, 30492.390152197277, 30543.54184687276, 30638.540421894137, 30668.0, 30770.692293076783, 43558.0, 43663.56336436595, 43715.78142109269, 43754.34368484539, 43806.34290485709, 250178.0, 250725.0, 250961.0, 251920.0, 252524.0, 38261.808690956364, 38631.80684096562, 38634.42048369333, 38646.2270754589, 38743.41884871673, 47856.282155768386, 49490.0, 49555.50444495509, 50083.0, 50126.49873501364, 36246.818765906, 36270.63729362673, 36408.27183456369, 36732.81633591815, 36961.0, 37008.25983480369, 37069.62930370662, 37348.81325593355, 37449.43825842669, 37663.0, 42555.148897022504, 42752.786236069864, 42901.14197716091, 43042.0, 43165.568344316154, 35268.64731352653, 35482.41974666877, 35766.0, 35773.46339804957, 36184.457233140995, 26460.735392645827, 26587.202383928514, 26643.866780665972, 26741.33146671271, 27067.72932270731], "value": 19551.580201241963, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.9662297624913, 643.2911479031933, 648.7936185527363, 653.9363083951687, 654.4554850552696, 24369.147079852188, 25022.624660630107, 25095.247142585707, 25125.12062077823, 25187.0, 27685.0, 27915.16254512368, 27940.720592794067, 27972.3006924827, 27997.86001069997, 6434.260060093079, 6833.58998460094, 6876.312368763109, 6881.827954301132, 6898.103246577938, 6802.829929251783, 6914.412274956635, 6915.343042410988, 6915.412189963859, 6925.099737034178, 6615.0, 6878.0, 6878.27778083299, 6897.586144831301, 6898.724051037978, 6848.1097457330725, 6891.517593768438, 6893.310668933092, 6895.5173137880365, 6939.444844412435, 6431.163948686688, 6436.613803171825, 6453.645049522274, 6468.1268028815975, 6480.351964803529, 30510.237244068852, 31438.214044648837, 31831.363372732416, 31981.0, 32126.839365803255, 2362.4330160761465, 2413.444907671228, 2414.3360575841784, 2418.625113107471, 2418.7944024757917, 20707.5858482831, 21209.151633934624, 21329.893350533304, 21334.146634134617, 21337.89331053335, 2483.192962287264, 2488.0, 2536.708278547954, 2542.3898264416594, 2550.528152291832, 144877.0, 148855.0, 160787.0, 164870.0, 166299.0, 16378.181090945405, 16521.83478165227, 16837.41069062577, 17117.743233851503, 17221.827781722022, 27162.0, 27172.320691982462, 27202.047928322412, 27326.316842079108, 27370.45259094807, 10969.067629251604, 11013.83479247812, 11069.114470842313, 11075.16936229786, 11187.049100826522, 10120.190384769201, 10133.847992280122, 10146.137578305927, 10169.440680762482, 10213.693589192337, 10082.848757268646, 10134.746631334276, 10297.12474439666, 10329.74175645615, 10356.482175891175, 3122.703343182394, 3171.682831716856, 3182.7294679952465, 3184.1084496341064, 3243.2702641905385, 1067.4769363012224, 1068.9625863094755, 1094.2449709700281, 1097.8353247013013, 1097.8902109788958, 21812.563748724937, 21900.561988760135, 22025.889870550545, 22030.669539956914, 22100.88949555242, 1131.2025022359217, 1139.6921234500203, 1141.8173092305192, 1146.9025132863633, 1153.3944679043568, 11748.70628234301, 12219.389030548436, 12243.265404075784, 12306.323152226534, 12331.321777302155, 651400.0, 652936.0, 659105.0, 665779.0, 686982.0, 133406.0, 136584.0, 141638.2918085403, 141663.2916835409, 141676.0, 1249.419020155632, 1265.7531781302557, 1294.210531575757, 1294.9287789171497, 1299.6555912683148, 19271.22915083437, 19362.90318548398, 19456.416307510797, 19491.220351185795, 19640.312589059315, 63406.048909265475, 63790.3620963803, 63827.68086159539, 65082.0, 66594.0, 83752.58123709343, 84037.57981210055, 84792.15207847842, 84904.0, 86065.56967215124, 16497.09265990382, 16530.66938661196, 16538.173091345627, 16625.91687041557, 16634.833651663328, 24479.38801529976, 24717.25848224484, 24854.254372368858, 24881.129160479297, 24963.875180624706, 20312.187512499335, 20317.59364812725, 20417.183312667326, 20424.285150019678, 20582.38252852417, 18088.909555452137, 18186.545336366697, 18300.450986470427, 18394.448166555027, 18651.0, 19359.3224237151, 20671.79328206759, 20739.792602073787, 20829.687554686996, 20855.8957205213, 16320.0, 16351.836481635031, 16413.917930410273, 16423.768217383506, 16427.260773264992, 20067.59864802725, 20174.697379539026, 20197.19211231593, 20556.0, 20585.588288233852], "value": 13316.25197429122, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.512265829618, 1508.8038554987857, 1511.6145382927352, 1521.4218596933163, 1524.3445318513036, 17477.56306092347, 18033.458996230132, 18048.45854624362, 18225.0887455627, 18234.361797337097, 17661.293548258087, 17833.643327133483, 17851.732224016618, 17860.732089018642, 17861.10694465278, 3112.3464072544793, 3137.231378312317, 3182.10900947735, 3185.410699020677, 3195.872165113393, 3116.0, 3141.3560220154814, 3151.243701511632, 3154.511050787132, 3155.5108958111527, 3057.1287183152804, 3117.9688203117908, 3130.20179854138, 3141.732952699012, 3148.590683211191, 3020.3355261842357, 3042.4371491274187, 3045.2082458560935, 3058.4036112958092, 3058.72471477567, 3000.744936680385, 3017.818930864133, 3021.1993821637284, 3021.969780302191, 3032.181311046027, 16833.242504087346, 16988.15059247045, 17118.31526738929, 17242.0, 17318.740218896728, 8168.0, 8451.957740211321, 8481.321494280442, 8496.277816385615, 8513.914860851373, 5688.715564221772, 5715.628484148517, 5990.820275391745, 5998.0, 6050.334463209044, 2138.134055707441, 2161.7513985891665, 2162.7837216278404, 2165.177232651598, 2208.1608988584394, 28617.427651447062, 29597.55603665947, 31254.687453125403, 32167.356652866813, 32196.678033219836, 8212.794680132925, 8254.628541715638, 8310.792230194293, 8410.074891761902, 8473.406861519758, 23870.880645596662, 23970.161044363365, 24083.518329633658, 24108.758912410653, 24114.39714007164, 8846.734597962071, 9057.68298109563, 9070.54647267634, 9131.77170570741, 9136.771580710536, 7650.349720273728, 7848.7645370638975, 7936.087349954829, 7992.480488768211, 7994.2805147537, 6463.06285588587, 6501.609903405811, 6525.3800888915475, 6624.635645039569, 6831.146106736658, 2113.281484295347, 2124.341454149219, 2125.288028510435, 2125.5005073807642, 2137.294692751388, 983.2772911909741, 983.537737263485, 983.6704703924265, 1012.3166862367872, 1019.8827134879584, 8294.917050829536, 8392.244697977212, 8405.579721013924, 8507.447015943944, 8513.318934485227, 784.705735349244, 813.1461964936793, 815.8409110223449, 817.8631701934316, 828.4242451496273, 4805.918668299604, 4864.127186516364, 4940.110780059552, 4964.032013757283, 5012.899742005213, 25239.62140567893, 26304.473910521683, 26575.734242657327, 26600.467990640078, 26696.59955100675, 24858.875705621354, 25895.093671721777, 25919.22242332733, 26276.605850912252, 26359.47281054368, 11022.614208502664, 11263.211575189822, 12233.755324893453, 12790.808137877939, 12976.221426714428, 9630.32587718853, 9639.132478077006, 9646.035396460298, 9776.0, 9828.4102953823, 21818.781812181674, 21978.340649780534, 22410.215642452436, 22590.0, 22988.275879309454, 23131.537369252856, 23286.65070023917, 23417.882910585337, 23735.762642373356, 23982.640260396456, 13381.598552043455, 13610.319484025957, 13625.659358516117, 13629.318534073058, 13669.931650341685, 15929.601759956095, 15979.121148336953, 16016.0, 16124.0, 16193.75709364384, 12749.6175114743, 12799.10406271552, 12869.35653217354, 13065.673358166121, 13254.337283135997, 12707.36463176856, 12710.682232944251, 12745.299008554619, 12804.551840685905, 13095.869041309847, 11123.0, 11153.330800152016, 11237.38194399316, 11303.943480282545, 11323.15076369259, 7924.0, 7944.602769861599, 7980.680772769024, 7981.082175549654, 8064.475809072392, 8514.574271286534, 8550.700725474579, 8574.31405487572, 8638.611262493328, 8656.48061116335], "value": 7635.851674130136, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.124341648736, 582.935877053524, 584.1179818474103, 607.5139888089528, 614.4009590649117, 6283.183186185789, 6422.454091402231, 6429.903551446726, 6473.579217350881, 6508.609483430998, 6252.437280644741, 6350.301466838644, 6358.205224346957, 6367.458766004884, 6423.068655045027, 5283.630145889799, 5317.46825317469, 5318.228856815779, 5336.332958380202, 5337.839864804062, 4998.875028124289, 5202.141646628302, 5247.107991641411, 5254.369475662926, 5291.3650361956425, 5094.898102037957, 5117.692938423688, 5131.435542090367, 5132.820351287705, 5135.794568217267, 5079.695218286896, 5091.261767043758, 5109.259157422191, 5131.819681473282, 5134.127198376266, 4594.724316541018, 4633.745144017078, 4652.325412815157, 4660.324252983299, 4682.344471773954, 6246.906296405557, 6248.96875515624, 6290.150829638032, 6303.716332765037, 6308.148399966038, 5950.226470558843, 5977.760889564455, 6021.729022194012, 6024.277086749618, 6031.728572214261, 6631.635260060695, 6684.866302673968, 6767.763128290486, 6770.932290677079, 6787.219469760942, 4998.775055122528, 5193.922091168635, 5214.296070030536, 5224.686518808883, 5242.344706911636, 6729.25978142404, 6917.204521480044, 6949.0, 6981.441484681264, 7055.329743674394, 5328.280682107943, 5344.812931547377, 5349.786008559614, 5371.892562148735, 5388.973055134699, 5410.107332290128, 5424.349078110652, 5426.75579598919, 5430.647007944471, 5443.564514838803, 5951.791687290924, 5973.551983601247, 5993.61041532299, 5994.610350327302, 6020.939790602038, 6805.625690587065, 6812.48906332027, 6834.521583489108, 6841.486888483384, 6849.938259569774, 1865.2818664814145, 1865.9720104198448, 1873.484791682271, 1881.6048629787624, 1888.8772229805018, 1298.2664794391328, 1321.6497628128554, 1322.6891680455085, 1342.9261390623415, 1343.43575698207, 6289.7484100636375, 6297.842553936188, 6312.305646378894, 6330.873382532415, 6345.270293916166, 6629.569078009914, 6698.96650516754, 6721.798346049626, 6725.226598941086, 6739.797806065826, 6177.69111544421, 6212.689365531705, 6261.843453913598, 6359.491240700733, 6377.394147555975, 3561.893143205708, 3611.3138503684218, 3613.855445782192, 3619.8371073301764, 3637.1270894985014, 7061.788146355618, 7096.822579435556, 7136.393406560501, 7162.713491460283, 7164.92835071653, 6969.606078784162, 7072.3634872861685, 7087.82280442993, 7098.574085554883, 7119.181294151135, 7012.966239790404, 7013.368796808311, 7014.824629384306, 7054.08296921397, 7093.255208203168, 6756.358145976124, 6772.864542709217, 6780.627065511444, 6787.913933770573, 6800.251972283144, 6745.966270168814, 6759.324067593201, 6765.0, 6803.115594972622, 6826.8634627308165, 7005.474589405917, 7068.29317068289, 7101.680424380812, 7110.395616372666, 7126.465515086285, 5231.790728370823, 5273.6572122811895, 5318.4149743527405, 5358.946410535845, 5398.3791863934575, 6513.934860651522, 6597.746428178631, 6850.561382109613, 6853.280405557344, 6864.828379290558, 6653.434458071119, 6688.899666504909, 6716.261211266659, 6745.291744366967, 6767.492438067263, 6622.63575503352, 6637.535372523877, 6665.733370665119, 6688.398044176048, 6694.59832410057, 6321.11504389395, 6443.420092191725, 6534.673266336569, 6609.603423794395, 6707.262201157771, 6616.636085015178, 6700.430463410665, 6729.394354508117, 6758.662066896733, 6808.489363297674, 6432.067350234096, 6559.934400655932, 6597.0764093026055, 6612.834679132868, 6629.3039230882], "value": 5290.506163021142, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1003.0671475527756, 1019.0980981831079, 1037.5279247942187, 1040.1210976724665, 1049.1606714628294, 21077.367678969636, 21579.892100539495, 21699.3490195294, 21758.0, 21764.673529897063, 17287.049212293317, 17550.0, 19439.0, 20395.286164984227, 20520.076596553154, 1057.9523921423536, 1093.1418836213575, 1093.4532733633184, 1095.780843831233, 1098.1050443888246, 1072.3244356055675, 1105.8396532502782, 1107.0, 1109.5894519027931, 1118.8937050980144, 1175.153889199779, 1183.7928362536522, 1184.8696643369221, 1185.2296007595046, 1189.3636904256225, 1181.533294348736, 1186.4898093819654, 1186.7211205366732, 1187.1511869013655, 1191.1185722565326, 870.3472395703222, 872.3718922375879, 872.5768002518773, 872.7425409504211, 875.6891303587262, 21220.257290994894, 21352.893235533877, 21504.354869353945, 21655.0, 22141.778582214134, 23374.53250934972, 23405.41486462835, 23543.4114147146, 23840.284791456284, 23851.52296954051, 19441.708374374397, 19798.307059253002, 19818.0, 19946.70079948802, 19971.50071248216, 4161.583841615814, 4200.6219440250525, 4205.537390887, 4218.8523401680795, 4220.556841531626, 157214.21392892962, 165994.1700291503, 167719.1614041934, 167968.0, 169018.0, 18162.182701778453, 18294.45116646476, 18306.63386732258, 18337.541561461072, 18357.63284734298, 34838.65161348405, 35717.28565428729, 35971.640283596826, 36007.4598881017, 36042.45936310958, 8947.3736838422, 9097.499637519868, 9103.271738260923, 9128.406653567497, 9131.041240669634, 5033.245013247969, 5075.822346217865, 5092.898142037213, 5104.846854594368, 5105.2086926526445, 6165.10605962133, 6175.59858609189, 6224.284207316126, 6237.937620623827, 6260.0, 1063.1547919404093, 1076.6608518316705, 1085.4030283344287, 1089.4171618184391, 1097.040089921319, 770.6878714120791, 772.8454309138195, 791.9247671471201, 796.016919104905, 800.4156965415164, 12929.612111636667, 13038.478460861648, 13750.862491374959, 14330.283485825667, 14369.56891293263, 730.0983285642228, 736.9742059027908, 738.7303634173488, 739.7152096442908, 758.7268583310004, 6005.849853753691, 6080.118382834553, 6128.080787881764, 6179.938200618026, 6241.313455519889, 152652.0, 157305.0, 157964.0, 159282.0, 159437.0, 139661.30169349292, 139801.0, 142033.0, 144636.0, 145902.0, 120897.0, 121173.39413303054, 121998.0, 123482.38258808649, 127884.36057819652, 11512.36681982499, 11542.422878855856, 11658.417079146178, 11675.416229188337, 11676.0, 58877.0, 59363.40636593752, 59507.404925951916, 59891.70054149701, 60040.972371064694, 75365.6231718838, 76495.0, 77457.61271193608, 79050.60474697589, 79543.20456795358, 15431.537053888402, 15515.534533963999, 15759.921200394383, 15904.681906362039, 15921.601959951095, 17450.9127454367, 17744.822551774832, 17838.19728112212, 17964.461066167514, 18098.457046288633, 17300.65398691994, 17548.473545793648, 17572.472825814733, 17788.46634600964, 17793.110344482982, 16944.74582881233, 16973.830261697225, 17019.659606807545, 17066.31734730597, 17161.914190428968, 16297.022178669318, 16356.754648680517, 16542.255598498337, 16685.16574171311, 16754.0, 11997.820032699341, 12122.939385303018, 12192.878071219175, 12450.501979920702, 12468.940140088194, 12165.391730413266, 12172.75654486923, 12311.938440307742, 12409.131360804657, 12433.191842530206], "value": 10082.572766105306, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1365.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 104.0, 107.0, 106.0, 108.0, 109.0, 105.0, 109.0, 109.0, 106.0], "value": 106.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 113.0, 112.0, 110.0, 111.0, 139.0, 109.0, 123.0, 111.0], "value": 115.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 162.0, 163.0, 163.0, 162.0, 162.0, 162.0, 163.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 73.0, 74.0, 76.0, 73.0, 73.0, 73.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 77.0, 78.0, 81.0, 81.0, 80.0, 85.0, 80.0, 82.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 114.0, 115.0, 115.0, 115.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 149.0, 149.0, 149.0, 148.0, 147.0, 148.0, 148.0, 157.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 99.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 44.0, 40.0, 41.0, 42.0, 42.0, 43.0, 42.0, 44.0, 44.0], "value": 42.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 38.0, 38.0, 38.0, 37.0, 37.0, 39.0, 38.0, 38.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 69.0, 70.0, 72.0, 71.0, 72.0, 70.0, 69.0, 73.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 103.0, 107.0, 108.0, 101.0, 107.0, 105.0, 101.0, 104.0, 103.0], "value": 104.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 152.0, 154.0, 165.0, 153.0, 147.0, 159.0, 150.0, 151.0, 155.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 60.0, 60.0, 59.0, 58.0, 58.0, 66.0, 59.0, 59.0], "value": 60.4, "unit": "score"}]}]} 08:15:34 INFO - Return code: 0 08:15:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:15:34 INFO - # TBPL SUCCESS # 08:15:34 INFO - Running post-action listener: _package_coverage_data 08:15:34 INFO - Running post-action listener: _resource_record_post_action 08:15:34 INFO - [mozharness: 2017-09-21 15:15:34.598156Z] Finished run-tests step (success) 08:15:34 INFO - Running post-run listener: _resource_record_post_run 08:15:34 INFO - Total resource usage - Wall time: 1182s; CPU: 13.0%; Read bytes: 9224192; Write bytes: 402321408; Read time: 2268; Write time: 296368 08:15:34 INFO - TinderboxPrint: CPU usage
13.0% 08:15:34 INFO - TinderboxPrint: I/O read bytes / time
9,224,192 / 2,268 08:15:34 INFO - TinderboxPrint: I/O write bytes / time
402,321,408 / 296,368 08:15:34 INFO - TinderboxPrint: CPU idle
8,196.1 (87.0%) 08:15:34 INFO - TinderboxPrint: CPU user
1,178.1 (12.5%) 08:15:34 INFO - TinderboxPrint: Swap in / out
0 / 0 08:15:34 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 323584; Read time: 0; Write time: 40 08:15:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:15:34 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 8986624; Write bytes: 329191424; Read time: 2040; Write time: 247588 08:15:35 INFO - Running post-run listener: _upload_blobber_files 08:15:35 INFO - Blob upload gear active. 08:15:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:15:35 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 08:15:35 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:15:35 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:15:35 INFO - (blobuploader) - INFO - Open directory for files ... 08:15:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:15:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:15:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:15:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:15:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:15:36 INFO - (blobuploader) - INFO - Done attempting. 08:15:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:15:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:15:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:15:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:15:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:15:44 INFO - (blobuploader) - INFO - Done attempting. 08:15:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:15:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:15:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:15:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:15:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:15:45 INFO - (blobuploader) - INFO - Done attempting. 08:15:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:15:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:15:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:15:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:15:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:15:46 INFO - (blobuploader) - INFO - Done attempting. 08:15:46 INFO - (blobuploader) - INFO - Iteration through files over. 08:15:46 INFO - Return code: 0 08:15:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:15:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:15:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1090b8f58b3c31e33e70273dd2b716e811337242c35e73a2fb89317b20decb0dc6051a9872ee9475e6e623f7dbb8dcb745454958301b33deaa46ef2920bd465a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dd0fd3b499bd24cee6813971e5d0d337ce5549b6aee3c999dd36b746626d619dfb79b5b836240df8c7e5bfd885b914da118a10041c88ad62fd416a7cfae62e5f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f4287a4f462b84411b2c55f67ac88b939f218b2645b6027fdfa8208f1a3dc40ee393e969d23ff5e6cc4854d580ad64b4ad7efab8e8140b90f26ed8e8ca530b3a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c3fc84f4d0449e017ed7aa6608580289706f62ade3424683e1f6aa547dabcb26faf36c6a74791851090cdf1d4dbce9af042459e97acb98fd2ccb7d25a22fb228"} 08:15:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:15:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:15:46 INFO - Contents: 08:15:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1090b8f58b3c31e33e70273dd2b716e811337242c35e73a2fb89317b20decb0dc6051a9872ee9475e6e623f7dbb8dcb745454958301b33deaa46ef2920bd465a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dd0fd3b499bd24cee6813971e5d0d337ce5549b6aee3c999dd36b746626d619dfb79b5b836240df8c7e5bfd885b914da118a10041c88ad62fd416a7cfae62e5f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f4287a4f462b84411b2c55f67ac88b939f218b2645b6027fdfa8208f1a3dc40ee393e969d23ff5e6cc4854d580ad64b4ad7efab8e8140b90f26ed8e8ca530b3a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c3fc84f4d0449e017ed7aa6608580289706f62ade3424683e1f6aa547dabcb26faf36c6a74791851090cdf1d4dbce9af042459e97acb98fd2ccb7d25a22fb228"} 08:15:46 INFO - Running post-run listener: copy_logs_to_upload_dir 08:15:46 INFO - Copying logs to upload dir... 08:15:46 INFO - mkdir: /builds/slave/test/build/upload/logs 08:15:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1298.861138 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2017-09-21 08:15:46.429436) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 08:15:46.433218) ========= 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=923d835db2ba3686ebe126b700000192-1506005577.607800-164198643 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1090b8f58b3c31e33e70273dd2b716e811337242c35e73a2fb89317b20decb0dc6051a9872ee9475e6e623f7dbb8dcb745454958301b33deaa46ef2920bd465a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dd0fd3b499bd24cee6813971e5d0d337ce5549b6aee3c999dd36b746626d619dfb79b5b836240df8c7e5bfd885b914da118a10041c88ad62fd416a7cfae62e5f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f4287a4f462b84411b2c55f67ac88b939f218b2645b6027fdfa8208f1a3dc40ee393e969d23ff5e6cc4854d580ad64b4ad7efab8e8140b90f26ed8e8ca530b3a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c3fc84f4d0449e017ed7aa6608580289706f62ade3424683e1f6aa547dabcb26faf36c6a74791851090cdf1d4dbce9af042459e97acb98fd2ccb7d25a22fb228"} build_url:https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011138 build_url: 'https://queue.taskcluster.net/v1/task/AWIRF7hySD62YZ9nvxMw9Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1090b8f58b3c31e33e70273dd2b716e811337242c35e73a2fb89317b20decb0dc6051a9872ee9475e6e623f7dbb8dcb745454958301b33deaa46ef2920bd465a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dd0fd3b499bd24cee6813971e5d0d337ce5549b6aee3c999dd36b746626d619dfb79b5b836240df8c7e5bfd885b914da118a10041c88ad62fd416a7cfae62e5f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f4287a4f462b84411b2c55f67ac88b939f218b2645b6027fdfa8208f1a3dc40ee393e969d23ff5e6cc4854d580ad64b4ad7efab8e8140b90f26ed8e8ca530b3a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c3fc84f4d0449e017ed7aa6608580289706f62ade3424683e1f6aa547dabcb26faf36c6a74791851090cdf1d4dbce9af042459e97acb98fd2ccb7d25a22fb228"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 08:15:46.467558) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 08:15:46.468165) ========= 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=923d835db2ba3686ebe126b700000192-1506005577.607800-164198643 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 08:15:46.553263) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 08:15:46.553841) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 08:15:46.554428) ========= ========= Total master_lag: 0.26 =========