builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-027 starttime: 1504653474.58 results: success (0) revision: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:54.582852) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:54.583284) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:54.583561) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:54.601166) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:54.601567) ========= 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=1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-05 16:17:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2017-09-05 16:17:54 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.159151 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:54.777631) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:54.780990) ========= 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=1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015391 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:54.830801) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:54.834178) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 --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=1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-05 16:17:55,088 truncating revision to first 12 chars 2017-09-05 16:17:55,088 Setting DEBUG logging. 2017-09-05 16:17:55,088 attempt 1/10 2017-09-05 16:17:55,089 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f64e2b4dcf5e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-05 16:17:55,544 unpacking tar archive at: mozilla-central-f64e2b4dcf5e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.857120 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:55.709622) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:55.710047) ========= script_repo_revision: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:55.710445) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:55.710722) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 16:17:55.723854) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-05 16:17:55.724220) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:17:56 INFO - MultiFileLogger online at 20170905 16:17:56 in /builds/slave/test 16:17:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:17:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:17:56 INFO - {'append_to_log': False, 16:17:56 INFO - 'base_work_dir': '/builds/slave/test', 16:17:56 INFO - 'blob_upload_branch': 'Firefox', 16:17:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:17:56 INFO - 'branch': 'Firefox', 16:17:56 INFO - 'buildbot_json_path': 'buildprops.json', 16:17:56 INFO - 'code_coverage': False, 16:17:56 INFO - 'config_files': ('talos/linux_config.py',), 16:17:56 INFO - 'default_actions': ('clobber', 16:17:56 INFO - 'read-buildbot-config', 16:17:56 INFO - 'download-and-extract', 16:17:56 INFO - 'populate-webroot', 16:17:56 INFO - 'create-virtualenv', 16:17:56 INFO - 'install', 16:17:56 INFO - 'setup-mitmproxy', 16:17:56 INFO - 'run-tests'), 16:17:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:17:56 INFO - 'disable_ccov_upload': False, 16:17:56 INFO - 'disable_stylo': False, 16:17:56 INFO - 'download_minidump_stackwalk': True, 16:17:56 INFO - 'download_symbols': 'ondemand', 16:17:56 INFO - 'enable_stylo': False, 16:17:56 INFO - 'enable_webrender': False, 16:17:56 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:17:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:17:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:17:56 INFO - 'gecko_profile': False, 16:17:56 INFO - 'gecko_profile_interval': 0, 16:17:56 INFO - 'installer_path': 'installer.exe', 16:17:56 INFO - 'log_level': 'info', 16:17:56 INFO - 'log_name': 'talos', 16:17:56 INFO - 'log_to_console': True, 16:17:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:17:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:17:56 INFO - 'opt_config_files': (), 16:17:56 INFO - 'pip_index': False, 16:17:56 INFO - 'suite': 'dromaeojs-e10s', 16:17:56 INFO - 'system_bits': '32', 16:17:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:17:56 INFO - 'title': 'talos-linux64-ix-027', 16:17:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:17:56 INFO - 'use_talos_json': True, 16:17:56 INFO - 'verify': 'False', 16:17:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:17:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:17:56 INFO - 'work_dir': 'build'} 16:17:56 INFO - [mozharness: 2017-09-05 23:17:56.115334Z] Running clobber step. 16:17:56 INFO - Running pre-action listener: _resource_record_pre_action 16:17:56 INFO - Running main action method: clobber 16:17:56 INFO - rmtree: /builds/slave/test/build 16:17:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:17:57 INFO - Running post-action listener: _resource_record_post_action 16:17:57 INFO - [mozharness: 2017-09-05 23:17:57.204879Z] Finished clobber step (success) 16:17:57 INFO - [mozharness: 2017-09-05 23:17:57.204976Z] Running read-buildbot-config step. 16:17:57 INFO - Running pre-action listener: _resource_record_pre_action 16:17:57 INFO - Running main action method: read_buildbot_config 16:17:57 INFO - Using buildbot properties: 16:17:57 INFO - { 16:17:57 INFO - "project": "", 16:17:57 INFO - "product": "firefox", 16:17:57 INFO - "who": "archaeopteryx@coole-files.de", 16:17:57 INFO - "installer_path": "public/build/target.tar.bz2", 16:17:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:17:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:17:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:17:57 INFO - "stage_platform": "linux64-pgo", 16:17:57 INFO - "basedir": "/builds/slave/test", 16:17:57 INFO - "buildnumber": 140, 16:17:57 INFO - "platform": "ubuntu64_hw", 16:17:57 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:17:57 INFO - "slavebuilddir": "test", 16:17:57 INFO - "taskId": "a4mXZuh6Su2kNJIny9e5kA", 16:17:57 INFO - "branch": "mozilla-central", 16:17:57 INFO - "script_repo_revision": "production", 16:17:57 INFO - "revision": "f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4", 16:17:57 INFO - "slavename": "talos-linux64-ix-027", 16:17:57 INFO - "repo_path": "mozilla-central" 16:17:57 INFO - } 16:17:57 INFO - Finding installer, test and symbols from parent task. 16:17:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a4mXZuh6Su2kNJIny9e5kA'}, attempt #1 16:17:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a4mXZuh6Su2kNJIny9e5kA'}, attempt #1 16:17:58 INFO - Task dependencies: MjZZTm4hRByt6qM6fPeTvw 16:17:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw'}, attempt #1 16:17:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.tar.bz2 16:17:58 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.test_packages.json 16:17:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.crashreporter-symbols.zip 16:17:58 INFO - Running post-action listener: _resource_record_post_action 16:17:58 INFO - [mozharness: 2017-09-05 23:17:58.548711Z] Finished read-buildbot-config step (success) 16:17:58 INFO - [mozharness: 2017-09-05 23:17:58.548924Z] Running download-and-extract step. 16:17:58 INFO - Running pre-action listener: _resource_record_pre_action 16:17:58 INFO - Running main action method: download_and_extract 16:17:58 INFO - mkdir: /builds/slave/test/build/tests 16:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:58 INFO - trying https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.test_packages.json 16:17:58 INFO - Downloading https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:17:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:17:59 INFO - Downloaded 989 bytes. 16:17:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:17:59 INFO - Using the following test package requirements: 16:17:59 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:17:59 INFO - u'common': [u'target.common.tests.zip'], 16:17:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:17:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:17:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:17:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:17:59 INFO - u'mozbase': [u'target.common.tests.zip'], 16:17:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:17:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:17:59 INFO - u'web-platform': [u'target.common.tests.zip', 16:17:59 INFO - u'target.web-platform.tests.tar.gz'], 16:17:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:17:59 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:17:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.common.tests.zip 16:17:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:17:59 INFO - Fetch https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.common.tests.zip into memory 16:18:00 INFO - Content-Length response header: 55683006 16:18:00 INFO - Bytes received: 55683006 16:18:10 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:18:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.common.tests.zip 16:18:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:18:10 INFO - Fetch https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.common.tests.zip into memory 16:18:12 INFO - Content-Length response header: 55683006 16:18:12 INFO - Bytes received: 55683006 16:18:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.talos.tests.zip 16:18:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:18:23 INFO - Fetch https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.talos.tests.zip into memory 16:18:24 INFO - Content-Length response header: 14496654 16:18:24 INFO - Bytes received: 14496654 16:18:24 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')]} 16:18:24 INFO - trying https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.tar.bz2 16:18:24 INFO - Downloading https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:18:26 INFO - Downloaded 63479193 bytes. 16:18:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.tar.bz2 16:18:26 INFO - mkdir: /builds/slave/test/properties 16:18:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:18:26 INFO - Writing to file /builds/slave/test/properties/build_url 16:18:26 INFO - Contents: 16:18:26 INFO - build_url:https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.tar.bz2 16:18:26 INFO - Running post-action listener: _resource_record_post_action 16:18:26 INFO - Running post-action listener: find_tests_for_verification 16:18:26 INFO - Running post-action listener: set_extra_try_arguments 16:18:26 INFO - [mozharness: 2017-09-05 23:18:26.064124Z] Finished download-and-extract step (success) 16:18:26 INFO - [mozharness: 2017-09-05 23:18:26.064221Z] Running populate-webroot step. 16:18:26 INFO - Running pre-action listener: _resource_record_pre_action 16:18:26 INFO - Running main action method: populate_webroot 16:18:26 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:18:26 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:18:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:18:26 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:18:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:18:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:26 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:18:26 INFO - 'talos_options': ['--enable-stylo'], 16:18:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:18:26 INFO - 'tests': ['damp', 'tps']}, 16:18:26 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:18:26 INFO - 'talos_options': ['--enable-stylo'], 16:18:26 INFO - 'tests': ['damp', 'tps']}, 16:18:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:18:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:18:26 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:26 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:18:26 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:18:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:18:26 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:18:26 INFO - 'talos_options': ['--enable-stylo'], 16:18:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:18:26 INFO - 'other-e10s': {'tests': ['a11yr', 16:18:26 INFO - 'ts_paint', 16:18:26 INFO - 'tpaint', 16:18:26 INFO - 'sessionrestore', 16:18:26 INFO - 'sessionrestore_many_windows', 16:18:26 INFO - 'sessionrestore_no_auto_restore', 16:18:26 INFO - 'tabpaint']}, 16:18:26 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:26 INFO - 'tests': ['a11yr', 16:18:26 INFO - 'ts_paint', 16:18:26 INFO - 'tpaint', 16:18:26 INFO - 'sessionrestore', 16:18:26 INFO - 'sessionrestore_many_windows', 16:18:26 INFO - 'sessionrestore_no_auto_restore', 16:18:26 INFO - 'tabpaint']}, 16:18:26 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:18:26 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:18:26 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:26 INFO - 'tests': ['bloom_basic_singleton']}, 16:18:26 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:26 INFO - 'tests': ['bloom_basic']}, 16:18:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:18:26 INFO - 'tsvgr_opacity', 16:18:26 INFO - 'tart', 16:18:26 INFO - 'tscrollx', 16:18:26 INFO - 'tsvg_static']}, 16:18:26 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:18:26 INFO - 'tests': ['tsvgx', 16:18:26 INFO - 'tsvgr_opacity', 16:18:26 INFO - 'tart', 16:18:26 INFO - 'tscrollx', 16:18:26 INFO - 'tsvg_static']}, 16:18:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:18:26 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:18:26 INFO - 'talos_options': ['--enable-stylo'], 16:18:26 INFO - 'tests': ['tp5o']}, 16:18:26 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:18:26 INFO - 'talos_options': ['--mitmproxy', 16:18:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:18:26 INFO - '--firstNonBlankPaint'], 16:18:26 INFO - 'tests': ['tp6_google', 16:18:26 INFO - 'tp6_youtube', 16:18:26 INFO - 'tp6_amazon', 16:18:26 INFO - 'tp6_facebook']}, 16:18:26 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:18:26 INFO - 'talos_options': ['--enable-stylo', 16:18:26 INFO - '--mitmproxy', 16:18:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:18:26 INFO - '--firstNonBlankPaint'], 16:18:26 INFO - 'tests': ['tp6_google', 16:18:26 INFO - 'tp6_youtube', 16:18:26 INFO - 'tp6_amazon', 16:18:26 INFO - 'tp6_facebook']}, 16:18:26 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:18:26 INFO - 'talos_options': ['--enable-stylo', 16:18:26 INFO - '--stylo-threads=1', 16:18:26 INFO - '--mitmproxy', 16:18:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:18:26 INFO - '--firstNonBlankPaint'], 16:18:26 INFO - 'tests': ['tp6_google', 16:18:26 INFO - 'tp6_youtube', 16:18:26 INFO - 'tp6_amazon', 16:18:26 INFO - 'tp6_facebook']}, 16:18:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:18:26 INFO - 'talos_options': ['--xperf_path', 16:18:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:26 INFO - 'tests': ['tp5n']}, 16:18:26 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:18:26 INFO - 'talos_options': ['--enable-stylo', 16:18:26 INFO - '--xperf_path', 16:18:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:26 INFO - 'tests': ['tp5n']}}} 16:18:26 INFO - Running post-action listener: _resource_record_post_action 16:18:26 INFO - [mozharness: 2017-09-05 23:18:26.078581Z] Finished populate-webroot step (success) 16:18:26 INFO - [mozharness: 2017-09-05 23:18:26.078669Z] Running create-virtualenv step. 16:18:26 INFO - Running pre-action listener: _resource_record_pre_action 16:18:26 INFO - Running main action method: create_virtualenv 16:18:26 INFO - Creating virtualenv /builds/slave/test/build/venv 16:18:26 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:18:26 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:18:26 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:18:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:18:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:18:26 INFO - Installing distribute.............................................................................................................................................................................................done. 16:18:26 INFO - Installing pip...............done. 16:18:26 INFO - Return code: 0 16:18:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:18:27 INFO - Reading from file tmpfile_stdout 16:18:27 INFO - Output received: 16:18:27 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:18:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb090>, '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 0x1fbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5f90>, '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': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', '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 16:18:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:18:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:18:27 INFO - Using env: {'DISPLAY': ':0', 16:18:27 INFO - 'HOME': '/home/cltbld', 16:18:27 INFO - 'LANG': 'en_US.UTF-8', 16:18:27 INFO - 'LANGUAGE': 'en_US:en', 16:18:27 INFO - 'LOGNAME': 'cltbld', 16:18:27 INFO - 'MAIL': '/var/mail/cltbld', 16:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:27 INFO - 'MOZ_NO_REMOTE': '1', 16:18:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:27 INFO - 'NO_EM_RESTART': '1', 16:18:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:27 INFO - 'PWD': '/builds/slave/test', 16:18:27 INFO - 'SHELL': '/bin/bash', 16:18:27 INFO - 'SHLVL': '1', 16:18:27 INFO - 'TERM': 'linux', 16:18:27 INFO - 'TMOUT': '86400', 16:18:27 INFO - 'USER': 'cltbld', 16:18:27 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:27 INFO - '_': '/tools/buildbot/bin/python'} 16:18:28 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:18:28 INFO - Downloading/unpacking pip>=1.5 16:18:28 INFO - Running setup.py egg_info for package pip 16:18:28 INFO - warning: no files found matching 'pip/cacert.pem' 16:18:28 INFO - warning: no files found matching '*.html' under directory 'docs' 16:18:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:18:28 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:18:28 INFO - Installing collected packages: pip 16:18:28 INFO - Found existing installation: pip 1.1 16:18:28 INFO - Uninstalling pip: 16:18:28 INFO - Successfully uninstalled pip 16:18:28 INFO - Running setup.py install for pip 16:18:28 INFO - warning: no files found matching 'pip/cacert.pem' 16:18:28 INFO - warning: no files found matching '*.html' under directory 'docs' 16:18:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:18:28 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:18:28 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:18:28 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:18:28 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:18:28 INFO - Successfully installed pip 16:18:28 INFO - Cleaning up... 16:18:28 INFO - Return code: 0 16:18:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:18:28 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')]} 16:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:28 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')]} 16:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:28 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 0x1c4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb090>, '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 0x1fbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5f90>, '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': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', '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 16:18:28 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 16:18:28 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 16:18:28 INFO - Using env: {'DISPLAY': ':0', 16:18:28 INFO - 'HOME': '/home/cltbld', 16:18:28 INFO - 'LANG': 'en_US.UTF-8', 16:18:28 INFO - 'LANGUAGE': 'en_US:en', 16:18:28 INFO - 'LOGNAME': 'cltbld', 16:18:28 INFO - 'MAIL': '/var/mail/cltbld', 16:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:28 INFO - 'MOZ_NO_REMOTE': '1', 16:18:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:28 INFO - 'NO_EM_RESTART': '1', 16:18:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:28 INFO - 'PWD': '/builds/slave/test', 16:18:28 INFO - 'SHELL': '/bin/bash', 16:18:28 INFO - 'SHLVL': '1', 16:18:28 INFO - 'TERM': 'linux', 16:18:28 INFO - 'TMOUT': '86400', 16:18:28 INFO - 'USER': 'cltbld', 16:18:28 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:28 INFO - '_': '/tools/buildbot/bin/python'} 16:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:28 INFO - Downloading/unpacking psutil>=3.1.1 16:18:28 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 16:18:28 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 16:18:28 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 16:18:28 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 16:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:18:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:29 INFO - Installing collected packages: psutil 16:18:29 INFO - Running setup.py install for psutil 16:18:29 INFO - building 'psutil._psutil_linux' extension 16:18:29 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 16:18:29 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 16:18:29 INFO - building 'psutil._psutil_posix' extension 16:18:29 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 16:18:30 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 16:18:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:30 INFO - Successfully installed psutil 16:18:30 INFO - Cleaning up... 16:18:30 INFO - Return code: 0 16:18:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:18:30 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')]} 16:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:30 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')]} 16:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:30 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 0x1c4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb090>, '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 0x1fbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5f90>, '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': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', '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 16:18:30 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 16:18:30 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 16:18:30 INFO - Using env: {'DISPLAY': ':0', 16:18:30 INFO - 'HOME': '/home/cltbld', 16:18:30 INFO - 'LANG': 'en_US.UTF-8', 16:18:30 INFO - 'LANGUAGE': 'en_US:en', 16:18:30 INFO - 'LOGNAME': 'cltbld', 16:18:30 INFO - 'MAIL': '/var/mail/cltbld', 16:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:30 INFO - 'MOZ_NO_REMOTE': '1', 16:18:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:30 INFO - 'NO_EM_RESTART': '1', 16:18:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:30 INFO - 'PWD': '/builds/slave/test', 16:18:30 INFO - 'SHELL': '/bin/bash', 16:18:30 INFO - 'SHLVL': '1', 16:18:30 INFO - 'TERM': 'linux', 16:18:30 INFO - 'TMOUT': '86400', 16:18:30 INFO - 'USER': 'cltbld', 16:18:30 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:30 INFO - '_': '/tools/buildbot/bin/python'} 16:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:18:30 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 16:18:30 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 16:18:30 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 16:18:30 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 16:18:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:18:31 INFO - Installing collected packages: mozsystemmonitor 16:18:31 INFO - Running setup.py install for mozsystemmonitor 16:18:31 INFO - Successfully installed mozsystemmonitor 16:18:31 INFO - Cleaning up... 16:18:31 INFO - Return code: 0 16:18:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:18: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')]} 16:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18: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')]} 16:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18: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', '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 0x1c4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb090>, '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 0x1fbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5f90>, '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': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', '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 16:18: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:18: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 jsonschema==2.5.1 16:18:31 INFO - Using env: {'DISPLAY': ':0', 16:18:31 INFO - 'HOME': '/home/cltbld', 16:18:31 INFO - 'LANG': 'en_US.UTF-8', 16:18:31 INFO - 'LANGUAGE': 'en_US:en', 16:18:31 INFO - 'LOGNAME': 'cltbld', 16:18:31 INFO - 'MAIL': '/var/mail/cltbld', 16:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:31 INFO - 'MOZ_NO_REMOTE': '1', 16:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:31 INFO - 'NO_EM_RESTART': '1', 16:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:31 INFO - 'PWD': '/builds/slave/test', 16:18:31 INFO - 'SHELL': '/bin/bash', 16:18:31 INFO - 'SHLVL': '1', 16:18:31 INFO - 'TERM': 'linux', 16:18:31 INFO - 'TMOUT': '86400', 16:18:31 INFO - 'USER': 'cltbld', 16:18:31 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:31 INFO - '_': '/tools/buildbot/bin/python'} 16:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:31 INFO - Downloading/unpacking jsonschema==2.5.1 16:18: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 16:18: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 16:18: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 16:18: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 16:18:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:18:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:18:33 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 16:18:33 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 16:18:33 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 16:18:33 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 16:18:33 INFO - Downloading functools32-3.2.3-2.tar.gz 16:18:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:18:33 INFO - warning: no files found matching '*.txt' 16:18:33 INFO - no previously-included directories found matching 'build' 16:18:33 INFO - no previously-included directories found matching 'dist' 16:18:33 INFO - no previously-included directories found matching '.git*' 16:18:33 INFO - Installing collected packages: jsonschema, functools32 16:18:33 INFO - Running setup.py install for functools32 16:18:33 INFO - warning: no files found matching '*.txt' 16:18:33 INFO - no previously-included directories found matching 'build' 16:18:33 INFO - no previously-included directories found matching 'dist' 16:18:33 INFO - no previously-included directories found matching '.git*' 16:18:33 INFO - Successfully installed jsonschema functools32 16:18:33 INFO - Cleaning up... 16:18:33 INFO - Return code: 0 16:18:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:18:33 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')]} 16:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:33 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')]} 16:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:33 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 0x1c4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb090>, '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 0x1fbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5f90>, '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': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', '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 16:18:33 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 16:18:33 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 16:18:33 INFO - Using env: {'DISPLAY': ':0', 16:18:33 INFO - 'HOME': '/home/cltbld', 16:18:33 INFO - 'LANG': 'en_US.UTF-8', 16:18:33 INFO - 'LANGUAGE': 'en_US:en', 16:18:33 INFO - 'LOGNAME': 'cltbld', 16:18:33 INFO - 'MAIL': '/var/mail/cltbld', 16:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:33 INFO - 'MOZ_NO_REMOTE': '1', 16:18:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:33 INFO - 'NO_EM_RESTART': '1', 16:18:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:33 INFO - 'PWD': '/builds/slave/test', 16:18:33 INFO - 'SHELL': '/bin/bash', 16:18:33 INFO - 'SHLVL': '1', 16:18:33 INFO - 'TERM': 'linux', 16:18:33 INFO - 'TMOUT': '86400', 16:18:33 INFO - 'USER': 'cltbld', 16:18:33 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:33 INFO - '_': '/tools/buildbot/bin/python'} 16:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:18:33 INFO - Cleaning up... 16:18:33 INFO - Return code: 0 16:18:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:18:33 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')]} 16:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:33 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')]} 16:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:33 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 0x1c4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb090>, '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 0x1fbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5f90>, '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': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', '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 16:18:33 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 16:18:33 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 16:18:33 INFO - Using env: {'DISPLAY': ':0', 16:18:33 INFO - 'HOME': '/home/cltbld', 16:18:33 INFO - 'LANG': 'en_US.UTF-8', 16:18:33 INFO - 'LANGUAGE': 'en_US:en', 16:18:33 INFO - 'LOGNAME': 'cltbld', 16:18:33 INFO - 'MAIL': '/var/mail/cltbld', 16:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:33 INFO - 'MOZ_NO_REMOTE': '1', 16:18:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:33 INFO - 'NO_EM_RESTART': '1', 16:18:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:33 INFO - 'PWD': '/builds/slave/test', 16:18:33 INFO - 'SHELL': '/bin/bash', 16:18:33 INFO - 'SHLVL': '1', 16:18:33 INFO - 'TERM': 'linux', 16:18:33 INFO - 'TMOUT': '86400', 16:18:33 INFO - 'USER': 'cltbld', 16:18:33 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:33 INFO - '_': '/tools/buildbot/bin/python'} 16:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:33 INFO - Downloading/unpacking blobuploader==1.2.4 16:18:33 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 16:18:33 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 16:18:33 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 16:18:33 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 16:18:34 INFO - Downloading blobuploader-1.2.4.tar.gz 16:18:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:18:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:18: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 16:18: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 16:18: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 16:18: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 16:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:18:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:18: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 16:18: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 16:18: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 16:18: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 16:18:35 INFO - Downloading docopt-0.6.1.tar.gz 16:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:18:35 INFO - Installing collected packages: blobuploader, requests, docopt 16:18:35 INFO - Running setup.py install for blobuploader 16:18:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:18:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:18:35 INFO - Running setup.py install for requests 16:18:35 INFO - Running setup.py install for docopt 16:18:36 INFO - Successfully installed blobuploader requests docopt 16:18:36 INFO - Cleaning up... 16:18:36 INFO - Return code: 0 16:18:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18: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')]} 16:18:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18: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')]} 16:18:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:36 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 0x1c4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb090>, '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 0x1fbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5f90>, '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': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', '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 16:18:36 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 16:18:36 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 16:18:36 INFO - Using env: {'DISPLAY': ':0', 16:18:36 INFO - 'HOME': '/home/cltbld', 16:18:36 INFO - 'LANG': 'en_US.UTF-8', 16:18:36 INFO - 'LANGUAGE': 'en_US:en', 16:18:36 INFO - 'LOGNAME': 'cltbld', 16:18:36 INFO - 'MAIL': '/var/mail/cltbld', 16:18:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:36 INFO - 'MOZ_NO_REMOTE': '1', 16:18:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:36 INFO - 'NO_EM_RESTART': '1', 16:18:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:36 INFO - 'PWD': '/builds/slave/test', 16:18:36 INFO - 'SHELL': '/bin/bash', 16:18:36 INFO - 'SHLVL': '1', 16:18:36 INFO - 'TERM': 'linux', 16:18:36 INFO - 'TMOUT': '86400', 16:18:36 INFO - 'USER': 'cltbld', 16:18:36 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:18:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:36 INFO - '_': '/tools/buildbot/bin/python'} 16:18:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:36 INFO - Running setup.py (path:/tmp/pip-i1V3k3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:36 INFO - Running setup.py (path:/tmp/pip-9On32r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:36 INFO - Running setup.py (path:/tmp/pip-dwVMP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:36 INFO - Running setup.py (path:/tmp/pip-XTJXCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:36 INFO - Running setup.py (path:/tmp/pip-6ebNUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:36 INFO - Running setup.py (path:/tmp/pip-IbZmeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:36 INFO - Running setup.py (path:/tmp/pip-mQdFxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:36 INFO - Running setup.py (path:/tmp/pip-FNp1Iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:36 INFO - Running setup.py (path:/tmp/pip-wVlMr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:36 INFO - Running setup.py (path:/tmp/pip-M53cXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:37 INFO - Running setup.py (path:/tmp/pip-_CBDAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:37 INFO - Running setup.py (path:/tmp/pip-XpAHdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:37 INFO - Running setup.py (path:/tmp/pip-4JO_Cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:37 INFO - Running setup.py (path:/tmp/pip-Uj2_Tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:37 INFO - Running setup.py (path:/tmp/pip-hSlYpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:37 INFO - Running setup.py (path:/tmp/pip-rKZJT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:37 INFO - Running setup.py (path:/tmp/pip-rtmECU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:18:37 INFO - Running setup.py install for manifestparser 16:18:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:18:37 INFO - Running setup.py install for mozcrash 16:18:37 INFO - Running setup.py install for mozdebug 16:18:37 INFO - Running setup.py install for mozdevice 16:18:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:18:37 INFO - Running setup.py install for mozfile 16:18:38 INFO - Running setup.py install for mozhttpd 16:18:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Running setup.py install for mozinfo 16:18:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Running setup.py install for mozInstall 16:18:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Running setup.py install for mozleak 16:18:38 INFO - Running setup.py install for mozlog 16:18:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Running setup.py install for moznetwork 16:18:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Running setup.py install for mozprocess 16:18:38 INFO - Running setup.py install for mozprofile 16:18:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Running setup.py install for mozrunner 16:18:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:18:38 INFO - Running setup.py install for mozscreenshot 16:18:39 INFO - Running setup.py install for moztest 16:18:39 INFO - Running setup.py install for mozversion 16:18:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:18:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:18:39 INFO - Cleaning up... 16:18:39 INFO - Return code: 0 16:18:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:39 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 0x1c4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb090>, '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 0x1fbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5f90>, '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': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', '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 16:18:39 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 16:18:39 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 16:18:39 INFO - Using env: {'DISPLAY': ':0', 16:18:39 INFO - 'HOME': '/home/cltbld', 16:18:39 INFO - 'LANG': 'en_US.UTF-8', 16:18:39 INFO - 'LANGUAGE': 'en_US:en', 16:18:39 INFO - 'LOGNAME': 'cltbld', 16:18:39 INFO - 'MAIL': '/var/mail/cltbld', 16:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:39 INFO - 'MOZ_NO_REMOTE': '1', 16:18:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:39 INFO - 'NO_EM_RESTART': '1', 16:18:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:39 INFO - 'PWD': '/builds/slave/test', 16:18:39 INFO - 'SHELL': '/bin/bash', 16:18:39 INFO - 'SHLVL': '1', 16:18:39 INFO - 'TERM': 'linux', 16:18:39 INFO - 'TMOUT': '86400', 16:18:39 INFO - 'USER': 'cltbld', 16:18:39 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:39 INFO - '_': '/tools/buildbot/bin/python'} 16:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:39 INFO - Running setup.py (path:/tmp/pip-d11yM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:39 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)) 16:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:39 INFO - Running setup.py (path:/tmp/pip-D51Mkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:39 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)) 16:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:39 INFO - Running setup.py (path:/tmp/pip-D7NCNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:39 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)) 16:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:39 INFO - Running setup.py (path:/tmp/pip-H2AjRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:39 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)) 16:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:39 INFO - Running setup.py (path:/tmp/pip-A33UJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:39 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)) 16:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:39 INFO - Running setup.py (path:/tmp/pip-4Wbxhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:39 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)) 16:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:39 INFO - Running setup.py (path:/tmp/pip-MVY69P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:40 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)) 16:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:40 INFO - Running setup.py (path:/tmp/pip-gIBdAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:40 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)) 16:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:40 INFO - Running setup.py (path:/tmp/pip-W0sXMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:40 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)) 16:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:40 INFO - Running setup.py (path:/tmp/pip-oYWAGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:40 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)) 16:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:40 INFO - Running setup.py (path:/tmp/pip-Pae6SU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:40 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)) 16:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:40 INFO - Running setup.py (path:/tmp/pip-_bV84e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:40 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)) 16:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:40 INFO - Running setup.py (path:/tmp/pip-VemZwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:40 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)) 16:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:40 INFO - Running setup.py (path:/tmp/pip-FsA4uW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:40 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)) 16:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:40 INFO - Running setup.py (path:/tmp/pip-z7S4pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:40 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)) 16:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:40 INFO - Running setup.py (path:/tmp/pip-H56WJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:40 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)) 16:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:40 INFO - Running setup.py (path:/tmp/pip-KqimWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:40 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)) 16:18:40 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)) 16:18:40 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)) 16:18:40 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)) 16:18:40 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)) 16:18:40 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)) 16:18:40 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)) 16:18:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18: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 16:18: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 16:18: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 16:18: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 16:18:41 INFO - Downloading blessings-1.6.tar.gz 16:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:18:41 INFO - Installing collected packages: blessings 16:18:41 INFO - Running setup.py install for blessings 16:18:42 INFO - Successfully installed blessings 16:18:42 INFO - Cleaning up... 16:18:42 INFO - Return code: 0 16:18:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:18:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:42 INFO - Reading from file tmpfile_stdout 16:18:42 INFO - Current package versions: 16:18:42 INFO - argparse == 1.2.1 16:18:42 INFO - blessings == 1.6 16:18:42 INFO - blobuploader == 1.2.4 16:18:42 INFO - docopt == 0.6.1 16:18:42 INFO - functools32 == 3.2.3-2 16:18:42 INFO - jsonschema == 2.5.1 16:18:42 INFO - manifestparser == 1.1 16:18:42 INFO - mozInstall == 1.13 16:18:42 INFO - mozcrash == 1.0 16:18:42 INFO - mozdebug == 0.1 16:18:42 INFO - mozdevice == 0.51 16:18:42 INFO - mozfile == 1.2 16:18:42 INFO - mozhttpd == 0.7 16:18:42 INFO - mozinfo == 0.10 16:18:42 INFO - mozleak == 0.1 16:18:42 INFO - mozlog == 3.5 16:18:42 INFO - moznetwork == 0.27 16:18:42 INFO - mozprocess == 0.25 16:18:42 INFO - mozprofile == 0.28 16:18:42 INFO - mozrunner == 6.13 16:18:42 INFO - mozscreenshot == 0.1 16:18:42 INFO - mozsystemmonitor == 0.3 16:18:42 INFO - moztest == 0.8 16:18:42 INFO - mozversion == 1.4 16:18:42 INFO - psutil == 3.1.1 16:18:42 INFO - requests == 1.2.3 16:18:42 INFO - wsgiref == 0.1.2 16:18:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:42 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 0x1c4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb090>, '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 0x1fbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5f90>, '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': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', '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 16:18:42 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 16:18:42 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 16:18:42 INFO - Using env: {'DISPLAY': ':0', 16:18:42 INFO - 'HOME': '/home/cltbld', 16:18:42 INFO - 'LANG': 'en_US.UTF-8', 16:18:42 INFO - 'LANGUAGE': 'en_US:en', 16:18:42 INFO - 'LOGNAME': 'cltbld', 16:18:42 INFO - 'MAIL': '/var/mail/cltbld', 16:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:42 INFO - 'MOZ_NO_REMOTE': '1', 16:18:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:42 INFO - 'NO_EM_RESTART': '1', 16:18:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:42 INFO - 'PWD': '/builds/slave/test', 16:18:42 INFO - 'SHELL': '/bin/bash', 16:18:42 INFO - 'SHLVL': '1', 16:18:42 INFO - 'TERM': 'linux', 16:18:42 INFO - 'TMOUT': '86400', 16:18:42 INFO - 'USER': 'cltbld', 16:18:42 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:42 INFO - '_': '/tools/buildbot/bin/python'} 16:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:42 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)) 16:18:42 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)) 16:18:42 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)) 16:18:42 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)) 16:18:42 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)) 16:18:42 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)) 16:18:42 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)) 16:18:42 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)) 16:18:42 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)) 16:18:42 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:18:43 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)) 16:18:43 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)) 16:18:43 INFO - Installing collected packages: simplejson 16:18:43 INFO - Running setup.py install for simplejson 16:18:43 INFO - building 'simplejson._speedups' extension 16:18:43 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 16:18:44 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 16:18:44 INFO - Successfully installed simplejson 16:18:44 INFO - Cleaning up... 16:18:44 INFO - Return code: 0 16:18:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:18: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')]} 16:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18: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')]} 16:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c4b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb7a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb090>, '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 0x1fbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5f90>, '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': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', '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 16:18:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:18:44 INFO - Using env: {'DISPLAY': ':0', 16:18:44 INFO - 'HOME': '/home/cltbld', 16:18:44 INFO - 'LANG': 'en_US.UTF-8', 16:18:44 INFO - 'LANGUAGE': 'en_US:en', 16:18:44 INFO - 'LOGNAME': 'cltbld', 16:18:44 INFO - 'MAIL': '/var/mail/cltbld', 16:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:44 INFO - 'MOZ_NO_REMOTE': '1', 16:18:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:44 INFO - 'NO_EM_RESTART': '1', 16:18:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:44 INFO - 'PWD': '/builds/slave/test', 16:18:44 INFO - 'SHELL': '/bin/bash', 16:18:44 INFO - 'SHLVL': '1', 16:18:44 INFO - 'TERM': 'linux', 16:18:44 INFO - 'TMOUT': '86400', 16:18:44 INFO - 'USER': 'cltbld', 16:18:44 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:44 INFO - '_': '/tools/buildbot/bin/python'} 16:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:18:44 INFO - Cleaning up... 16:18:44 INFO - Return code: 0 16:18:44 INFO - Running post-action listener: _resource_record_post_action 16:18:44 INFO - Running post-action listener: _start_resource_monitoring 16:18:45 INFO - Starting resource monitoring. 16:18:45 INFO - [mozharness: 2017-09-05 23:18:45.026870Z] Finished create-virtualenv step (success) 16:18:45 INFO - [mozharness: 2017-09-05 23:18:45.027338Z] Running install step. 16:18:45 INFO - Running pre-action listener: _resource_record_pre_action 16:18:45 INFO - Running main action method: install 16:18:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:45 INFO - Reading from file tmpfile_stdout 16:18:45 INFO - Detecting whether we're running mozinstall >=1.0... 16:18:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:18:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:18:45 INFO - Reading from file tmpfile_stdout 16:18:45 INFO - Output received: 16:18:45 INFO - Usage: mozinstall [options] installer 16:18:45 INFO - Options: 16:18:45 INFO - -h, --help show this help message and exit 16:18:45 INFO - -d DEST, --destination=DEST 16:18:45 INFO - Directory to install application into. [default: 16:18:45 INFO - "/builds/slave/test"] 16:18:45 INFO - --app=APP Application being installed. [default: firefox] 16:18:45 INFO - mkdir: /builds/slave/test/build/application 16:18:45 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'] 16:18:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:19:01 INFO - Reading from file tmpfile_stdout 16:19:01 INFO - Output received: 16:19:01 INFO - /builds/slave/test/build/application/firefox/firefox 16:19:01 INFO - Running post-action listener: _resource_record_post_action 16:19:01 INFO - [mozharness: 2017-09-05 23:19:01.759718Z] Finished install step (success) 16:19:01 INFO - [mozharness: 2017-09-05 23:19:01.759968Z] Running setup-mitmproxy step. 16:19:01 INFO - Running pre-action listener: _resource_record_pre_action 16:19:01 INFO - Running main action method: setup_mitmproxy 16:19:01 INFO - Skipping: mitmproxy is not required 16:19:01 INFO - Running post-action listener: _resource_record_post_action 16:19:01 INFO - [mozharness: 2017-09-05 23:19:01.761078Z] Finished setup-mitmproxy step (success) 16:19:01 INFO - [mozharness: 2017-09-05 23:19:01.761295Z] Running run-tests step. 16:19:01 INFO - Running pre-action listener: _resource_record_pre_action 16:19:01 INFO - Running pre-action listener: _set_gcov_prefix 16:19:01 INFO - Running main action method: run_tests 16:19:01 WARNING - Try message not found. 16:19:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:19:01 INFO - Python 2.7.3 16:19:01 INFO - Return code: 0 16:19:01 INFO - grabbing minidump binary from tooltool 16:19:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:19:01 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 0x1fbb090>, '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 0x1fbbd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fb5f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:19:01 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 16:19:01 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 16:19:01 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 16:19:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:19:01 INFO - Return code: 0 16:19:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:19:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:19:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:19:01 INFO - ENV: RUST_BACKTRACE is now full 16:19:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:19:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:19:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 16:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 16:19:01 INFO - Using env: {'DISPLAY': ':0', 16:19:01 INFO - 'HOME': '/home/cltbld', 16:19:01 INFO - 'LANG': 'en_US.UTF-8', 16:19:01 INFO - 'LANGUAGE': 'en_US:en', 16:19:01 INFO - 'LOGNAME': 'cltbld', 16:19:01 INFO - 'MAIL': '/var/mail/cltbld', 16:19:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:19:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:01 INFO - 'MOZ_NO_REMOTE': '1', 16:19:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:19:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:01 INFO - 'NO_EM_RESTART': '1', 16:19:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:01 INFO - 'PWD': '/builds/slave/test', 16:19:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:19:01 INFO - 'RUST_BACKTRACE': 'full', 16:19:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:19:01 INFO - 'SHELL': '/bin/bash', 16:19:01 INFO - 'SHLVL': '1', 16:19:01 INFO - 'TERM': 'linux', 16:19:01 INFO - 'TMOUT': '86400', 16:19:01 INFO - 'USER': 'cltbld', 16:19:01 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:01 INFO - '_': '/tools/buildbot/bin/python'} 16:19:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 16:19:02 INFO - mozversion application_buildid: 20170905215634 16:19:02 INFO - mozversion application_changeset: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 16:19:02 INFO - mozversion application_display_name: Nightly 16:19:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:19:02 INFO - mozversion application_name: Firefox 16:19:02 INFO - mozversion application_remotingname: firefox 16:19:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:19:02 INFO - mozversion application_vendor: Mozilla 16:19:02 INFO - mozversion application_version: 57.0a1 16:19:02 INFO - mozversion platform_buildid: 20170905215634 16:19:02 INFO - mozversion platform_changeset: f64e2b4dcf5eec0b4ad456c149680a67b7c26dc4 16:19:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:19:02 INFO - mozversion platform_version: 57.0a1 16:19:02 INFO - using testdate: 1504653542 16:19:02 INFO - actual date: 1504653542 16:19:02 INFO - starting webserver on 'localhost:43559' 16:19:02 INFO - SUITE-START | Running 2 tests 16:19:02 INFO - TEST-START | dromaeo_css 16:19:02 INFO - Initialising browser for dromaeo_css test... 16:19:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43559/getInfo.html -profile /tmp/tmplBvDlI/profile 16:19:02 INFO - TEST-INFO | started process 10749 (/builds/slave/test/build/application/firefox/firefox http://localhost:43559/getInfo.html) 16:19:07 INFO - TEST-INFO | 10749: exit 0 16:19:07 INFO - Browser initialized. 16:19:07 INFO - Running cycle 1/1 for dromaeo_css test... 16:19:07 INFO - Using env: {'DISPLAY': ':0', 16:19:07 INFO - 'HOME': '/home/cltbld', 16:19:07 INFO - 'JSGC_DISABLE_POISONING': '1', 16:19:07 INFO - 'LANG': 'en_US.UTF-8', 16:19:07 INFO - 'LANGUAGE': 'en_US:en', 16:19:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:19:07 INFO - 'LOGNAME': 'cltbld', 16:19:07 INFO - 'MAIL': '/var/mail/cltbld', 16:19:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:19:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:19:07 INFO - 'MOZ_CRASHREPORTER': '1', 16:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:19:07 INFO - 'MOZ_NO_REMOTE': '1', 16:19:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:19:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:07 INFO - 'NO_EM_RESTART': '1', 16:19:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:07 INFO - 'PWD': '/builds/slave/test', 16:19:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:19:07 INFO - 'RUST_BACKTRACE': 'full', 16:19:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:19:07 INFO - 'SHELL': '/bin/bash', 16:19:07 INFO - 'SHLVL': '1', 16:19:07 INFO - 'TERM': 'linux', 16:19:07 INFO - 'TMOUT': '86400', 16:19:07 INFO - 'USER': 'cltbld', 16:19:07 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:07 INFO - '_': '/tools/buildbot/bin/python'} 16:19:07 INFO - TEST-INFO | started process 10938 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplBvDlI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:08 INFO - PID 10938 | 16:19:08 INFO - PID 10938 | (/builds/slave/test/build/application/firefox/firefox:10991): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:19:08 INFO - PID 10938 | 16:19:18 INFO - PID 10938 | RSS: Main: 157843456 16:19:18 INFO - PID 10938 | 16:22:17 INFO - PID 10938 | Cycle 1(1): loaded http://localhost:43559/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43559/tests/dromaeo/cssquery-ext.html) 16:22:17 INFO - PID 10938 | RSS: Main: 165834752 16:22:17 INFO - PID 10938 | 16:25:19 INFO - PID 10938 | Cycle 1(1): loaded http://localhost:43559/tests/dromaeo/cssquery-ext.html (next: http://localhost:43559/tests/dromaeo/cssquery-jquery.html) 16:25:20 INFO - PID 10938 | RSS: Main: 160260096 16:25:20 INFO - PID 10938 | 16:28:21 INFO - PID 10938 | Cycle 1(1): loaded http://localhost:43559/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43559/tests/dromaeo/cssquery-mootools.html) 16:28:22 INFO - PID 10938 | RSS: Main: 164577280 16:28:22 INFO - PID 10938 | 16:31:23 INFO - PID 10938 | Cycle 1(1): loaded http://localhost:43559/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43559/tests/dromaeo/cssquery-prototype.html) 16:31:24 INFO - PID 10938 | RSS: Main: 163344384 16:31:24 INFO - PID 10938 | 16:34:27 INFO - PID 10938 | Cycle 1(1): loaded http://localhost:43559/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43559/tests/dromaeo/cssquery-yui.html) 16:34:28 INFO - PID 10938 | RSS: Main: 164249600 16:34:28 INFO - PID 10938 | 16:37:29 INFO - PID 10938 | Cycle 1(1): loaded http://localhost:43559/tests/dromaeo/cssquery-yui.html (next: http://localhost:43559/tests/dromaeo/cssquery-dojo.html) 16:37:29 INFO - PID 10938 | RSS: Main: 166588416 16:37:29 INFO - PID 10938 | 16:37:29 INFO - PID 10938 | __start_tp_report 16:37:29 INFO - PID 10938 | _x_x_mozilla_page_load 16:37:29 INFO - PID 10938 | _x_x_mozilla_page_load_details 16:37:29 INFO - PID 10938 | |i|pagename|runs| 16:37:29 INFO - PID 10938 | |0;dojo.html;3873.786941718206;3885.592012838652;3896.805159742012;3913.982364585208;3960.861369852054;6787.423069039125;6811.352921472459;6871.759488417906;6874.243833178352;6875.621840798754;6814.1482314710665;6873.79378618641;6949.687264073116;6956.96521517393;6958.9652051739795;6062.96968515159;6107.083937409379;6163.691815409234;6202.100695399165;6227.719752611143;6057.303410107828;6143.477804386633;6151.507879369639;6193.90709139361;6273.466755325803;6026.578139530235;6078.908816367758;6133.478654314388;6180.752769889199;6194.876102477971;6104.08438734189;6136.079588061782;6139.293981192175;6170.167027451326;6201.906971395432;6034.275886893557;6084.421979911901;6131.908021379683;6165.383461653843;6186.257649082094;6738.764143254963;6763.154605674291;6775.457963362919;6849.1780986281965;6872.862542749167;7373.631318434111;7401.370883474911;7471.10346758387;7477.887831682529;7491.438142139307;7384.864072002638;7488.588127652977;7523.3229009389415;7533.7363192288;7585.848283034308;6791.694373753193;6890.827729306758;6938.167419909542;6957.9304206958295;6970.721171153148;7511.849763004819;7547.132079810783;7621.199774023758;7632.732854350071;7647.235276472419;7326.96336518314;7376.225496322916;7389;7432;7433.10802703679;7444;7455.4035677146785;7467.738629147953;7562.924370756222;7564.962175189199;7300.634968251672;7399.001134846727;7408.111026676834;7518.060242469692;7528.548287102792;7294.234105418961;7389.704411823574;7394.889076663854;7416.480846340704;7435.591042492715;6251.74993000284;6251.843703907439;6345.397187267202;6349.174607301022;6353.174087368705;5420.972895135499;5443.194027042711;5451.427600102012;5486.615936884459;5508;6274.372562743689;6286.9056964145575;6342.809715708536;6345.746170153235;6347.301796802348;7362.263773622595;7370.668319925616;7428.182899881009;7462.440316976248;7471.4396420268695;6273.560850740404;6287.591306565058;6327.936720632735;6376.74493020274;6412.262589802139;6561.507886908405;6571.3757193066585;6615.536912416084;6664.86670266592;6673.199216094003;7265.564066155942;7280.599567023864;7287.599182045038;7303.160136584361;7372.668229929559;7295.955708460679;7384.593951817591;7409.518381305198;7410.258974102547;7496.999999999891;7058.294170583106;7129.750458733919;7146.7141314346845;7153.964230178815;7302.050733404623;7337.266273372834;7349.669264882986;7364.447666424932;7367.4106071515225;7373.594452305175;7342.889856652261;7399.667014984447;7403.259674032553;7442.96278518604;7453.105627324756;7045.647717614202;7086.574805511686;7093.077899872984;7156.928430715834;7177.318154775288;7134.143902731706;7140.5358651687475;7147.82130446743;7151.928480715126;7158.212596614264;7093.716251350094;7096.325849044332;7117.359437650636;7193.640317983975;7204.963975180301;7169.283071692789;7175.21072682015;7234.312740289664;7254.818629534304;7262.201157872524;6942.618156001468;7000.194977577644;7014;7022.648867556704;7104.325089116525 16:37:29 INFO - PID 10938 | |1;ext.html;930.4835816122052;935.6865450074225;973.780899297658;999.1906555689892;999.6850991937534;32604.347913041725;33263.50104748428;41075.17849643009;41928.580714192845;42004;42838;43181.78409107958;43219.13561728763;43231;43294.78352608233;7702.807429814257;7916.525008499509;8080.272775450208;8084.191580841927;8084.434089613729;7855.685772569091;7948.085970113453;7952.562609056499;7953.920460795404;7958.721444749435;7943.880841787378;7999.880001799948;8018.198180181965;8018.518888866687;8018.719344822933;7896.447248692594;7934;7945.12603613602;7950.284474397332;7952.801179970518;7854.057513098408;7903.683852645887;7912;7916.762497125095;7922.841543169162;39197.60802391968;39461.802690986646;39558.406623900664;39642.80178599117;39719.20561588781;3786.1291902862495;3786.2616789726017;3829.100161462054;3829.138443850139;3834.271488417192;32022.519662205086;32102.197445063826;32105.678943210503;32211.677883221335;32256.677433225836;5431.809886653988;5456.945430545723;5492.313460817398;5508.669479831223;5618.634788738719;222373;223044;223410;223410;223605;50234.49765502371;51559.48440515622;51572.48427515752;51584;51686.22470662943;53189.7340513295;53213.7339313301;53250;53281.46718532842;53346.733266334195;13779.724405511834;13866.306684665726;14007.719845603031;15259;15432.691346173238;11034.613788517363;11135.053520450852;11144.275622084539;11172.944135279271;11179.273347232567;14399.424023039172;14421.351039203051;14424.846012318989;14442.494512692216;14510.782338264935;5730.713464326767;5763.88472230553;5785.768569257267;5822.243108395882;5889.234399528034;1081.8918108189118;1103.2332528892503;1114.1309778372881;1115.7824224276187;1119.2948442481186;25234.495310093695;25269.62095568568;25380.619290710652;25459;25517.3620659485;1185.6898127568911;1192.4812706472712;1193.8507686539183;1197.3175290084569;1206.0592737664635;13598.86401135976;13613.455461781417;13619.659508512366;13766.65583360404;17492.125393730515;288873;291688;291791;292068;292574;58664.120038199464;58985.41014589799;59087.11369329463;59217;59296.40703592995;1455.1123814473162;1518.1346632419636;1539.0073402655448;1542.0516382424728;1545.5672411724736;30463.695363046085;30851.228719282197;30970.690293097683;31841;32106.19734506656;49422;49651.50348496469;49731;49812.501874980786;50034;261815;266881;267510;267578;267612;37026.6297337034;38313;39693.80153099216;39881;39900;48582.27126593033;49258.75370623124;49837.252441214136;49878.251826223364;49998;35509.82245088758;36438.05939230005;36755.44866826946;36800.26399471942;37553.81223093867;36154.27691446209;36189.45715814212;36454.453183201746;36608.26783464369;36914.26171476609;41539;41977.790111049246;42484;42548.78725606476;45938.540614593425;44275.557244427146;44412.55587444084;44613.776931115135;44916;45337.546624534654;32836.83581582077;33040.66959330376;33211.83394083014;33229.66770332363;33963.66036339605 16:37:29 INFO - PID 10938 | |2;jquery.html;577.9942200577996;601.1703848688807;601.8705978214683;604.5997390360268;616.195864396962;22615.095396184173;22700.54598908021;23417.414564635892;23445.944932478036;23481.530369392603;22711.886440567774;24546;24629.507409851838;24740.13409530667;24829.875850620723;6815.591064536131;6948.444124470056;6952.443804495653;6956.234814170437;6962.617056061915;6571.934280657204;6839.28187540306;6850.1779786425695;6852.246252912176;6887.655617219144;6788.8302792430095;6793.388595026447;6806.421454176401;6812.931870681279;6813.591184528919;6776.525643204977;6777.322267773233;6780.457563394916;6784;6787.864242715118;6352.841178970517;6359.968200159016;6361.650109243989;6370.936290637127;6378.202724659418;30540.389192216255;31178;31392.686073139434;31642.841785791155;31726.682733172605;2328.8136949044206;2364.6216605343234;2365.6569797379457;2366.7278262999803;2368.2539999899977;20677.379678609665;21287.148514059572;21299.467513312135;21326.146954121818;21358.252461163785;2490.4396510784927;2547.694276686791;2600.4929038837436;2625.2780485366675;2632.328756167165;150635.9999999989;152121;155971;156135;156180;15094;15628.531144065697;15865.920670396574;16229.431969880998;16622.50132496027;25726.6141007885;25735.099271525407;25849.741502585108;25866.353341166247;25960.610590841152;10221.23340749432;10265.12746416563;10340.793184136275;10405.271630985759;10436.269461137648;7432.9256707433315;7842.254985776342;9269.582868770922;9315.254779617615;9444.433333999981;9303.72088837336;9700.5149742514;9714.22286217101;9900.900990989998;10141.340812847142;2620.5283049050972;2645.894164233409;2681.745234202787;2842.9147125586264;2941.8529073546238;1005.4068099820994;1034.803387356415;1041.880183778875;1043.0768769638923;1044.8171569975225;21171.78828211698;21515.784842151377;21603.891980539996;21964.89017554934;26092.434453932823;1112.0825319111766;1114.9888501114885;1122.8147355686383;1142.23470274916;1149.0635132367095;12182.654966206457;12342.938285308515;12585.55950541745;12606.936965315299;12620.81068783969;616830;621104;634346;634782;644144;135780.32109839388;139303;139814;140152;141600;1253.348258905364;1257.25821765159;1285.2995117660898;1291.430054192151;1292.126416695795;19881.900590496956;19951.60096798027;20014;20050.699239511712;20446.488837779176;60789.392106080144;62949.68525157345;63550.36449635629;64452.35547644651;66868.3313166862;78762.21237787549;84091;87176;87807.56096219478;88009.55995220238;13447.394867230802;14497.85502144965;15475.613109672347;16067.47359000893;16317.67364652724;25099.623505647796;25177.87411062933;25529.74470255348;25615.743842561336;25862.870685646452;20304;20520.486987825425;20567.38297851067;20829.791702083392;20856.061477233863;18322;18449.262029518668;18681.81318186801;18859.811401885807;18879.339223127125;20809.37571872846;21002.369928902157;21206.151753930288;21257.68113478268;21289.574208516053;15684.137372444626;15784.60538486547;15825.208739562746;15863.841361586236;15866.365345386055;20101.899490502456;20195.394138175878;20421.183152673726;20441.182352705724;20481.180752769724 16:37:29 INFO - PID 10938 | |3;mootools.html;1454.3964254834245;1502;1514.53049554638;1526.298999082825;1529.2124555853736;16119.597010074755;16513.58716032098;17031.40390086347;17133.828661713364;17265.654686906255;16896.915515422406;17021.40425085122;17118.229679664415;17243.396481123164;17281.481555553353;2639.7756190723812;2651.6817981842205;2677.9464410711776;2681.919542413721;2683.745044220796;2620.2139358192467;2638.3536033671776;2638.93402664933;2641.6829980402376;2641.867906604662;2732.0437846753675;2771.7366850149206;2799.538076217432;2835.943281134376;2836.290927268183;2632.091928284748;2674.398260391416;2682.181934509974;2686.7044625091225;2690.838549687025;2667.346500107467;2687;2691.663542057243;2699.7705195058443;2709.078913169532;16325.918370408192;16431.83568164315;16440;16441.26014329358;16442.177891105395;7983.201679832028;8239.546824924626;8248.8350232995;8274.255317021498;8275.751727448187;5227.686338819645;5269.683818970874;5345.893082138375;5406.297181366436;5460.945390546083;2098.8740675559516;2107.3783233946024;2113.2920471641864;2126.776688447722;2150.9354719358444;30113.096607101605;30476.69523304783;30521.694783052106;30529.542056868944;30586.08241752751;6892.586444813327;6919.515633905679;6968.581885086911;7014.649267536704;7016.543924644984;17765.822341776675;17808.821911780717;17809;17809.821901781077;17857.107144642716;7651.38788896898;7748.535087894745;7753.689852405953;7758.456908016385;7758.7672369829;7030.6484675766005;7055.753048643272;7122.857542849114;7202.387797037206;7349.963250183715;5570.275864137717;5732.713364331767;5758.683272420057;5823.301203855564;5848.795292164839;1586.8889177757446;1609.6217388913597;1636.533587927435;1638.1154176744424;1642.622196894697;914.4878867834034;952.2382094324513;952.5189779161618;957.2342126298932;961.697065424389;8047.758567242992;8083;8152.796180095545;8163.836723265502;8176.959115204386;735.3933005270673;747.9326860582573;750.8385697075155;756.9318761311508;766.7469734987441;4834.270671293543;5012.548870601663;5294.020606187791;5413.079776438015;5506.587005974568;24255.272341829772;25086.498270034863;25209.369765755637;25272.241832745047;25278.620820687705;24107.15624953118;24555.754442455705;24565.508689826103;24732.752672473405;24771.504569908502;12558.120931534704;12801.295928723823;12855.678608034874;12913.806292905614;12952.222866628032;9354.438733676001;9440.386374885886;9450.102240287162;9561;9578.616855325708;20986.740795552316;21251.362459126252;21540;21551.46121346979;21837;21884.671729923746;21904.780952190275;21972.780272197713;22420.551588968454;22434;13415.396307166005;13464;13616.93191534036;13733.519326823913;13733.793993090312;13735.725285494435;13782.93108534451;13786.517471888435;13812.792808108088;13870.237136957565;11734.530618775154;11784.528618855149;11858.940705296418;11867;12063.698407539881;11306.83039754388;11391.145664075062;11418.942905285421;11492.655220343404;11676.649700508999;10201.489925503547;10309.74225644365;10359.223058270509;10362.067413932782;10365.689029329133;7789.961050194713;7986.241307075818;8019.639116239667;8075.353971682134;8139.83720325602;8081.353491720766;8112.310453611509;8119.431639785158;8162.0613629431;8168.060673022678 16:37:29 INFO - PID 10938 | |4;prototype.html;526.365729296198;532.1060618161487;546.4398991034187;547.5893080189858;557.4731878374935;6728.79813605592;6889.1732992041025;6890.315489720988;6898.241193468714;6913.792586222415;6665.866682666344;6747.865042699155;6814.4889133314955;6816.182058153016;6816.239423033667;5472.808451704191;5476.616636835423;5504.889902201953;5526.557875369981;5531.529819965308;5179.87050323743;5277.1556550951855;5444.91832622509;5461.672299662033;5485.204645326426;5248.320537428024;5252.3697156341295;5252.553532949704;5284.313039304904;5316.175992721134;4978.427480839714;5218.843434696927;5267.55225805807;5269.156934890419;5295.775774174817;4714;4757.785899634525;4759.714417134983;4769.833055843029;4774.355462012619;6610.669466526654;6640.136782218281;6648.368405001517;6655.93344066558;6665.9666701666665;6179.598326108789;6232;6248.468880145193;6309.810705678837;6329.942768600177;7147.53541019832;7175.210726820045;7195.093300275431;7208.2431344708575;7213.386862116727;5262.657927234717;5293.3647962244395;5321.685066995869;5345.754782991153;5347.0909945309;7228.241034691387;7232.349088582053;7349.448791340617;7431.962840185818;7686.884696729554;5411.377691565521;5427.457254274541;5451.482109199699;5469.863253418696;5502.339719233718;5473.48001939823;5498.065328894098;5513.9448605514235;5544.530699364672;5575.498205161473;6163.445289923839;6225.315215326311;6250.15622890913;6254.21822272216;6268.623882567061;7258.49190556656;7315.451341149435;7334.559926404433;7370.631468426557;7376.926230737731;1892.262017813054;1893.2143160588478;1901.6196760647927;1901.7908030116675;1923.7111135539221;1221.8167274908835;1266.3731452930858;1299.811527328532;1318.5121505043003;1322.1141834970592;6191.4118158774845;6220.782272620437;6328.746850125944;6346.238451385863;6371.617702937839;7128.893066604005;7158.642067896584;7161;7228.421726261887;7369.373603243785;6221.262644436433;6239.469645080128;6278.30938596754;6318.7472501100365;6348.174737284216;3916.4712763776524;3917.804109794499;3935.311320518898;3953.7627742335553;3970.3647416413237;7125.786226413215;7230;7245.5652660840515;7263.527870688388;7287.744928927462;7541.4343924205905;7603.809904752426;7618.5809780462605;7637.923620763832;7691.384689224848;7235.602041887747;7261.3827824634445;7267.418606511466;7292.525985810906;7303.196648368569;7411.962940185264;7425.554466732014;7432.442566807403;7432.777016689508;7441.404687625085;7353.485256032241;7400.629968501446;7407.851842963003;7425.777226683208;7428.591427471541;7213;7266.818329541804;7275.636218189175;7303.890441643486;7315.231900650355;5430.51125398716;5471.644343117685;5484.506394424521;5504.972475137599;5519.8896022080135;7214.5671259722485;7264.745733899288;7365.742199023009;7437.553746775211;7441.665125069493;6664.83337916556;7043.154821421257;7049.718011279491;7077.752278670222;7126.429885609035;6948.861022779617;6992.895106573507;7043;7065.258147894603;7086.661401737311;6823.522353435212;6945.895811562729;7032.859342813217;7037.472189585699;7107.573545587281;7034.397273009019;7042.408371730352;7087.362137407658;7105.2894710528535;7160.462965277521;6926.549774264657;6981.79054628362;7012.824679383056;7024.8574184874105;7063.1524217094275 16:37:29 INFO - PID 10938 | |5;yui.html;932.4265576670346;959.8194221108037;960.745402468346;999.7950420163867;1007.1238022920058;20135.59728805423;20612.38162855113;20664.48338791531;20675;21925.01337439815;18382.356617518388;18748.343807966725;22755.203567875127;22849.42876428086;22884.77115228847;1022.3814592171722;1037.0407373179817;1038.1176000399637;1038.1227862456212;1038.6676263595652;1042.1610603464208;1095.2771171027123;1117.8826223246524;1120.0703416164563;1120.2718233148437;1143.771245750849;1153.2042890405594;1153.9596114136007;1154.8267759836049;1155.0932517973392;1148.9195756297063;1151.7984352738279;1152.2970987697497;1154.4054811771944;1156.2368836567825;842.2798507276314;843.5655637346771;847.1274587175219;848.1984524624232;848.4273115646913;19120.23519059236;19258.518537036547;19340;19953.501162470908;21109.89445052765;22579;22713.318600442013;23013.194538191252;23116.190933317423;23137.768622313728;17257;17550.912245438692;17587.03271320077;17611.911940440343;17705.203265853066;4075.4294398784195;4086.6730661547003;4088.4276201331836;4089.795510224477;4093.713440059197;148902;148998;149440.2527987364;149465;150468;17488.726465559397;17750.91124544395;17954.910225448788;18081.457556273333;18160.455186344432;32898;32917.6832926685;32924.835375822964;32958;33245.50131748026;9561.713148605553;9636.75908102303;9653.613855445845;9663.27525435581;9667.613295468243;4846.418429788448;4949.183384741549;4972.5276098770555;5022;5039.042581909425;5563.721813909288;5691.971540142273;5710.400407957188;5712.200291959129;5734.369219385864;1089.2919602258596;1124.2973141786383;1136.9829452558217;1140.8459857919074;1147.6040765935738;693.9583624982517;714.3035540348222;716.9103862017248;718.8274814044591;741.6625435426868;12811.615651530468;12885.355732213351;12900.483980640856;13530.458781648624;13781.86218137826;705.3933617089299;710.9751158709421;717.6196615793638;720.8846584546448;721.6968873073322;5990.64056156632;6042.456178943916;6087.482563982111;6176.073588961602;6262.812115636447;132009;135289;136206;136594;139347;111492;111955;112129;112265;115132;102059.4897025525;102685.48657256816;102959;103982.48008760059;104068.47965760123;12533.248005119722;12737.617871463872;12930.870691292967;12986.935065324613;13416.597502074954;50232.246516303014;50283.49716502788;50840.23739643982;51088.48911510837;51791;69317.30682693109;82090.58954705189;83692.16307836844;84168.15831841603;84921;18372.908135459238;18565.071746412894;18598.44204673862;18770.155343009323;18790.248390064244;20781.48046298794;20825.37523874286;20998.370048897945;21131.577368452854;21192.046357913623;18096.366627167987;18296;19605.313814017012;20058;20220.39338819838;18301;18422;18437.07814609291;18917.243310267437;19822;17997.550061248574;18446.446606601825;18756.531086722942;19458.124384402454;19698.31055913036;13769.449222031408;14271.64320891986;14637.85362146365;14685.265736712909;14726.779098313747;14637.780433293721;14869.18219497938;14900.254987250812;14962.326695298956;15026 16:37:29 INFO - PID 10938 | __end_tp_report 16:37:29 INFO - PID 10938 | __start_cc_report 16:37:29 INFO - PID 10938 | _x_x_mozilla_cycle_collect,351 16:37:29 INFO - PID 10938 | __end_cc_report 16:37:29 INFO - PID 10938 | __startTimestamp1504654649863__endTimestamp 16:37:29 INFO - PID 10938 | 16:37:29 INFO - PID 10938 | ------- Summary: start ------- 16:37:29 INFO - PID 10938 | Number of tests: 6 16:37:29 INFO - PID 10938 | 16:37:29 INFO - PID 10938 | [#0] dojo.html Cycles:165 Average:6819.78 Median:7093.08 stddev:753.58 (10.6%) stddev-sans-first:719.58 16:37:29 INFO - PID 10938 | Values: 3873.8 3885.6 3896.8 3914.0 3960.9 6787.4 6811.4 6871.8 6874.2 6875.6 6814.1 6873.8 6949.7 6957.0 6959.0 6063.0 6107.1 6163.7 6202.1 6227.7 6057.3 6143.5 6151.5 6193.9 6273.5 6026.6 6078.9 6133.5 6180.8 6194.9 6104.1 6136.1 6139.3 6170.2 6201.9 6034.3 6084.4 6131.9 6165.4 6186.3 6738.8 6763.2 6775.5 6849.2 6872.9 7373.6 7401.4 7471.1 7477.9 7491.4 7384.9 7488.6 7523.3 7533.7 7585.8 6791.7 6890.8 6938.2 6957.9 6970.7 7511.8 7547.1 7621.2 7632.7 7647.2 7327.0 7376.2 7389.0 7432.0 7433.1 7444.0 7455.4 7467.7 7562.9 7565.0 7300.6 7399.0 7408.1 7518.1 7528.5 7294.2 7389.7 7394.9 7416.5 7435.6 6251.7 6251.8 6345.4 6349.2 6353.2 5421.0 5443.2 5451.4 5486.6 5508.0 6274.4 6286.9 6342.8 6345.7 6347.3 7362.3 7370.7 7428.2 7462.4 7471.4 6273.6 6287.6 6327.9 6376.7 6412.3 6561.5 6571.4 6615.5 6664.9 6673.2 7265.6 7280.6 7287.6 7303.2 7372.7 7296.0 7384.6 7409.5 7410.3 7497.0 7058.3 7129.8 7146.7 7154.0 7302.1 7337.3 7349.7 7364.4 7367.4 7373.6 7342.9 7399.7 7403.3 7443.0 7453.1 7045.6 7086.6 7093.1 7156.9 7177.3 7134.1 7140.5 7147.8 7151.9 7158.2 7093.7 7096.3 7117.4 7193.6 7205.0 7169.3 7175.2 7234.3 7254.8 7262.2 6942.6 7000.2 7014.0 7022.6 7104.3 16:37:29 INFO - PID 10938 | 16:37:29 INFO - PID 10938 | [#1] ext.html Cycles:180 Average:44453.76 Median:39510.10 stddev:68138.58 (172.5%) stddev-sans-first:68251.36 16:37:29 INFO - PID 10938 | Values: 930.5 935.7 973.8 999.2 999.7 32604.3 33263.5 41075.2 41928.6 42004.0 42838.0 43181.8 43219.1 43231.0 43294.8 7702.8 7916.5 8080.3 8084.2 8084.4 7855.7 7948.1 7952.6 7953.9 7958.7 7943.9 7999.9 8018.2 8018.5 8018.7 7896.4 7934.0 7945.1 7950.3 7952.8 7854.1 7903.7 7912.0 7916.8 7922.8 39197.6 39461.8 39558.4 39642.8 39719.2 3786.1 3786.3 3829.1 3829.1 3834.3 32022.5 32102.2 32105.7 32211.7 32256.7 5431.8 5456.9 5492.3 5508.7 5618.6 222373.0 223044.0 223410.0 223410.0 223605.0 50234.5 51559.5 51572.5 51584.0 51686.2 53189.7 53213.7 53250.0 53281.5 53346.7 13779.7 13866.3 14007.7 15259.0 15432.7 11034.6 11135.1 11144.3 11172.9 11179.3 14399.4 14421.4 14424.8 14442.5 14510.8 5730.7 5763.9 5785.8 5822.2 5889.2 1081.9 1103.2 1114.1 1115.8 1119.3 25234.5 25269.6 25380.6 25459.0 25517.4 1185.7 1192.5 1193.9 1197.3 1206.1 13598.9 13613.5 13619.7 13766.7 17492.1 288873.0 291688.0 291791.0 292068.0 292574.0 58664.1 58985.4 59087.1 59217.0 59296.4 1455.1 1518.1 1539.0 1542.1 1545.6 30463.7 30851.2 30970.7 31841.0 32106.2 49422.0 49651.5 49731.0 49812.5 50034.0 261815.0 266881.0 267510.0 267578.0 267612.0 37026.6 38313.0 39693.8 39881.0 39900.0 48582.3 49258.8 49837.3 49878.3 49998.0 35509.8 36438.1 36755.4 36800.3 37553.8 36154.3 36189.5 36454.5 36608.3 36914.3 41539.0 41977.8 42484.0 42548.8 45938.5 44275.6 44412.6 44613.8 44916.0 45337.5 32836.8 33040.7 33211.8 33229.7 33963.7 16:37:29 INFO - PID 10938 | 16:37:29 INFO - PID 10938 | [#2] jquery.html Cycles:180 Average:41126.87 Median:23463.74 stddev:105541.28 (449.8%) stddev-sans-first:105793.44 16:37:29 INFO - PID 10938 | Values: 578.0 601.2 601.9 604.6 616.2 22615.1 22700.5 23417.4 23445.9 23481.5 22711.9 24546.0 24629.5 24740.1 24829.9 6815.6 6948.4 6952.4 6956.2 6962.6 6571.9 6839.3 6850.2 6852.2 6887.7 6788.8 6793.4 6806.4 6812.9 6813.6 6776.5 6777.3 6780.5 6784.0 6787.9 6352.8 6360.0 6361.7 6370.9 6378.2 30540.4 31178.0 31392.7 31642.8 31726.7 2328.8 2364.6 2365.7 2366.7 2368.3 20677.4 21287.1 21299.5 21326.1 21358.3 2490.4 2547.7 2600.5 2625.3 2632.3 150636.0 152121.0 155971.0 156135.0 156180.0 15094.0 15628.5 15865.9 16229.4 16622.5 25726.6 25735.1 25849.7 25866.4 25960.6 10221.2 10265.1 10340.8 10405.3 10436.3 7432.9 7842.3 9269.6 9315.3 9444.4 9303.7 9700.5 9714.2 9900.9 10141.3 2620.5 2645.9 2681.7 2842.9 2941.9 1005.4 1034.8 1041.9 1043.1 1044.8 21171.8 21515.8 21603.9 21964.9 26092.4 1112.1 1115.0 1122.8 1142.2 1149.1 12182.7 12342.9 12585.6 12606.9 12620.8 616830.0 621104.0 634346.0 634782.0 644144.0 135780.3 139303.0 139814.0 140152.0 141600.0 1253.3 1257.3 1285.3 1291.4 1292.1 19881.9 19951.6 20014.0 20050.7 20446.5 60789.4 62949.7 63550.4 64452.4 66868.3 78762.2 84091.0 87176.0 87807.6 88009.6 13447.4 14497.9 15475.6 16067.5 16317.7 25099.6 25177.9 25529.7 25615.7 25862.9 20304.0 20520.5 20567.4 20829.8 20856.1 18322.0 18449.3 18681.8 18859.8 18879.3 20809.4 21002.4 21206.2 21257.7 21289.6 15684.1 15784.6 15825.2 15863.8 15866.4 20101.9 20195.4 20421.2 20441.2 20481.2 16:37:29 INFO - PID 10938 | 16:37:29 INFO - PID 10938 | [#3] mootools.html Cycles:180 Average:10168.38 Median:2641.78 stddev:7679.77 (290.7%) stddev-sans-first:7673.41 16:37:29 INFO - PID 10938 | Values: 1454.4 1502.0 1514.5 1526.3 1529.2 16119.6 16513.6 17031.4 17133.8 17265.7 16896.9 17021.4 17118.2 17243.4 17281.5 2639.8 2651.7 2677.9 2681.9 2683.7 2620.2 2638.4 2638.9 2641.7 2641.9 2732.0 2771.7 2799.5 2835.9 2836.3 2632.1 2674.4 2682.2 2686.7 2690.8 2667.3 2687.0 2691.7 2699.8 2709.1 16325.9 16431.8 16440.0 16441.3 16442.2 7983.2 8239.5 8248.8 8274.3 8275.8 5227.7 5269.7 5345.9 5406.3 5460.9 2098.9 2107.4 2113.3 2126.8 2150.9 30113.1 30476.7 30521.7 30529.5 30586.1 6892.6 6919.5 6968.6 7014.6 7016.5 17765.8 17808.8 17809.0 17809.8 17857.1 7651.4 7748.5 7753.7 7758.5 7758.8 7030.6 7055.8 7122.9 7202.4 7350.0 5570.3 5732.7 5758.7 5823.3 5848.8 1586.9 1609.6 1636.5 1638.1 1642.6 914.5 952.2 952.5 957.2 961.7 8047.8 8083.0 8152.8 8163.8 8177.0 735.4 747.9 750.8 756.9 766.7 4834.3 5012.5 5294.0 5413.1 5506.6 24255.3 25086.5 25209.4 25272.2 25278.6 24107.2 24555.8 24565.5 24732.8 24771.5 12558.1 12801.3 12855.7 12913.8 12952.2 9354.4 9440.4 9450.1 9561.0 9578.6 20986.7 21251.4 21540.0 21551.5 21837.0 21884.7 21904.8 21972.8 22420.6 22434.0 13415.4 13464.0 13616.9 13733.5 13733.8 13735.7 13782.9 13786.5 13812.8 13870.2 11734.5 11784.5 11858.9 11867.0 12063.7 11306.8 11391.1 11418.9 11492.7 11676.6 10201.5 10309.7 10359.2 10362.1 10365.7 7790.0 7986.2 8019.6 8075.4 8139.8 8081.4 8112.3 8119.4 8162.1 8168.1 16:37:29 INFO - PID 10938 | 16:37:29 INFO - PID 10938 | [#4] prototype.html Cycles:180 Average:6018.69 Median:6665.92 stddev:1711.18 (25.7%) stddev-sans-first:1665.59 16:37:29 INFO - PID 10938 | Values: 526.4 532.1 546.4 547.6 557.5 6728.8 6889.2 6890.3 6898.2 6913.8 6665.9 6747.9 6814.5 6816.2 6816.2 5472.8 5476.6 5504.9 5526.6 5531.5 5179.9 5277.2 5444.9 5461.7 5485.2 5248.3 5252.4 5252.6 5284.3 5316.2 4978.4 5218.8 5267.6 5269.2 5295.8 4714.0 4757.8 4759.7 4769.8 4774.4 6610.7 6640.1 6648.4 6655.9 6666.0 6179.6 6232.0 6248.5 6309.8 6329.9 7147.5 7175.2 7195.1 7208.2 7213.4 5262.7 5293.4 5321.7 5345.8 5347.1 7228.2 7232.3 7349.4 7432.0 7686.9 5411.4 5427.5 5451.5 5469.9 5502.3 5473.5 5498.1 5513.9 5544.5 5575.5 6163.4 6225.3 6250.2 6254.2 6268.6 7258.5 7315.5 7334.6 7370.6 7376.9 1892.3 1893.2 1901.6 1901.8 1923.7 1221.8 1266.4 1299.8 1318.5 1322.1 6191.4 6220.8 6328.7 6346.2 6371.6 7128.9 7158.6 7161.0 7228.4 7369.4 6221.3 6239.5 6278.3 6318.7 6348.2 3916.5 3917.8 3935.3 3953.8 3970.4 7125.8 7230.0 7245.6 7263.5 7287.7 7541.4 7603.8 7618.6 7637.9 7691.4 7235.6 7261.4 7267.4 7292.5 7303.2 7412.0 7425.6 7432.4 7432.8 7441.4 7353.5 7400.6 7407.9 7425.8 7428.6 7213.0 7266.8 7275.6 7303.9 7315.2 5430.5 5471.6 5484.5 5505.0 5519.9 7214.6 7264.7 7365.7 7437.6 7441.7 6664.8 7043.2 7049.7 7077.8 7126.4 6948.9 6992.9 7043.0 7065.3 7086.7 6823.5 6945.9 7032.9 7037.5 7107.6 7034.4 7042.4 7087.4 7105.3 7160.5 6926.5 6981.8 7012.8 7024.9 7063.2 16:37:29 INFO - PID 10938 | 16:37:29 INFO - PID 10938 | [#5] yui.html Cycles:180 Average:27138.89 Median:18853.75 stddev:38525.62 (204.3%) stddev-sans-first:38583.44 16:37:29 INFO - PID 10938 | Values: 932.4 959.8 960.7 999.8 1007.1 20135.6 20612.4 20664.5 20675.0 21925.0 18382.4 18748.3 22755.2 22849.4 22884.8 1022.4 1037.0 1038.1 1038.1 1038.7 1042.2 1095.3 1117.9 1120.1 1120.3 1143.8 1153.2 1154.0 1154.8 1155.1 1148.9 1151.8 1152.3 1154.4 1156.2 842.3 843.6 847.1 848.2 848.4 19120.2 19258.5 19340.0 19953.5 21109.9 22579.0 22713.3 23013.2 23116.2 23137.8 17257.0 17550.9 17587.0 17611.9 17705.2 4075.4 4086.7 4088.4 4089.8 4093.7 148902.0 148998.0 149440.3 149465.0 150468.0 17488.7 17750.9 17954.9 18081.5 18160.5 32898.0 32917.7 32924.8 32958.0 33245.5 9561.7 9636.8 9653.6 9663.3 9667.6 4846.4 4949.2 4972.5 5022.0 5039.0 5563.7 5692.0 5710.4 5712.2 5734.4 1089.3 1124.3 1137.0 1140.8 1147.6 694.0 714.3 716.9 718.8 741.7 12811.6 12885.4 12900.5 13530.5 13781.9 705.4 711.0 717.6 720.9 721.7 5990.6 6042.5 6087.5 6176.1 6262.8 132009.0 135289.0 136206.0 136594.0 139347.0 111492.0 111955.0 112129.0 112265.0 115132.0 102059.5 102685.5 102959.0 103982.5 104068.5 12533.2 12737.6 12930.9 12986.9 13416.6 50232.2 50283.5 50840.2 51088.5 51791.0 69317.3 82090.6 83692.2 84168.2 84921.0 18372.9 18565.1 18598.4 18770.2 18790.2 20781.5 20825.4 20998.4 21131.6 21192.0 18096.4 18296.0 19605.3 20058.0 20220.4 18301.0 18422.0 18437.1 18917.2 19822.0 17997.6 18446.4 18756.5 19458.1 19698.3 13769.4 14271.6 14637.9 14685.3 14726.8 14637.8 14869.2 14900.3 14962.3 15026.0 16:37:29 INFO - PID 10938 | -------- Summary: end -------- 16:37:29 INFO - PID 10938 | 16:37:30 INFO - TEST-INFO | 10938: exit 0 16:37:30 INFO - TEST-OK | dromaeo_css | took 1108400ms 16:37:30 INFO - TEST-START | kraken 16:37:30 INFO - Initialising browser for kraken test... 16:37:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43559/getInfo.html -profile /tmp/tmp8wTW9P/profile 16:37:30 INFO - TEST-INFO | started process 11310 (/builds/slave/test/build/application/firefox/firefox http://localhost:43559/getInfo.html) 16:37:35 INFO - TEST-INFO | 11310: exit 0 16:37:35 INFO - Browser initialized. 16:37:35 INFO - Running cycle 1/1 for kraken test... 16:37:35 INFO - Using env: {'DISPLAY': ':0', 16:37:35 INFO - 'HOME': '/home/cltbld', 16:37:35 INFO - 'JSGC_DISABLE_POISONING': '1', 16:37:35 INFO - 'LANG': 'en_US.UTF-8', 16:37:35 INFO - 'LANGUAGE': 'en_US:en', 16:37:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:37:35 INFO - 'LOGNAME': 'cltbld', 16:37:35 INFO - 'MAIL': '/var/mail/cltbld', 16:37:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:37:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:37:35 INFO - 'MOZ_CRASHREPORTER': '1', 16:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:37:35 INFO - 'MOZ_NO_REMOTE': '1', 16:37:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:35 INFO - 'NO_EM_RESTART': '1', 16:37:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:35 INFO - 'PWD': '/builds/slave/test', 16:37:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:37:35 INFO - 'RUST_BACKTRACE': 'full', 16:37:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:37:35 INFO - 'SHELL': '/bin/bash', 16:37:35 INFO - 'SHLVL': '1', 16:37:35 INFO - 'TERM': 'linux', 16:37:35 INFO - 'TMOUT': '86400', 16:37:35 INFO - 'USER': 'cltbld', 16:37:35 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334', 16:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:35 INFO - '_': '/tools/buildbot/bin/python'} 16:37:35 INFO - TEST-INFO | started process 11501 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8wTW9P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:37:36 INFO - PID 11501 | 16:37:36 INFO - PID 11501 | (/builds/slave/test/build/application/firefox/firefox:11553): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:37:36 INFO - PID 11501 | 16:37:47 INFO - PID 11501 | RSS: Main: 158195712 16:37:47 INFO - PID 11501 | 16:37:49 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43559/tests/kraken/driver.html?testName=audio-beat-detection) 16:37:50 INFO - PID 11501 | RSS: Main: 164040704 16:37:50 INFO - PID 11501 | 16:37:53 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43559/tests/kraken/driver.html?testName=audio-dft) 16:37:53 INFO - PID 11501 | RSS: Main: 168345600 16:37:53 INFO - PID 11501 | 16:37:56 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43559/tests/kraken/driver.html?testName=audio-fft) 16:37:57 INFO - PID 11501 | RSS: Main: 161628160 16:37:57 INFO - PID 11501 | 16:37:59 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43559/tests/kraken/driver.html?testName=audio-oscillator) 16:37:59 INFO - PID 11501 | RSS: Main: 162820096 16:37:59 INFO - PID 11501 | 16:38:02 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43559/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:38:02 INFO - PID 11501 | RSS: Main: 163024896 16:38:02 INFO - PID 11501 | 16:38:05 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43559/tests/kraken/driver.html?testName=imaging-darkroom) 16:38:06 INFO - PID 11501 | RSS: Main: 163127296 16:38:06 INFO - PID 11501 | 16:38:09 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43559/tests/kraken/driver.html?testName=imaging-desaturate) 16:38:10 INFO - PID 11501 | RSS: Main: 166653952 16:38:10 INFO - PID 11501 | 16:38:13 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43559/tests/kraken/driver.html?testName=json-parse-financial) 16:38:13 INFO - PID 11501 | RSS: Main: 164728832 16:38:13 INFO - PID 11501 | 16:38:15 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43559/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:38:15 INFO - PID 11501 | RSS: Main: 164438016 16:38:15 INFO - PID 11501 | 16:38:17 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43559/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:38:18 INFO - PID 11501 | RSS: Main: 164409344 16:38:18 INFO - PID 11501 | 16:38:20 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43559/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:38:20 INFO - PID 11501 | RSS: Main: 164544512 16:38:20 INFO - PID 11501 | 16:38:23 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43559/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:38:23 INFO - PID 11501 | RSS: Main: 164106240 16:38:23 INFO - PID 11501 | 16:38:26 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43559/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:38:27 INFO - PID 11501 | RSS: Main: 164732928 16:38:27 INFO - PID 11501 | 16:38:28 INFO - PID 11501 | Cycle 1(1): loaded http://localhost:43559/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43559/tests/kraken/driver.html?testName=ai-astar) 16:38:29 INFO - PID 11501 | RSS: Main: 166461440 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | __start_tp_report 16:38:29 INFO - PID 11501 | _x_x_mozilla_page_load 16:38:29 INFO - PID 11501 | _x_x_mozilla_page_load_details 16:38:29 INFO - PID 11501 | |i|pagename|runs| 16:38:29 INFO - PID 11501 | |0;ai-astar;98;105;100;105;105;106;107;97;104;105 16:38:29 INFO - PID 11501 | |1;audio-beat-detection;135;114;149;171;114;139;141;123;166;124 16:38:29 INFO - PID 11501 | |2;audio-dft;163;163;162;163;161;162;161;164;164;172 16:38:29 INFO - PID 11501 | |3;audio-fft;73;73;74;171;74;73;74;73;143;74 16:38:29 INFO - PID 11501 | |4;audio-oscillator;78;78;132;78;78;78;130;79;78;130 16:38:29 INFO - PID 11501 | |5;imaging-gaussian-blur;114;114;114;116;114;114;114;113;114;113 16:38:29 INFO - PID 11501 | |6;imaging-darkroom;150;151;150;149;150;149;150;151;149;150 16:38:29 INFO - PID 11501 | |7;imaging-desaturate;94;94;94;94;94;94;94;94;93;94 16:38:29 INFO - PID 11501 | |8;json-parse-financial;46;45;47;43;46;44;44;51;43;44 16:38:29 INFO - PID 11501 | |9;json-stringify-tinderbox;37;37;36;36;37;36;36;38;36;36 16:38:29 INFO - PID 11501 | |10;stanford-crypto-aes;71;72;72;71;71;70;72;72;73;72 16:38:29 INFO - PID 11501 | |11;stanford-crypto-ccm;116;115;109;115;116;113;110;117;116;155 16:38:29 INFO - PID 11501 | |12;stanford-crypto-pbkdf2;155;172;157;161;165;156;150;167;153;153 16:38:29 INFO - PID 11501 | |13;stanford-crypto-sha256-iterative;60;59;66;58;58;64;57;59;59;58 16:38:29 INFO - PID 11501 | __end_tp_report 16:38:29 INFO - PID 11501 | __start_cc_report 16:38:29 INFO - PID 11501 | _x_x_mozilla_cycle_collect,901 16:38:29 INFO - PID 11501 | __end_cc_report 16:38:29 INFO - PID 11501 | __startTimestamp1504654709121__endTimestamp 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | ------- Summary: start ------- 16:38:29 INFO - PID 11501 | Number of tests: 14 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#0] ai-astar Cycles:10 Average:103.20 Median:105.50 stddev:3.52 (3.3%) stddev-sans-first:3.19 16:38:29 INFO - PID 11501 | Values: 98.0 105.0 100.0 105.0 105.0 106.0 107.0 97.0 104.0 105.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#1] audio-beat-detection Cycles:10 Average:137.60 Median:140.00 stddev:19.96 (14.3%) stddev-sans-first:21.15 16:38:29 INFO - PID 11501 | Values: 135.0 114.0 149.0 171.0 114.0 139.0 141.0 123.0 166.0 124.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.00 stddev:3.17 (1.9%) stddev-sans-first:3.36 16:38:29 INFO - PID 11501 | Values: 163.0 163.0 162.0 163.0 161.0 162.0 161.0 164.0 164.0 172.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#3] audio-fft Cycles:10 Average:90.20 Median:73.50 stddev:35.82 (48.7%) stddev-sans-first:37.45 16:38:29 INFO - PID 11501 | Values: 73.0 73.0 74.0 171.0 74.0 73.0 74.0 73.0 143.0 74.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:78.00 stddev:25.38 (32.5%) stddev-sans-first:26.26 16:38:29 INFO - PID 11501 | Values: 78.0 78.0 132.0 78.0 78.0 78.0 130.0 79.0 78.0 130.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 16:38:29 INFO - PID 11501 | Values: 114.0 114.0 114.0 116.0 114.0 114.0 114.0 113.0 114.0 113.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:150.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 16:38:29 INFO - PID 11501 | Values: 150.0 151.0 150.0 149.0 150.0 149.0 150.0 151.0 149.0 150.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:38:29 INFO - PID 11501 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#8] json-parse-financial Cycles:10 Average:45.30 Median:45.50 stddev:2.41 (5.3%) stddev-sans-first:2.54 16:38:29 INFO - PID 11501 | Values: 46.0 45.0 47.0 43.0 46.0 44.0 44.0 51.0 43.0 44.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#9] json-stringify-tinderbox Cycles:10 Average:36.50 Median:36.50 stddev:0.71 (1.9%) stddev-sans-first:0.73 16:38:29 INFO - PID 11501 | Values: 37.0 37.0 36.0 36.0 37.0 36.0 36.0 38.0 36.0 36.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#10] stanford-crypto-aes Cycles:10 Average:71.60 Median:72.00 stddev:0.84 (1.2%) stddev-sans-first:0.87 16:38:29 INFO - PID 11501 | Values: 71.0 72.0 72.0 71.0 71.0 70.0 72.0 72.0 73.0 72.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#11] stanford-crypto-ccm Cycles:10 Average:118.20 Median:116.00 stddev:13.21 (11.4%) stddev-sans-first:13.98 16:38:29 INFO - PID 11501 | Values: 116.0 115.0 109.0 115.0 116.0 113.0 110.0 117.0 116.0 155.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.90 Median:159.00 stddev:7.11 (4.5%) stddev-sans-first:7.40 16:38:29 INFO - PID 11501 | Values: 155.0 172.0 157.0 161.0 165.0 156.0 150.0 167.0 153.0 153.0 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.80 Median:59.00 stddev:2.90 (4.9%) stddev-sans-first:3.07 16:38:29 INFO - PID 11501 | Values: 60.0 59.0 66.0 58.0 58.0 64.0 57.0 59.0 59.0 58.0 16:38:29 INFO - PID 11501 | -------- Summary: end -------- 16:38:29 INFO - PID 11501 | 16:38:29 INFO - PID 11501 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:38:29 INFO - TEST-INFO | 11501: exit 0 16:38:29 INFO - TEST-OK | kraken | took 59294ms 16:38:29 INFO - SUITE-END | took 1167s 16:38:30 INFO - Completed test suite (00:19:28) 16:38:30 INFO - javascript benchmark 16:38:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9293.232491707347, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3873.786941718206, 3885.592012838652, 3896.805159742012, 3913.982364585208, 3960.861369852054, 6787.423069039125, 6811.352921472459, 6871.759488417906, 6874.243833178352, 6875.621840798754, 6814.1482314710665, 6873.79378618641, 6949.687264073116, 6956.96521517393, 6958.9652051739795, 6062.96968515159, 6107.083937409379, 6163.691815409234, 6202.100695399165, 6227.719752611143, 6057.303410107828, 6143.477804386633, 6151.507879369639, 6193.90709139361, 6273.466755325803, 6026.578139530235, 6078.908816367758, 6133.478654314388, 6180.752769889199, 6194.876102477971, 6104.08438734189, 6136.079588061782, 6139.293981192175, 6170.167027451326, 6201.906971395432, 6034.275886893557, 6084.421979911901, 6131.908021379683, 6165.383461653843, 6186.257649082094, 6738.764143254963, 6763.154605674291, 6775.457963362919, 6849.1780986281965, 6872.862542749167, 7373.631318434111, 7401.370883474911, 7471.10346758387, 7477.887831682529, 7491.438142139307, 7384.864072002638, 7488.588127652977, 7523.3229009389415, 7533.7363192288, 7585.848283034308, 6791.694373753193, 6890.827729306758, 6938.167419909542, 6957.9304206958295, 6970.721171153148, 7511.849763004819, 7547.132079810783, 7621.199774023758, 7632.732854350071, 7647.235276472419, 7326.96336518314, 7376.225496322916, 7389.0, 7432.0, 7433.10802703679, 7444.0, 7455.4035677146785, 7467.738629147953, 7562.924370756222, 7564.962175189199, 7300.634968251672, 7399.001134846727, 7408.111026676834, 7518.060242469692, 7528.548287102792, 7294.234105418961, 7389.704411823574, 7394.889076663854, 7416.480846340704, 7435.591042492715, 6251.74993000284, 6251.843703907439, 6345.397187267202, 6349.174607301022, 6353.174087368705, 5420.972895135499, 5443.194027042711, 5451.427600102012, 5486.615936884459, 5508.0, 6274.372562743689, 6286.9056964145575, 6342.809715708536, 6345.746170153235, 6347.301796802348, 7362.263773622595, 7370.668319925616, 7428.182899881009, 7462.440316976248, 7471.4396420268695, 6273.560850740404, 6287.591306565058, 6327.936720632735, 6376.74493020274, 6412.262589802139, 6561.507886908405, 6571.3757193066585, 6615.536912416084, 6664.86670266592, 6673.199216094003, 7265.564066155942, 7280.599567023864, 7287.599182045038, 7303.160136584361, 7372.668229929559, 7295.955708460679, 7384.593951817591, 7409.518381305198, 7410.258974102547, 7496.999999999891, 7058.294170583106, 7129.750458733919, 7146.7141314346845, 7153.964230178815, 7302.050733404623, 7337.266273372834, 7349.669264882986, 7364.447666424932, 7367.4106071515225, 7373.594452305175, 7342.889856652261, 7399.667014984447, 7403.259674032553, 7442.96278518604, 7453.105627324756, 7045.647717614202, 7086.574805511686, 7093.077899872984, 7156.928430715834, 7177.318154775288, 7134.143902731706, 7140.5358651687475, 7147.82130446743, 7151.928480715126, 7158.212596614264, 7093.716251350094, 7096.325849044332, 7117.359437650636, 7193.640317983975, 7204.963975180301, 7169.283071692789, 7175.21072682015, 7234.312740289664, 7254.818629534304, 7262.201157872524, 6942.618156001468, 7000.194977577644, 7014.0, 7022.648867556704, 7104.325089116525], "value": 6770.281865951694, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [930.4835816122052, 935.6865450074225, 973.780899297658, 999.1906555689892, 999.6850991937534, 32604.347913041725, 33263.50104748428, 41075.17849643009, 41928.580714192845, 42004.0, 42838.0, 43181.78409107958, 43219.13561728763, 43231.0, 43294.78352608233, 7702.807429814257, 7916.525008499509, 8080.272775450208, 8084.191580841927, 8084.434089613729, 7855.685772569091, 7948.085970113453, 7952.562609056499, 7953.920460795404, 7958.721444749435, 7943.880841787378, 7999.880001799948, 8018.198180181965, 8018.518888866687, 8018.719344822933, 7896.447248692594, 7934.0, 7945.12603613602, 7950.284474397332, 7952.801179970518, 7854.057513098408, 7903.683852645887, 7912.0, 7916.762497125095, 7922.841543169162, 39197.60802391968, 39461.802690986646, 39558.406623900664, 39642.80178599117, 39719.20561588781, 3786.1291902862495, 3786.2616789726017, 3829.100161462054, 3829.138443850139, 3834.271488417192, 32022.519662205086, 32102.197445063826, 32105.678943210503, 32211.677883221335, 32256.677433225836, 5431.809886653988, 5456.945430545723, 5492.313460817398, 5508.669479831223, 5618.634788738719, 222373.0, 223044.0, 223410.0, 223410.0, 223605.0, 50234.49765502371, 51559.48440515622, 51572.48427515752, 51584.0, 51686.22470662943, 53189.7340513295, 53213.7339313301, 53250.0, 53281.46718532842, 53346.733266334195, 13779.724405511834, 13866.306684665726, 14007.719845603031, 15259.0, 15432.691346173238, 11034.613788517363, 11135.053520450852, 11144.275622084539, 11172.944135279271, 11179.273347232567, 14399.424023039172, 14421.351039203051, 14424.846012318989, 14442.494512692216, 14510.782338264935, 5730.713464326767, 5763.88472230553, 5785.768569257267, 5822.243108395882, 5889.234399528034, 1081.8918108189118, 1103.2332528892503, 1114.1309778372881, 1115.7824224276187, 1119.2948442481186, 25234.495310093695, 25269.62095568568, 25380.619290710652, 25459.0, 25517.3620659485, 1185.6898127568911, 1192.4812706472712, 1193.8507686539183, 1197.3175290084569, 1206.0592737664635, 13598.86401135976, 13613.455461781417, 13619.659508512366, 13766.65583360404, 17492.125393730515, 288873.0, 291688.0, 291791.0, 292068.0, 292574.0, 58664.120038199464, 58985.41014589799, 59087.11369329463, 59217.0, 59296.40703592995, 1455.1123814473162, 1518.1346632419636, 1539.0073402655448, 1542.0516382424728, 1545.5672411724736, 30463.695363046085, 30851.228719282197, 30970.690293097683, 31841.0, 32106.19734506656, 49422.0, 49651.50348496469, 49731.0, 49812.501874980786, 50034.0, 261815.0, 266881.0, 267510.0, 267578.0, 267612.0, 37026.6297337034, 38313.0, 39693.80153099216, 39881.0, 39900.0, 48582.27126593033, 49258.75370623124, 49837.252441214136, 49878.251826223364, 49998.0, 35509.82245088758, 36438.05939230005, 36755.44866826946, 36800.26399471942, 37553.81223093867, 36154.27691446209, 36189.45715814212, 36454.453183201746, 36608.26783464369, 36914.26171476609, 41539.0, 41977.790111049246, 42484.0, 42548.78725606476, 45938.540614593425, 44275.557244427146, 44412.55587444084, 44613.776931115135, 44916.0, 45337.546624534654, 32836.83581582077, 33040.66959330376, 33211.83394083014, 33229.66770332363, 33963.66036339605], "value": 18934.457386834307, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.9942200577996, 601.1703848688807, 601.8705978214683, 604.5997390360268, 616.195864396962, 22615.095396184173, 22700.54598908021, 23417.414564635892, 23445.944932478036, 23481.530369392603, 22711.886440567774, 24546.0, 24629.507409851838, 24740.13409530667, 24829.875850620723, 6815.591064536131, 6948.444124470056, 6952.443804495653, 6956.234814170437, 6962.617056061915, 6571.934280657204, 6839.28187540306, 6850.1779786425695, 6852.246252912176, 6887.655617219144, 6788.8302792430095, 6793.388595026447, 6806.421454176401, 6812.931870681279, 6813.591184528919, 6776.525643204977, 6777.322267773233, 6780.457563394916, 6784.0, 6787.864242715118, 6352.841178970517, 6359.968200159016, 6361.650109243989, 6370.936290637127, 6378.202724659418, 30540.389192216255, 31178.0, 31392.686073139434, 31642.841785791155, 31726.682733172605, 2328.8136949044206, 2364.6216605343234, 2365.6569797379457, 2366.7278262999803, 2368.2539999899977, 20677.379678609665, 21287.148514059572, 21299.467513312135, 21326.146954121818, 21358.252461163785, 2490.4396510784927, 2547.694276686791, 2600.4929038837436, 2625.2780485366675, 2632.328756167165, 150635.9999999989, 152121.0, 155971.0, 156135.0, 156180.0, 15094.0, 15628.531144065697, 15865.920670396574, 16229.431969880998, 16622.50132496027, 25726.6141007885, 25735.099271525407, 25849.741502585108, 25866.353341166247, 25960.610590841152, 10221.23340749432, 10265.12746416563, 10340.793184136275, 10405.271630985759, 10436.269461137648, 7432.9256707433315, 7842.254985776342, 9269.582868770922, 9315.254779617615, 9444.433333999981, 9303.72088837336, 9700.5149742514, 9714.22286217101, 9900.900990989998, 10141.340812847142, 2620.5283049050972, 2645.894164233409, 2681.745234202787, 2842.9147125586264, 2941.8529073546238, 1005.4068099820994, 1034.803387356415, 1041.880183778875, 1043.0768769638923, 1044.8171569975225, 21171.78828211698, 21515.784842151377, 21603.891980539996, 21964.89017554934, 26092.434453932823, 1112.0825319111766, 1114.9888501114885, 1122.8147355686383, 1142.23470274916, 1149.0635132367095, 12182.654966206457, 12342.938285308515, 12585.55950541745, 12606.936965315299, 12620.81068783969, 616830.0, 621104.0, 634346.0, 634782.0, 644144.0, 135780.32109839388, 139303.0, 139814.0, 140152.0, 141600.0, 1253.348258905364, 1257.25821765159, 1285.2995117660898, 1291.430054192151, 1292.126416695795, 19881.900590496956, 19951.60096798027, 20014.0, 20050.699239511712, 20446.488837779176, 60789.392106080144, 62949.68525157345, 63550.36449635629, 64452.35547644651, 66868.3313166862, 78762.21237787549, 84091.0, 87176.0, 87807.56096219478, 88009.55995220238, 13447.394867230802, 14497.85502144965, 15475.613109672347, 16067.47359000893, 16317.67364652724, 25099.623505647796, 25177.87411062933, 25529.74470255348, 25615.743842561336, 25862.870685646452, 20304.0, 20520.486987825425, 20567.38297851067, 20829.791702083392, 20856.061477233863, 18322.0, 18449.262029518668, 18681.81318186801, 18859.811401885807, 18879.339223127125, 20809.37571872846, 21002.369928902157, 21206.151753930288, 21257.68113478268, 21289.574208516053, 15684.137372444626, 15784.60538486547, 15825.208739562746, 15863.841361586236, 15866.365345386055, 20101.899490502456, 20195.394138175878, 20421.183152673726, 20441.182352705724, 20481.180752769724], "value": 12966.058983257713, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1454.3964254834245, 1502.0, 1514.53049554638, 1526.298999082825, 1529.2124555853736, 16119.597010074755, 16513.58716032098, 17031.40390086347, 17133.828661713364, 17265.654686906255, 16896.915515422406, 17021.40425085122, 17118.229679664415, 17243.396481123164, 17281.481555553353, 2639.7756190723812, 2651.6817981842205, 2677.9464410711776, 2681.919542413721, 2683.745044220796, 2620.2139358192467, 2638.3536033671776, 2638.93402664933, 2641.6829980402376, 2641.867906604662, 2732.0437846753675, 2771.7366850149206, 2799.538076217432, 2835.943281134376, 2836.290927268183, 2632.091928284748, 2674.398260391416, 2682.181934509974, 2686.7044625091225, 2690.838549687025, 2667.346500107467, 2687.0, 2691.663542057243, 2699.7705195058443, 2709.078913169532, 16325.918370408192, 16431.83568164315, 16440.0, 16441.26014329358, 16442.177891105395, 7983.201679832028, 8239.546824924626, 8248.8350232995, 8274.255317021498, 8275.751727448187, 5227.686338819645, 5269.683818970874, 5345.893082138375, 5406.297181366436, 5460.945390546083, 2098.8740675559516, 2107.3783233946024, 2113.2920471641864, 2126.776688447722, 2150.9354719358444, 30113.096607101605, 30476.69523304783, 30521.694783052106, 30529.542056868944, 30586.08241752751, 6892.586444813327, 6919.515633905679, 6968.581885086911, 7014.649267536704, 7016.543924644984, 17765.822341776675, 17808.821911780717, 17809.0, 17809.821901781077, 17857.107144642716, 7651.38788896898, 7748.535087894745, 7753.689852405953, 7758.456908016385, 7758.7672369829, 7030.6484675766005, 7055.753048643272, 7122.857542849114, 7202.387797037206, 7349.963250183715, 5570.275864137717, 5732.713364331767, 5758.683272420057, 5823.301203855564, 5848.795292164839, 1586.8889177757446, 1609.6217388913597, 1636.533587927435, 1638.1154176744424, 1642.622196894697, 914.4878867834034, 952.2382094324513, 952.5189779161618, 957.2342126298932, 961.697065424389, 8047.758567242992, 8083.0, 8152.796180095545, 8163.836723265502, 8176.959115204386, 735.3933005270673, 747.9326860582573, 750.8385697075155, 756.9318761311508, 766.7469734987441, 4834.270671293543, 5012.548870601663, 5294.020606187791, 5413.079776438015, 5506.587005974568, 24255.272341829772, 25086.498270034863, 25209.369765755637, 25272.241832745047, 25278.620820687705, 24107.15624953118, 24555.754442455705, 24565.508689826103, 24732.752672473405, 24771.504569908502, 12558.120931534704, 12801.295928723823, 12855.678608034874, 12913.806292905614, 12952.222866628032, 9354.438733676001, 9440.386374885886, 9450.102240287162, 9561.0, 9578.616855325708, 20986.740795552316, 21251.362459126252, 21540.0, 21551.46121346979, 21837.0, 21884.671729923746, 21904.780952190275, 21972.780272197713, 22420.551588968454, 22434.0, 13415.396307166005, 13464.0, 13616.93191534036, 13733.519326823913, 13733.793993090312, 13735.725285494435, 13782.93108534451, 13786.517471888435, 13812.792808108088, 13870.237136957565, 11734.530618775154, 11784.528618855149, 11858.940705296418, 11867.0, 12063.698407539881, 11306.83039754388, 11391.145664075062, 11418.942905285421, 11492.655220343404, 11676.649700508999, 10201.489925503547, 10309.74225644365, 10359.223058270509, 10362.067413932782, 10365.689029329133, 7789.961050194713, 7986.241307075818, 8019.639116239667, 8075.353971682134, 8139.83720325602, 8081.353491720766, 8112.310453611509, 8119.431639785158, 8162.0613629431, 8168.060673022678], "value": 7044.427693014679, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.365729296198, 532.1060618161487, 546.4398991034187, 547.5893080189858, 557.4731878374935, 6728.79813605592, 6889.1732992041025, 6890.315489720988, 6898.241193468714, 6913.792586222415, 6665.866682666344, 6747.865042699155, 6814.4889133314955, 6816.182058153016, 6816.239423033667, 5472.808451704191, 5476.616636835423, 5504.889902201953, 5526.557875369981, 5531.529819965308, 5179.87050323743, 5277.1556550951855, 5444.91832622509, 5461.672299662033, 5485.204645326426, 5248.320537428024, 5252.3697156341295, 5252.553532949704, 5284.313039304904, 5316.175992721134, 4978.427480839714, 5218.843434696927, 5267.55225805807, 5269.156934890419, 5295.775774174817, 4714.0, 4757.785899634525, 4759.714417134983, 4769.833055843029, 4774.355462012619, 6610.669466526654, 6640.136782218281, 6648.368405001517, 6655.93344066558, 6665.9666701666665, 6179.598326108789, 6232.0, 6248.468880145193, 6309.810705678837, 6329.942768600177, 7147.53541019832, 7175.210726820045, 7195.093300275431, 7208.2431344708575, 7213.386862116727, 5262.657927234717, 5293.3647962244395, 5321.685066995869, 5345.754782991153, 5347.0909945309, 7228.241034691387, 7232.349088582053, 7349.448791340617, 7431.962840185818, 7686.884696729554, 5411.377691565521, 5427.457254274541, 5451.482109199699, 5469.863253418696, 5502.339719233718, 5473.48001939823, 5498.065328894098, 5513.9448605514235, 5544.530699364672, 5575.498205161473, 6163.445289923839, 6225.315215326311, 6250.15622890913, 6254.21822272216, 6268.623882567061, 7258.49190556656, 7315.451341149435, 7334.559926404433, 7370.631468426557, 7376.926230737731, 1892.262017813054, 1893.2143160588478, 1901.6196760647927, 1901.7908030116675, 1923.7111135539221, 1221.8167274908835, 1266.3731452930858, 1299.811527328532, 1318.5121505043003, 1322.1141834970592, 6191.4118158774845, 6220.782272620437, 6328.746850125944, 6346.238451385863, 6371.617702937839, 7128.893066604005, 7158.642067896584, 7161.0, 7228.421726261887, 7369.373603243785, 6221.262644436433, 6239.469645080128, 6278.30938596754, 6318.7472501100365, 6348.174737284216, 3916.4712763776524, 3917.804109794499, 3935.311320518898, 3953.7627742335553, 3970.3647416413237, 7125.786226413215, 7230.0, 7245.5652660840515, 7263.527870688388, 7287.744928927462, 7541.4343924205905, 7603.809904752426, 7618.5809780462605, 7637.923620763832, 7691.384689224848, 7235.602041887747, 7261.3827824634445, 7267.418606511466, 7292.525985810906, 7303.196648368569, 7411.962940185264, 7425.554466732014, 7432.442566807403, 7432.777016689508, 7441.404687625085, 7353.485256032241, 7400.629968501446, 7407.851842963003, 7425.777226683208, 7428.591427471541, 7213.0, 7266.818329541804, 7275.636218189175, 7303.890441643486, 7315.231900650355, 5430.51125398716, 5471.644343117685, 5484.506394424521, 5504.972475137599, 5519.8896022080135, 7214.5671259722485, 7264.745733899288, 7365.742199023009, 7437.553746775211, 7441.665125069493, 6664.83337916556, 7043.154821421257, 7049.718011279491, 7077.752278670222, 7126.429885609035, 6948.861022779617, 6992.895106573507, 7043.0, 7065.258147894603, 7086.661401737311, 6823.522353435212, 6945.895811562729, 7032.859342813217, 7037.472189585699, 7107.573545587281, 7034.397273009019, 7042.408371730352, 7087.362137407658, 7105.2894710528535, 7160.462965277521, 6926.549774264657, 6981.79054628362, 7012.824679383056, 7024.8574184874105, 7063.1524217094275], "value": 5508.035184588189, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [932.4265576670346, 959.8194221108037, 960.745402468346, 999.7950420163867, 1007.1238022920058, 20135.59728805423, 20612.38162855113, 20664.48338791531, 20675.0, 21925.01337439815, 18382.356617518388, 18748.343807966725, 22755.203567875127, 22849.42876428086, 22884.77115228847, 1022.3814592171722, 1037.0407373179817, 1038.1176000399637, 1038.1227862456212, 1038.6676263595652, 1042.1610603464208, 1095.2771171027123, 1117.8826223246524, 1120.0703416164563, 1120.2718233148437, 1143.771245750849, 1153.2042890405594, 1153.9596114136007, 1154.8267759836049, 1155.0932517973392, 1148.9195756297063, 1151.7984352738279, 1152.2970987697497, 1154.4054811771944, 1156.2368836567825, 842.2798507276314, 843.5655637346771, 847.1274587175219, 848.1984524624232, 848.4273115646913, 19120.23519059236, 19258.518537036547, 19340.0, 19953.501162470908, 21109.89445052765, 22579.0, 22713.318600442013, 23013.194538191252, 23116.190933317423, 23137.768622313728, 17257.0, 17550.912245438692, 17587.03271320077, 17611.911940440343, 17705.203265853066, 4075.4294398784195, 4086.6730661547003, 4088.4276201331836, 4089.795510224477, 4093.713440059197, 148902.0, 148998.0, 149440.2527987364, 149465.0, 150468.0, 17488.726465559397, 17750.91124544395, 17954.910225448788, 18081.457556273333, 18160.455186344432, 32898.0, 32917.6832926685, 32924.835375822964, 32958.0, 33245.50131748026, 9561.713148605553, 9636.75908102303, 9653.613855445845, 9663.27525435581, 9667.613295468243, 4846.418429788448, 4949.183384741549, 4972.5276098770555, 5022.0, 5039.042581909425, 5563.721813909288, 5691.971540142273, 5710.400407957188, 5712.200291959129, 5734.369219385864, 1089.2919602258596, 1124.2973141786383, 1136.9829452558217, 1140.8459857919074, 1147.6040765935738, 693.9583624982517, 714.3035540348222, 716.9103862017248, 718.8274814044591, 741.6625435426868, 12811.615651530468, 12885.355732213351, 12900.483980640856, 13530.458781648624, 13781.86218137826, 705.3933617089299, 710.9751158709421, 717.6196615793638, 720.8846584546448, 721.6968873073322, 5990.64056156632, 6042.456178943916, 6087.482563982111, 6176.073588961602, 6262.812115636447, 132009.0, 135289.0, 136206.0, 136594.0, 139347.0, 111492.0, 111955.0, 112129.0, 112265.0, 115132.0, 102059.4897025525, 102685.48657256816, 102959.0, 103982.48008760059, 104068.47965760123, 12533.248005119722, 12737.617871463872, 12930.870691292967, 12986.935065324613, 13416.597502074954, 50232.246516303014, 50283.49716502788, 50840.23739643982, 51088.48911510837, 51791.0, 69317.30682693109, 82090.58954705189, 83692.16307836844, 84168.15831841603, 84921.0, 18372.908135459238, 18565.071746412894, 18598.44204673862, 18770.155343009323, 18790.248390064244, 20781.48046298794, 20825.37523874286, 20998.370048897945, 21131.577368452854, 21192.046357913623, 18096.366627167987, 18296.0, 19605.313814017012, 20058.0, 20220.39338819838, 18301.0, 18422.0, 18437.07814609291, 18917.243310267437, 19822.0, 17997.550061248574, 18446.446606601825, 18756.531086722942, 19458.124384402454, 19698.31055913036, 13769.449222031408, 14271.64320891986, 14637.85362146365, 14685.265736712909, 14726.779098313747, 14637.780433293721, 14869.18219497938, 14900.254987250812, 14962.326695298956, 15026.0], "value": 9987.726110997934, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1436.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 105.0, 100.0, 105.0, 105.0, 106.0, 107.0, 97.0, 104.0, 105.0], "value": 103.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 114.0, 149.0, 171.0, 114.0, 139.0, 141.0, 123.0, 166.0, 124.0], "value": 137.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 163.0, 161.0, 162.0, 161.0, 164.0, 164.0, 172.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 171.0, 74.0, 73.0, 74.0, 73.0, 143.0, 74.0], "value": 90.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 132.0, 78.0, 78.0, 78.0, 130.0, 79.0, 78.0, 130.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 116.0, 114.0, 114.0, 114.0, 113.0, 114.0, 113.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 150.0, 149.0, 150.0, 149.0, 150.0, 151.0, 149.0, 150.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 45.0, 47.0, 43.0, 46.0, 44.0, 44.0, 51.0, 43.0, 44.0], "value": 45.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 36.0, 36.0, 37.0, 36.0, 36.0, 38.0, 36.0, 36.0], "value": 36.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 72.0, 71.0, 71.0, 70.0, 72.0, 72.0, 73.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 109.0, 115.0, 116.0, 113.0, 110.0, 117.0, 116.0, 155.0], "value": 118.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 172.0, 157.0, 161.0, 165.0, 156.0, 150.0, 167.0, 153.0, 153.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 66.0, 58.0, 58.0, 64.0, 57.0, 59.0, 59.0, 58.0], "value": 59.8, "unit": "score"}]}]} 16:38:30 INFO - Return code: 0 16:38:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:38:30 INFO - # TBPL SUCCESS # 16:38:30 INFO - Running post-action listener: _package_coverage_data 16:38:30 INFO - Running post-action listener: _resource_record_post_action 16:38:30 INFO - [mozharness: 2017-09-05 23:38:30.445687Z] Finished run-tests step (success) 16:38:30 INFO - Running post-run listener: _resource_record_post_run 16:38:30 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 390963200; Read time: 148; Write time: 239720 16:38:30 INFO - TinderboxPrint: CPU usage
13.1% 16:38:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 148 16:38:30 INFO - TinderboxPrint: I/O write bytes / time
390,963,200 / 239,720 16:38:30 INFO - TinderboxPrint: CPU idle
8,214.4 (86.9%) 16:38:30 INFO - TinderboxPrint: CPU user
1,185.9 (12.5%) 16:38:30 INFO - TinderboxPrint: Swap in / out
0 / 0 16:38:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 99966976; Read time: 0; Write time: 76728 16:38:30 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:38:30 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290996224; Read time: 148; Write time: 162992 16:38:31 INFO - Running post-run listener: _upload_blobber_files 16:38:31 INFO - Blob upload gear active. 16:38:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:38:31 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 16:38:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:38:31 INFO - (blobuploader) - INFO - Open directory for files ... 16:38:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:38:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:38:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:32 INFO - (blobuploader) - INFO - Done attempting. 16:38:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:38:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:38:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:35 INFO - (blobuploader) - INFO - Done attempting. 16:38:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:38:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:38:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:36 INFO - (blobuploader) - INFO - Done attempting. 16:38:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:38:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:38:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:37 INFO - (blobuploader) - INFO - Done attempting. 16:38:37 INFO - (blobuploader) - INFO - Iteration through files over. 16:38:37 INFO - Return code: 0 16:38:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:38:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:38:37 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6faddef889a98bd3d01ebae739c75b7ed224bd51d80370ce277f20468a5e1eaaff7216541e97d8cebfb173133e871e0cc80ca8d7f727afba43452e0eff663a35", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cc81d96863d9c3c684a7c291090147d941260b387e8bff19e70ccec2377076f90ff5e6b3d69db08930b58cf1d87debf6c1c1a37074b424edead291bc9d34e5a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81ebda62bb138d73864a86e90e11cc8a34e707af7e251310647033ae00e539ef691a10ffb0a7ec2a0a5deda03bb9aa6ca7b2c471b529f83ffff5d99529da5a2c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f1212db832e2c1051e0e41679a050f79861d89df8414d675c3b930829d1d6b41737f48903cf9d3012b253cb08901b30e766429006f3cd38291e13024a054297"} 16:38:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:38:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:38:37 INFO - Contents: 16:38:37 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6faddef889a98bd3d01ebae739c75b7ed224bd51d80370ce277f20468a5e1eaaff7216541e97d8cebfb173133e871e0cc80ca8d7f727afba43452e0eff663a35", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cc81d96863d9c3c684a7c291090147d941260b387e8bff19e70ccec2377076f90ff5e6b3d69db08930b58cf1d87debf6c1c1a37074b424edead291bc9d34e5a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81ebda62bb138d73864a86e90e11cc8a34e707af7e251310647033ae00e539ef691a10ffb0a7ec2a0a5deda03bb9aa6ca7b2c471b529f83ffff5d99529da5a2c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f1212db832e2c1051e0e41679a050f79861d89df8414d675c3b930829d1d6b41737f48903cf9d3012b253cb08901b30e766429006f3cd38291e13024a054297"} 16:38:37 INFO - Running post-run listener: copy_logs_to_upload_dir 16:38:37 INFO - Copying logs to upload dir... 16:38:37 INFO - mkdir: /builds/slave/test/build/upload/logs 16:38:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.998232 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-05 16:38:37.754772) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:38:37.759333) ========= 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=1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6faddef889a98bd3d01ebae739c75b7ed224bd51d80370ce277f20468a5e1eaaff7216541e97d8cebfb173133e871e0cc80ca8d7f727afba43452e0eff663a35", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cc81d96863d9c3c684a7c291090147d941260b387e8bff19e70ccec2377076f90ff5e6b3d69db08930b58cf1d87debf6c1c1a37074b424edead291bc9d34e5a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81ebda62bb138d73864a86e90e11cc8a34e707af7e251310647033ae00e539ef691a10ffb0a7ec2a0a5deda03bb9aa6ca7b2c471b529f83ffff5d99529da5a2c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f1212db832e2c1051e0e41679a050f79861d89df8414d675c3b930829d1d6b41737f48903cf9d3012b253cb08901b30e766429006f3cd38291e13024a054297"} build_url:https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011417 build_url: 'https://queue.taskcluster.net/v1/task/MjZZTm4hRByt6qM6fPeTvw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6faddef889a98bd3d01ebae739c75b7ed224bd51d80370ce277f20468a5e1eaaff7216541e97d8cebfb173133e871e0cc80ca8d7f727afba43452e0eff663a35", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cc81d96863d9c3c684a7c291090147d941260b387e8bff19e70ccec2377076f90ff5e6b3d69db08930b58cf1d87debf6c1c1a37074b424edead291bc9d34e5a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81ebda62bb138d73864a86e90e11cc8a34e707af7e251310647033ae00e539ef691a10ffb0a7ec2a0a5deda03bb9aa6ca7b2c471b529f83ffff5d99529da5a2c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4f1212db832e2c1051e0e41679a050f79861d89df8414d675c3b930829d1d6b41737f48903cf9d3012b253cb08901b30e766429006f3cd38291e13024a054297"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:38:37.790787) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:38:37.791134) ========= 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=1f5564757af27664ca02d71f000001b0-1504653473.669574-164146334 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004568 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:38:37.873822) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:38:37.877155) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:38:37.877453) ========= ========= Total master_lag: 0.20 =========