builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-071 starttime: 1505502450.83 results: success (0) revision: 7aceaf8bcb9f582db0f93488b48ef7019e348dba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:30.827801) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:30.828240) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:30.828508) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:30.877357) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:30.877630) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-15 12:07:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.4M=0s 2017-09-15 12:07:31 (45.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139239 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:31.034827) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:31.035136) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021379 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:31.085065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:31.085652) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7aceaf8bcb9f582db0f93488b48ef7019e348dba --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 7aceaf8bcb9f582db0f93488b48ef7019e348dba --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=dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-15 12:07:31,127 truncating revision to first 12 chars 2017-09-15 12:07:31,128 Setting DEBUG logging. 2017-09-15 12:07:31,128 attempt 1/10 2017-09-15 12:07:31,128 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7aceaf8bcb9f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-15 12:07:31,610 unpacking tar archive at: mozilla-central-7aceaf8bcb9f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.780325 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:31.882212) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:31.882513) ========= script_repo_revision: 7aceaf8bcb9f582db0f93488b48ef7019e348dba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:31.882887) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:31.883157) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 12:07:31.896944) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-15 12:07:31.897303) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:07:32 INFO - MultiFileLogger online at 20170915 12:07:32 in /builds/slave/test 12:07:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:07:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:07:32 INFO - {'append_to_log': False, 12:07:32 INFO - 'base_work_dir': '/builds/slave/test', 12:07:32 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:07:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:07:32 INFO - 'branch': 'Firefox-Non-PGO', 12:07:32 INFO - 'buildbot_json_path': 'buildprops.json', 12:07:32 INFO - 'code_coverage': False, 12:07:32 INFO - 'config_files': ('talos/linux_config.py',), 12:07:32 INFO - 'default_actions': ('clobber', 12:07:32 INFO - 'read-buildbot-config', 12:07:32 INFO - 'download-and-extract', 12:07:32 INFO - 'populate-webroot', 12:07:32 INFO - 'create-virtualenv', 12:07:32 INFO - 'install', 12:07:32 INFO - 'setup-mitmproxy', 12:07:32 INFO - 'run-tests'), 12:07:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:07:32 INFO - 'disable_ccov_upload': False, 12:07:32 INFO - 'disable_stylo': False, 12:07:32 INFO - 'download_minidump_stackwalk': True, 12:07:32 INFO - 'download_symbols': 'ondemand', 12:07:32 INFO - 'enable_stylo': False, 12:07:32 INFO - 'enable_webrender': False, 12:07:32 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:07:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:07:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:07:32 INFO - 'gecko_profile': False, 12:07:32 INFO - 'gecko_profile_interval': 0, 12:07:32 INFO - 'installer_path': 'installer.exe', 12:07:32 INFO - 'log_level': 'info', 12:07:32 INFO - 'log_name': 'talos', 12:07:32 INFO - 'log_to_console': True, 12:07:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:07:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:07:32 INFO - 'opt_config_files': (), 12:07:32 INFO - 'pip_index': False, 12:07:32 INFO - 'suite': 'dromaeojs-e10s', 12:07:32 INFO - 'system_bits': '32', 12:07:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:07:32 INFO - 'title': 'talos-linux64-ix-071', 12:07:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:07:32 INFO - 'use_talos_json': True, 12:07:32 INFO - 'verify': 'False', 12:07:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:07:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:07:32 INFO - 'work_dir': 'build'} 12:07:32 INFO - [mozharness: 2017-09-15 19:07:32.081867Z] Running clobber step. 12:07:32 INFO - Running pre-action listener: _resource_record_pre_action 12:07:32 INFO - Running main action method: clobber 12:07:32 INFO - rmtree: /builds/slave/test/build 12:07:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:07:33 INFO - Running post-action listener: _resource_record_post_action 12:07:33 INFO - [mozharness: 2017-09-15 19:07:33.063677Z] Finished clobber step (success) 12:07:33 INFO - [mozharness: 2017-09-15 19:07:33.063774Z] Running read-buildbot-config step. 12:07:33 INFO - Running pre-action listener: _resource_record_pre_action 12:07:33 INFO - Running main action method: read_buildbot_config 12:07:33 INFO - Using buildbot properties: 12:07:33 INFO - { 12:07:33 INFO - "project": "", 12:07:33 INFO - "product": "firefox", 12:07:33 INFO - "who": "ryanvm@gmail.com", 12:07:33 INFO - "installer_path": "public/build/target.tar.bz2", 12:07:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:07:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:07:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 12:07:33 INFO - "stage_platform": "linux64", 12:07:33 INFO - "basedir": "/builds/slave/test", 12:07:33 INFO - "buildnumber": 1152, 12:07:33 INFO - "platform": "ubuntu64_hw", 12:07:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:07:33 INFO - "slavebuilddir": "test", 12:07:33 INFO - "taskId": "L4zkYjEzQv62JU3ZYF-_-A", 12:07:33 INFO - "branch": "mozilla-central", 12:07:33 INFO - "script_repo_revision": "production", 12:07:33 INFO - "revision": "7aceaf8bcb9f582db0f93488b48ef7019e348dba", 12:07:33 INFO - "slavename": "talos-linux64-ix-071", 12:07:33 INFO - "repo_path": "mozilla-central" 12:07:33 INFO - } 12:07:33 INFO - Finding installer, test and symbols from parent task. 12:07:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L4zkYjEzQv62JU3ZYF-_-A'}, attempt #1 12:07:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L4zkYjEzQv62JU3ZYF-_-A'}, attempt #1 12:07:34 INFO - Task dependencies: SAlNeWrsQmaSXz_lbxgWHA 12:07:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA'}, attempt #1 12:07:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.tar.bz2 12:07:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.test_packages.json 12:07:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.crashreporter-symbols.zip 12:07:34 INFO - Running post-action listener: _resource_record_post_action 12:07:34 INFO - [mozharness: 2017-09-15 19:07:34.858967Z] Finished read-buildbot-config step (success) 12:07:34 INFO - [mozharness: 2017-09-15 19:07:34.859182Z] Running download-and-extract step. 12:07:34 INFO - Running pre-action listener: _resource_record_pre_action 12:07:34 INFO - Running main action method: download_and_extract 12:07:34 INFO - mkdir: /builds/slave/test/build/tests 12:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:34 INFO - trying https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.test_packages.json 12:07:34 INFO - Downloading https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:07:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:07:35 INFO - Downloaded 989 bytes. 12:07:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:07:35 INFO - Using the following test package requirements: 12:07:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:07:35 INFO - u'common': [u'target.common.tests.zip'], 12:07:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:07:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:07:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:07:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:07:35 INFO - u'mozbase': [u'target.common.tests.zip'], 12:07:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:07:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:07:35 INFO - u'web-platform': [u'target.common.tests.zip', 12:07:35 INFO - u'target.web-platform.tests.tar.gz'], 12:07:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:07:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:07:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.common.tests.zip 12:07:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:07:35 INFO - Fetch https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.common.tests.zip into memory 12:07:37 INFO - Content-Length response header: 54734895 12:07:37 INFO - Bytes received: 54734895 12:07:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.talos.tests.zip 12:07:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:07:48 INFO - Fetch https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.talos.tests.zip into memory 12:07:49 INFO - Content-Length response header: 14508749 12:07:49 INFO - Bytes received: 14508749 12:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:49 INFO - trying https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.tar.bz2 12:07:49 INFO - Downloading https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:07:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:07:50 INFO - Downloaded 61597254 bytes. 12:07:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.tar.bz2 12:07:50 INFO - mkdir: /builds/slave/test/properties 12:07:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:07:50 INFO - Writing to file /builds/slave/test/properties/build_url 12:07:50 INFO - Contents: 12:07:50 INFO - build_url:https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.tar.bz2 12:07:50 INFO - Running post-action listener: _resource_record_post_action 12:07:50 INFO - Running post-action listener: find_tests_for_verification 12:07:50 INFO - Running post-action listener: set_extra_try_arguments 12:07:50 INFO - [mozharness: 2017-09-15 19:07:50.649144Z] Finished download-and-extract step (success) 12:07:50 INFO - [mozharness: 2017-09-15 19:07:50.649245Z] Running populate-webroot step. 12:07:50 INFO - Running pre-action listener: _resource_record_pre_action 12:07:50 INFO - Running main action method: populate_webroot 12:07:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:07:50 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['tresize', 12:07:50 INFO - 'tcanvasmark']}, 12:07:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:07:50 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['dromaeo_css', 12:07:50 INFO - 'kraken']}, 12:07:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:07:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:50 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:07:50 INFO - 'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:07:50 INFO - 'tests': ['damp', 'tps']}, 12:07:50 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:07:50 INFO - 'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['damp', 'tps']}, 12:07:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:07:50 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['dromaeo_dom']}, 12:07:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:07:50 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['basic_compositor_video', 12:07:50 INFO - 'glvideo']}, 12:07:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:07:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:07:50 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:07:50 INFO - 'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['ts_paint_webext', 12:07:50 INFO - 'tp5o_webext']}, 12:07:50 INFO - 'other-e10s': {'tests': ['a11yr', 12:07:50 INFO - 'ts_paint', 12:07:50 INFO - 'tpaint', 12:07:50 INFO - 'sessionrestore', 12:07:50 INFO - 'sessionrestore_many_windows', 12:07:50 INFO - 'sessionrestore_no_auto_restore', 12:07:50 INFO - 'tabpaint']}, 12:07:50 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['a11yr', 12:07:50 INFO - 'ts_paint', 12:07:50 INFO - 'tpaint', 12:07:50 INFO - 'sessionrestore', 12:07:50 INFO - 'sessionrestore_many_windows', 12:07:50 INFO - 'sessionrestore_no_auto_restore', 12:07:50 INFO - 'tabpaint']}, 12:07:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:07:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:07:50 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['perf_reftest_singletons']}, 12:07:50 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['bloom_basic']}, 12:07:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:07:50 INFO - 'tsvgr_opacity', 12:07:50 INFO - 'tart', 12:07:50 INFO - 'tscrollx', 12:07:50 INFO - 'tsvg_static']}, 12:07:50 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['tsvgx', 12:07:50 INFO - 'tsvgr_opacity', 12:07:50 INFO - 'tart', 12:07:50 INFO - 'tscrollx', 12:07:50 INFO - 'tsvg_static']}, 12:07:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:07:50 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:07:50 INFO - 'talos_options': ['--disable-stylo'], 12:07:50 INFO - 'tests': ['tp5o']}, 12:07:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:07:50 INFO - 'talos_options': ['--mitmproxy', 12:07:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:07:50 INFO - '--firstNonBlankPaint'], 12:07:50 INFO - 'tests': ['tp6_google', 12:07:50 INFO - 'tp6_youtube', 12:07:50 INFO - 'tp6_amazon', 12:07:50 INFO - 'tp6_facebook']}, 12:07:50 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:07:50 INFO - 'talos_options': ['--disable-stylo', 12:07:50 INFO - '--mitmproxy', 12:07:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:07:50 INFO - '--firstNonBlankPaint'], 12:07:50 INFO - 'tests': ['tp6_google', 12:07:50 INFO - 'tp6_youtube', 12:07:50 INFO - 'tp6_amazon', 12:07:50 INFO - 'tp6_facebook']}, 12:07:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:07:50 INFO - 'talos_options': ['--stylo-threads=1', 12:07:50 INFO - '--mitmproxy', 12:07:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:07:50 INFO - '--firstNonBlankPaint'], 12:07:50 INFO - 'tests': ['tp6_google', 12:07:50 INFO - 'tp6_youtube', 12:07:50 INFO - 'tp6_amazon', 12:07:50 INFO - 'tp6_facebook']}, 12:07:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:07:50 INFO - 'talos_options': ['--xperf_path', 12:07:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:50 INFO - 'tests': ['tp5n']}, 12:07:50 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:07:50 INFO - 'talos_options': ['--disable-stylo', 12:07:50 INFO - '--xperf_path', 12:07:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:50 INFO - 'tests': ['tp5n']}}} 12:07:50 INFO - Running post-action listener: _resource_record_post_action 12:07:50 INFO - [mozharness: 2017-09-15 19:07:50.664141Z] Finished populate-webroot step (success) 12:07:50 INFO - [mozharness: 2017-09-15 19:07:50.664230Z] Running create-virtualenv step. 12:07:50 INFO - Running pre-action listener: _resource_record_pre_action 12:07:50 INFO - Running main action method: create_virtualenv 12:07:50 INFO - Creating virtualenv /builds/slave/test/build/venv 12:07:50 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:07:50 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:07:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:07:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:07:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:07:51 INFO - Installing distribute.............................................................................................................................................................................................done. 12:07:52 INFO - Installing pip...............done. 12:07:52 INFO - Return code: 0 12:07:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:07:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:07:52 INFO - Reading from file tmpfile_stdout 12:07:52 INFO - Output received: 12:07:52 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:07:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:07:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1885a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a28a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2c090>, '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 0x1a2cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a26f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', '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 12:07:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:07:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:07:52 INFO - Using env: {'DISPLAY': ':0', 12:07:52 INFO - 'HOME': '/home/cltbld', 12:07:52 INFO - 'LANG': 'en_US.UTF-8', 12:07:52 INFO - 'LANGUAGE': 'en_US:en', 12:07:52 INFO - 'LOGNAME': 'cltbld', 12:07:52 INFO - 'MAIL': '/var/mail/cltbld', 12:07:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:52 INFO - 'MOZ_NO_REMOTE': '1', 12:07:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:52 INFO - 'NO_EM_RESTART': '1', 12:07:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:52 INFO - 'PWD': '/builds/slave/test', 12:07:52 INFO - 'SHELL': '/bin/bash', 12:07:52 INFO - 'SHLVL': '1', 12:07:52 INFO - 'TERM': 'linux', 12:07:52 INFO - 'TMOUT': '86400', 12:07:52 INFO - 'USER': 'cltbld', 12:07:52 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:07:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:52 INFO - '_': '/tools/buildbot/bin/python'} 12:07:53 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:07:53 INFO - Downloading/unpacking pip>=1.5 12:07:53 INFO - Running setup.py egg_info for package pip 12:07:53 INFO - warning: no files found matching 'pip/cacert.pem' 12:07:53 INFO - warning: no files found matching '*.html' under directory 'docs' 12:07:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:07:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:07:53 INFO - Installing collected packages: pip 12:07:53 INFO - Found existing installation: pip 1.1 12:07:53 INFO - Uninstalling pip: 12:07:53 INFO - Successfully uninstalled pip 12:07:53 INFO - Running setup.py install for pip 12:07:53 INFO - warning: no files found matching 'pip/cacert.pem' 12:07:53 INFO - warning: no files found matching '*.html' under directory 'docs' 12:07:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:07:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:07:53 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:07:53 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:07:53 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:07:53 INFO - Successfully installed pip 12:07:53 INFO - Cleaning up... 12:07:53 INFO - Return code: 0 12:07:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:53 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 0x16bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1885a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a28a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2c090>, '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 0x1a2cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a26f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', '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 12:07:53 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 12:07:53 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 12:07:53 INFO - Using env: {'DISPLAY': ':0', 12:07:53 INFO - 'HOME': '/home/cltbld', 12:07:53 INFO - 'LANG': 'en_US.UTF-8', 12:07:53 INFO - 'LANGUAGE': 'en_US:en', 12:07:53 INFO - 'LOGNAME': 'cltbld', 12:07:53 INFO - 'MAIL': '/var/mail/cltbld', 12:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:53 INFO - 'MOZ_NO_REMOTE': '1', 12:07:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:53 INFO - 'NO_EM_RESTART': '1', 12:07:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:53 INFO - 'PWD': '/builds/slave/test', 12:07:53 INFO - 'SHELL': '/bin/bash', 12:07:53 INFO - 'SHLVL': '1', 12:07:53 INFO - 'TERM': 'linux', 12:07:53 INFO - 'TMOUT': '86400', 12:07:53 INFO - 'USER': 'cltbld', 12:07:53 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:53 INFO - '_': '/tools/buildbot/bin/python'} 12:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:54 INFO - Downloading/unpacking psutil>=3.1.1 12:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:07:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:55 INFO - Installing collected packages: psutil 12:07:55 INFO - Running setup.py install for psutil 12:07:55 INFO - building 'psutil._psutil_linux' extension 12:07:55 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 12:07:55 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 12:07:55 INFO - building 'psutil._psutil_posix' extension 12:07:55 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 12:07:55 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 12:07:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:55 INFO - Successfully installed psutil 12:07:55 INFO - Cleaning up... 12:07:55 INFO - Return code: 0 12:07:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:55 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 0x16bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1885a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a28a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2c090>, '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 0x1a2cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a26f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', '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 12:07:55 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 12:07:55 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 12:07:55 INFO - Using env: {'DISPLAY': ':0', 12:07:55 INFO - 'HOME': '/home/cltbld', 12:07:55 INFO - 'LANG': 'en_US.UTF-8', 12:07:55 INFO - 'LANGUAGE': 'en_US:en', 12:07:55 INFO - 'LOGNAME': 'cltbld', 12:07:55 INFO - 'MAIL': '/var/mail/cltbld', 12:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:55 INFO - 'MOZ_NO_REMOTE': '1', 12:07:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:55 INFO - 'NO_EM_RESTART': '1', 12:07:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:55 INFO - 'PWD': '/builds/slave/test', 12:07:55 INFO - 'SHELL': '/bin/bash', 12:07:55 INFO - 'SHLVL': '1', 12:07:55 INFO - 'TERM': 'linux', 12:07:55 INFO - 'TMOUT': '86400', 12:07:55 INFO - 'USER': 'cltbld', 12:07:55 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:55 INFO - '_': '/tools/buildbot/bin/python'} 12:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:07:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:07:57 INFO - Installing collected packages: mozsystemmonitor 12:07:57 INFO - Running setup.py install for mozsystemmonitor 12:07:57 INFO - Successfully installed mozsystemmonitor 12:07:57 INFO - Cleaning up... 12:07:57 INFO - Return code: 0 12:07:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:07:57 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')]} 12:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:57 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')]} 12:07:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:57 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 0x16bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1885a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a28a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2c090>, '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 0x1a2cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a26f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', '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 12:07:57 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 12:07:57 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 12:07:57 INFO - Using env: {'DISPLAY': ':0', 12:07:57 INFO - 'HOME': '/home/cltbld', 12:07:57 INFO - 'LANG': 'en_US.UTF-8', 12:07:57 INFO - 'LANGUAGE': 'en_US:en', 12:07:57 INFO - 'LOGNAME': 'cltbld', 12:07:57 INFO - 'MAIL': '/var/mail/cltbld', 12:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:57 INFO - 'MOZ_NO_REMOTE': '1', 12:07:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:57 INFO - 'NO_EM_RESTART': '1', 12:07:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:57 INFO - 'PWD': '/builds/slave/test', 12:07:57 INFO - 'SHELL': '/bin/bash', 12:07:57 INFO - 'SHLVL': '1', 12:07:57 INFO - 'TERM': 'linux', 12:07:57 INFO - 'TMOUT': '86400', 12:07:57 INFO - 'USER': 'cltbld', 12:07:57 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:57 INFO - '_': '/tools/buildbot/bin/python'} 12:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:58 INFO - Downloading/unpacking jsonschema==2.5.1 12:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:07:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:07:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:07:59 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 12:07:59 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 12:07:59 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 12:07:59 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 12:07:59 INFO - Downloading functools32-3.2.3-2.tar.gz 12:07:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:07:59 INFO - warning: no files found matching '*.txt' 12:07:59 INFO - no previously-included directories found matching 'build' 12:07:59 INFO - no previously-included directories found matching 'dist' 12:07:59 INFO - no previously-included directories found matching '.git*' 12:07:59 INFO - Installing collected packages: jsonschema, functools32 12:07:59 INFO - Running setup.py install for functools32 12:07:59 INFO - warning: no files found matching '*.txt' 12:07:59 INFO - no previously-included directories found matching 'build' 12:07:59 INFO - no previously-included directories found matching 'dist' 12:07:59 INFO - no previously-included directories found matching '.git*' 12:08:00 INFO - Successfully installed jsonschema functools32 12:08:00 INFO - Cleaning up... 12:08:00 INFO - Return code: 0 12:08:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1885a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a28a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2c090>, '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 0x1a2cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a26f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', '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 12:08:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:08:00 INFO - Using env: {'DISPLAY': ':0', 12:08:00 INFO - 'HOME': '/home/cltbld', 12:08:00 INFO - 'LANG': 'en_US.UTF-8', 12:08:00 INFO - 'LANGUAGE': 'en_US:en', 12:08:00 INFO - 'LOGNAME': 'cltbld', 12:08:00 INFO - 'MAIL': '/var/mail/cltbld', 12:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:00 INFO - 'MOZ_NO_REMOTE': '1', 12:08:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:00 INFO - 'NO_EM_RESTART': '1', 12:08:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:00 INFO - 'PWD': '/builds/slave/test', 12:08:00 INFO - 'SHELL': '/bin/bash', 12:08:00 INFO - 'SHLVL': '1', 12:08:00 INFO - 'TERM': 'linux', 12:08:00 INFO - 'TMOUT': '86400', 12:08:00 INFO - 'USER': 'cltbld', 12:08:00 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:00 INFO - '_': '/tools/buildbot/bin/python'} 12:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:08:00 INFO - Cleaning up... 12:08:00 INFO - Return code: 0 12:08:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1885a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a28a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2c090>, '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 0x1a2cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a26f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', '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 12:08:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:08:00 INFO - Using env: {'DISPLAY': ':0', 12:08:00 INFO - 'HOME': '/home/cltbld', 12:08:00 INFO - 'LANG': 'en_US.UTF-8', 12:08:00 INFO - 'LANGUAGE': 'en_US:en', 12:08:00 INFO - 'LOGNAME': 'cltbld', 12:08:00 INFO - 'MAIL': '/var/mail/cltbld', 12:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:00 INFO - 'MOZ_NO_REMOTE': '1', 12:08:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:00 INFO - 'NO_EM_RESTART': '1', 12:08:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:00 INFO - 'PWD': '/builds/slave/test', 12:08:00 INFO - 'SHELL': '/bin/bash', 12:08:00 INFO - 'SHLVL': '1', 12:08:00 INFO - 'TERM': 'linux', 12:08:00 INFO - 'TMOUT': '86400', 12:08:00 INFO - 'USER': 'cltbld', 12:08:00 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:00 INFO - '_': '/tools/buildbot/bin/python'} 12:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:00 INFO - Downloading/unpacking blobuploader==1.2.4 12:08:00 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 12:08:00 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 12:08:00 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 12:08:00 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 12:08:01 INFO - Downloading blobuploader-1.2.4.tar.gz 12:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:08:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:08:01 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 12:08:01 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 12:08:01 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 12:08:01 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 12:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:08:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:08:01 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 12:08:01 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 12:08:01 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 12:08:01 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 12:08:02 INFO - Downloading docopt-0.6.1.tar.gz 12:08:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:08:02 INFO - Installing collected packages: blobuploader, requests, docopt 12:08:02 INFO - Running setup.py install for blobuploader 12:08:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:08:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:08:02 INFO - Running setup.py install for requests 12:08:02 INFO - Running setup.py install for docopt 12:08:02 INFO - Successfully installed blobuploader requests docopt 12:08:02 INFO - Cleaning up... 12:08:02 INFO - Return code: 0 12:08:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:02 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')]} 12:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:02 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')]} 12:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:02 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 0x16bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1885a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a28a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2c090>, '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 0x1a2cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a26f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', '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 12:08:02 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 12:08:02 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 12:08:02 INFO - Using env: {'DISPLAY': ':0', 12:08:02 INFO - 'HOME': '/home/cltbld', 12:08:02 INFO - 'LANG': 'en_US.UTF-8', 12:08:02 INFO - 'LANGUAGE': 'en_US:en', 12:08:02 INFO - 'LOGNAME': 'cltbld', 12:08:02 INFO - 'MAIL': '/var/mail/cltbld', 12:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:02 INFO - 'MOZ_NO_REMOTE': '1', 12:08:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:02 INFO - 'NO_EM_RESTART': '1', 12:08:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:02 INFO - 'PWD': '/builds/slave/test', 12:08:02 INFO - 'SHELL': '/bin/bash', 12:08:02 INFO - 'SHLVL': '1', 12:08:02 INFO - 'TERM': 'linux', 12:08:02 INFO - 'TMOUT': '86400', 12:08:02 INFO - 'USER': 'cltbld', 12:08:02 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:02 INFO - '_': '/tools/buildbot/bin/python'} 12:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:02 INFO - Running setup.py (path:/tmp/pip-03620Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:03 INFO - Running setup.py (path:/tmp/pip-3U2mdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:03 INFO - Running setup.py (path:/tmp/pip-MTIgeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:03 INFO - Running setup.py (path:/tmp/pip-7TVex9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:03 INFO - Running setup.py (path:/tmp/pip-k7_w6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:03 INFO - Running setup.py (path:/tmp/pip-Gjb0Uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:03 INFO - Running setup.py (path:/tmp/pip-96wiMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:03 INFO - Running setup.py (path:/tmp/pip-j_WK8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:03 INFO - Running setup.py (path:/tmp/pip-99WLJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:03 INFO - Running setup.py (path:/tmp/pip-XKD3df-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:03 INFO - Running setup.py (path:/tmp/pip-BLB8jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:03 INFO - Running setup.py (path:/tmp/pip-0K4y46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:03 INFO - Running setup.py (path:/tmp/pip-mipFGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:03 INFO - Running setup.py (path:/tmp/pip-O1rkDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:03 INFO - Running setup.py (path:/tmp/pip-U_GCJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:04 INFO - Running setup.py (path:/tmp/pip-yR7ue5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:04 INFO - Running setup.py (path:/tmp/pip-pOFTdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:08:04 INFO - Running setup.py install for manifestparser 12:08:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:08:04 INFO - Running setup.py install for mozcrash 12:08:04 INFO - Running setup.py install for mozdebug 12:08:04 INFO - Running setup.py install for mozdevice 12:08:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:08:04 INFO - Running setup.py install for mozfile 12:08:04 INFO - Running setup.py install for mozhttpd 12:08:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:08:04 INFO - Running setup.py install for mozinfo 12:08:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:08:04 INFO - Running setup.py install for mozInstall 12:08:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:08:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:08:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:08:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:08:04 INFO - Running setup.py install for mozleak 12:08:05 INFO - Running setup.py install for mozlog 12:08:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:08:05 INFO - Running setup.py install for moznetwork 12:08:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:08:05 INFO - Running setup.py install for mozprocess 12:08:05 INFO - Running setup.py install for mozprofile 12:08:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:08:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:08:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:08:05 INFO - Running setup.py install for mozrunner 12:08:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:08:05 INFO - Running setup.py install for mozscreenshot 12:08:05 INFO - Running setup.py install for moztest 12:08:05 INFO - Running setup.py install for mozversion 12:08:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:08:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:08:05 INFO - Cleaning up... 12:08:05 INFO - Return code: 0 12:08:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:05 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')]} 12:08:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:05 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')]} 12:08:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:05 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 0x16bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1885a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a28a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2c090>, '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 0x1a2cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a26f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', '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 12:08:05 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 12:08:05 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 12:08:05 INFO - Using env: {'DISPLAY': ':0', 12:08:05 INFO - 'HOME': '/home/cltbld', 12:08:05 INFO - 'LANG': 'en_US.UTF-8', 12:08:05 INFO - 'LANGUAGE': 'en_US:en', 12:08:05 INFO - 'LOGNAME': 'cltbld', 12:08:05 INFO - 'MAIL': '/var/mail/cltbld', 12:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:05 INFO - 'MOZ_NO_REMOTE': '1', 12:08:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:05 INFO - 'NO_EM_RESTART': '1', 12:08:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:05 INFO - 'PWD': '/builds/slave/test', 12:08:05 INFO - 'SHELL': '/bin/bash', 12:08:05 INFO - 'SHLVL': '1', 12:08:05 INFO - 'TERM': 'linux', 12:08:05 INFO - 'TMOUT': '86400', 12:08:05 INFO - 'USER': 'cltbld', 12:08:05 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:05 INFO - '_': '/tools/buildbot/bin/python'} 12:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:06 INFO - Running setup.py (path:/tmp/pip-trLfyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:06 INFO - Running setup.py (path:/tmp/pip-W5epOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:06 INFO - Running setup.py (path:/tmp/pip-uN9zve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:06 INFO - Running setup.py (path:/tmp/pip-hDtv1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:06 INFO - Running setup.py (path:/tmp/pip-AylHqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:06 INFO - Running setup.py (path:/tmp/pip-FxDUYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:06 INFO - Running setup.py (path:/tmp/pip-2Ipb_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:06 INFO - Running setup.py (path:/tmp/pip-LApk7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:06 INFO - Running setup.py (path:/tmp/pip-eVHwLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:06 INFO - Running setup.py (path:/tmp/pip-WOAQOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:06 INFO - Running setup.py (path:/tmp/pip-biZDPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:06 INFO - Running setup.py (path:/tmp/pip-8TF922-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:06 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)) 12:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:07 INFO - Running setup.py (path:/tmp/pip-RyMuXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:07 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)) 12:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:07 INFO - Running setup.py (path:/tmp/pip-c6Gntf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:07 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)) 12:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:07 INFO - Running setup.py (path:/tmp/pip-eVPMte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:07 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)) 12:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:07 INFO - Running setup.py (path:/tmp/pip-qon2Sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:07 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)) 12:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:07 INFO - Running setup.py (path:/tmp/pip-qFlivB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:07 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)) 12:08:07 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)) 12:08:07 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)) 12:08:07 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)) 12:08:07 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)) 12:08:07 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)) 12:08:07 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)) 12:08:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:08:07 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 12:08:07 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 12:08:07 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 12:08:07 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 12:08:08 INFO - Downloading blessings-1.6.tar.gz 12:08:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:08:08 INFO - Installing collected packages: blessings 12:08:08 INFO - Running setup.py install for blessings 12:08:08 INFO - Successfully installed blessings 12:08:08 INFO - Cleaning up... 12:08:08 INFO - Return code: 0 12:08:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:08:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:08:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:08:08 INFO - Reading from file tmpfile_stdout 12:08:08 INFO - Current package versions: 12:08:08 INFO - argparse == 1.2.1 12:08:08 INFO - blessings == 1.6 12:08:08 INFO - blobuploader == 1.2.4 12:08:08 INFO - docopt == 0.6.1 12:08:08 INFO - functools32 == 3.2.3-2 12:08:08 INFO - jsonschema == 2.5.1 12:08:08 INFO - manifestparser == 1.1 12:08:08 INFO - mozInstall == 1.13 12:08:08 INFO - mozcrash == 1.0 12:08:08 INFO - mozdebug == 0.1 12:08:08 INFO - mozdevice == 0.51 12:08:08 INFO - mozfile == 1.2 12:08:08 INFO - mozhttpd == 0.7 12:08:08 INFO - mozinfo == 0.10 12:08:08 INFO - mozleak == 0.1 12:08:08 INFO - mozlog == 3.5 12:08:08 INFO - moznetwork == 0.27 12:08:08 INFO - mozprocess == 0.25 12:08:08 INFO - mozprofile == 0.28 12:08:08 INFO - mozrunner == 6.13 12:08:08 INFO - mozscreenshot == 0.1 12:08:08 INFO - mozsystemmonitor == 0.3 12:08:08 INFO - moztest == 0.8 12:08:08 INFO - mozversion == 1.4 12:08:08 INFO - psutil == 3.1.1 12:08:08 INFO - requests == 1.2.3 12:08:08 INFO - wsgiref == 0.1.2 12:08:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:08 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 0x16bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1885a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a28a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2c090>, '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 0x1a2cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a26f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', '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 12:08:08 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 12:08:08 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 12:08:08 INFO - Using env: {'DISPLAY': ':0', 12:08:08 INFO - 'HOME': '/home/cltbld', 12:08:08 INFO - 'LANG': 'en_US.UTF-8', 12:08:08 INFO - 'LANGUAGE': 'en_US:en', 12:08:08 INFO - 'LOGNAME': 'cltbld', 12:08:08 INFO - 'MAIL': '/var/mail/cltbld', 12:08:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:08 INFO - 'MOZ_NO_REMOTE': '1', 12:08:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:08 INFO - 'NO_EM_RESTART': '1', 12:08:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:08 INFO - 'PWD': '/builds/slave/test', 12:08:08 INFO - 'SHELL': '/bin/bash', 12:08:08 INFO - 'SHLVL': '1', 12:08:08 INFO - 'TERM': 'linux', 12:08:08 INFO - 'TMOUT': '86400', 12:08:08 INFO - 'USER': 'cltbld', 12:08:08 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:08:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:08 INFO - '_': '/tools/buildbot/bin/python'} 12:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:09 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)) 12:08:09 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)) 12:08:09 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)) 12:08:09 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)) 12:08:09 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)) 12:08:09 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)) 12:08:09 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)) 12:08:09 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)) 12:08:09 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)) 12:08:09 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:08:09 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 12:08:09 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 12:08:09 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 12:08:09 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 12:08:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:08:10 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)) 12:08:10 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)) 12:08:10 INFO - Installing collected packages: simplejson 12:08:10 INFO - Running setup.py install for simplejson 12:08:10 INFO - building 'simplejson._speedups' extension 12:08:10 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 12:08:11 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 12:08:11 INFO - Successfully installed simplejson 12:08:11 INFO - Cleaning up... 12:08:11 INFO - Return code: 0 12:08:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1885a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a28a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2c090>, '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 0x1a2cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a26f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', '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 12:08:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:08:11 INFO - Using env: {'DISPLAY': ':0', 12:08:11 INFO - 'HOME': '/home/cltbld', 12:08:11 INFO - 'LANG': 'en_US.UTF-8', 12:08:11 INFO - 'LANGUAGE': 'en_US:en', 12:08:11 INFO - 'LOGNAME': 'cltbld', 12:08:11 INFO - 'MAIL': '/var/mail/cltbld', 12:08:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:11 INFO - 'MOZ_NO_REMOTE': '1', 12:08:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:11 INFO - 'NO_EM_RESTART': '1', 12:08:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:11 INFO - 'PWD': '/builds/slave/test', 12:08:11 INFO - 'SHELL': '/bin/bash', 12:08:11 INFO - 'SHLVL': '1', 12:08:11 INFO - 'TERM': 'linux', 12:08:11 INFO - 'TMOUT': '86400', 12:08:11 INFO - 'USER': 'cltbld', 12:08:11 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:08:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:11 INFO - '_': '/tools/buildbot/bin/python'} 12:08:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:08:11 INFO - Cleaning up... 12:08:11 INFO - Return code: 0 12:08:11 INFO - Running post-action listener: _resource_record_post_action 12:08:11 INFO - Running post-action listener: _start_resource_monitoring 12:08:11 INFO - Starting resource monitoring. 12:08:11 INFO - [mozharness: 2017-09-15 19:08:11.621765Z] Finished create-virtualenv step (success) 12:08:11 INFO - [mozharness: 2017-09-15 19:08:11.622283Z] Running install step. 12:08:11 INFO - Running pre-action listener: _resource_record_pre_action 12:08:11 INFO - Running main action method: install 12:08:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:08:11 INFO - Reading from file tmpfile_stdout 12:08:11 INFO - Detecting whether we're running mozinstall >=1.0... 12:08:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:08:11 INFO - Reading from file tmpfile_stdout 12:08:11 INFO - Output received: 12:08:11 INFO - Usage: mozinstall [options] installer 12:08:11 INFO - Options: 12:08:11 INFO - -h, --help show this help message and exit 12:08:11 INFO - -d DEST, --destination=DEST 12:08:11 INFO - Directory to install application into. [default: 12:08:11 INFO - "/builds/slave/test"] 12:08:11 INFO - --app=APP Application being installed. [default: firefox] 12:08:11 INFO - mkdir: /builds/slave/test/build/application 12:08:11 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'] 12:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:08:29 INFO - Reading from file tmpfile_stdout 12:08:29 INFO - Output received: 12:08:29 INFO - /builds/slave/test/build/application/firefox/firefox 12:08:29 INFO - Running post-action listener: _resource_record_post_action 12:08:29 INFO - [mozharness: 2017-09-15 19:08:29.711582Z] Finished install step (success) 12:08:29 INFO - [mozharness: 2017-09-15 19:08:29.711836Z] Running setup-mitmproxy step. 12:08:29 INFO - Running pre-action listener: _resource_record_pre_action 12:08:29 INFO - Running main action method: setup_mitmproxy 12:08:29 INFO - Skipping: mitmproxy is not required 12:08:29 INFO - Running post-action listener: _resource_record_post_action 12:08:29 INFO - [mozharness: 2017-09-15 19:08:29.712971Z] Finished setup-mitmproxy step (success) 12:08:29 INFO - [mozharness: 2017-09-15 19:08:29.713272Z] Running run-tests step. 12:08:29 INFO - Running pre-action listener: _resource_record_pre_action 12:08:29 INFO - Running pre-action listener: _set_gcov_prefix 12:08:29 INFO - Running main action method: run_tests 12:08:29 WARNING - Try message not found. 12:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:08:29 INFO - Python 2.7.3 12:08:29 INFO - Return code: 0 12:08:29 INFO - grabbing minidump binary from tooltool 12:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:29 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 0x1a2c090>, '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 0x1a2cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a26f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:08:29 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 12:08:29 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 12:08:29 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 12:08:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:08:29 INFO - Return code: 0 12:08:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:08:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:08:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:08:29 INFO - ENV: RUST_BACKTRACE is now full 12:08:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:08:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--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 12:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-071 --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 12:08:29 INFO - Using env: {'DISPLAY': ':0', 12:08:29 INFO - 'HOME': '/home/cltbld', 12:08:29 INFO - 'LANG': 'en_US.UTF-8', 12:08:29 INFO - 'LANGUAGE': 'en_US:en', 12:08:29 INFO - 'LOGNAME': 'cltbld', 12:08:29 INFO - 'MAIL': '/var/mail/cltbld', 12:08:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:08:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:29 INFO - 'MOZ_NO_REMOTE': '1', 12:08:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:08:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:29 INFO - 'NO_EM_RESTART': '1', 12:08:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:29 INFO - 'PWD': '/builds/slave/test', 12:08:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:08:29 INFO - 'RUST_BACKTRACE': 'full', 12:08:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:08:29 INFO - 'SHELL': '/bin/bash', 12:08:29 INFO - 'SHLVL': '1', 12:08:29 INFO - 'TERM': 'linux', 12:08:29 INFO - 'TMOUT': '86400', 12:08:29 INFO - 'USER': 'cltbld', 12:08:29 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:29 INFO - '_': '/tools/buildbot/bin/python'} 12:08:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--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 12:08:30 INFO - mozversion application_buildid: 20170915182012 12:08:30 INFO - mozversion application_changeset: 7aceaf8bcb9f582db0f93488b48ef7019e348dba 12:08:30 INFO - mozversion application_display_name: Nightly 12:08:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:08:30 INFO - mozversion application_name: Firefox 12:08:30 INFO - mozversion application_remotingname: firefox 12:08:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:08:30 INFO - mozversion application_vendor: Mozilla 12:08:30 INFO - mozversion application_version: 57.0a1 12:08:30 INFO - mozversion platform_buildid: 20170915182012 12:08:30 INFO - mozversion platform_changeset: 7aceaf8bcb9f582db0f93488b48ef7019e348dba 12:08:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:08:30 INFO - mozversion platform_version: 57.0a1 12:08:30 INFO - using testdate: 1505502510 12:08:30 INFO - actual date: 1505502510 12:08:30 INFO - starting webserver on 'localhost:46960' 12:08:30 INFO - SUITE-START | Running 2 tests 12:08:30 INFO - TEST-START | dromaeo_css 12:08:30 INFO - Initialising browser for dromaeo_css test... 12:08:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46960/getInfo.html -profile /tmp/tmpyxwt1J/profile 12:08:30 INFO - TEST-INFO | started process 7354 (/builds/slave/test/build/application/firefox/firefox http://localhost:46960/getInfo.html) 12:08:35 INFO - TEST-INFO | 7354: exit 0 12:08:35 INFO - Browser initialized. 12:08:35 INFO - Running cycle 1/1 for dromaeo_css test... 12:08:35 INFO - Using env: {'DISPLAY': ':0', 12:08:35 INFO - 'HOME': '/home/cltbld', 12:08:35 INFO - 'JSGC_DISABLE_POISONING': '1', 12:08:35 INFO - 'LANG': 'en_US.UTF-8', 12:08:35 INFO - 'LANGUAGE': 'en_US:en', 12:08:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:08:35 INFO - 'LOGNAME': 'cltbld', 12:08:35 INFO - 'MAIL': '/var/mail/cltbld', 12:08:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:08:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:08:35 INFO - 'MOZ_CRASHREPORTER': '1', 12:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:08:35 INFO - 'MOZ_NO_REMOTE': '1', 12:08:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:08:35 INFO - 'NO_EM_RESTART': '1', 12:08:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:35 INFO - 'PWD': '/builds/slave/test', 12:08:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:08:35 INFO - 'RUST_BACKTRACE': 'full', 12:08:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:08:35 INFO - 'SHELL': '/bin/bash', 12:08:35 INFO - 'SHLVL': '1', 12:08:35 INFO - 'TERM': 'linux', 12:08:35 INFO - 'TMOUT': '86400', 12:08:35 INFO - 'USER': 'cltbld', 12:08:35 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:35 INFO - '_': '/tools/buildbot/bin/python'} 12:08:35 INFO - TEST-INFO | started process 7583 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyxwt1J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:08:36 INFO - PID 7583 | 12:08:36 INFO - PID 7583 | (/builds/slave/test/build/application/firefox/firefox:7633): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:08:36 INFO - PID 7583 | 12:08:46 INFO - PID 7583 | RSS: Main: 173645824 12:08:46 INFO - PID 7583 | 12:11:42 INFO - PID 7583 | Cycle 1(1): loaded http://localhost:46960/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46960/tests/dromaeo/cssquery-ext.html) 12:11:44 INFO - PID 7583 | RSS: Main: 176820224 12:11:44 INFO - PID 7583 | 12:14:46 INFO - PID 7583 | Cycle 1(1): loaded http://localhost:46960/tests/dromaeo/cssquery-ext.html (next: http://localhost:46960/tests/dromaeo/cssquery-jquery.html) 12:14:47 INFO - PID 7583 | RSS: Main: 173117440 12:14:47 INFO - PID 7583 | 12:17:48 INFO - PID 7583 | Cycle 1(1): loaded http://localhost:46960/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46960/tests/dromaeo/cssquery-mootools.html) 12:17:49 INFO - PID 7583 | RSS: Main: 175185920 12:17:49 INFO - PID 7583 | 12:20:51 INFO - PID 7583 | Cycle 1(1): loaded http://localhost:46960/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46960/tests/dromaeo/cssquery-prototype.html) 12:20:51 INFO - PID 7583 | RSS: Main: 173346816 12:20:51 INFO - PID 7583 | 12:23:55 INFO - PID 7583 | Cycle 1(1): loaded http://localhost:46960/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46960/tests/dromaeo/cssquery-yui.html) 12:23:55 INFO - PID 7583 | RSS: Main: 175210496 12:23:55 INFO - PID 7583 | 12:26:57 INFO - PID 7583 | Cycle 1(1): loaded http://localhost:46960/tests/dromaeo/cssquery-yui.html (next: http://localhost:46960/tests/dromaeo/cssquery-dojo.html) 12:26:57 INFO - PID 7583 | RSS: Main: 178184192 12:26:57 INFO - PID 7583 | 12:26:57 INFO - PID 7583 | __start_tp_report 12:26:57 INFO - PID 7583 | _x_x_mozilla_page_load 12:26:57 INFO - PID 7583 | _x_x_mozilla_page_load_details 12:26:57 INFO - PID 7583 | |i|pagename|runs| 12:26:57 INFO - PID 7583 | |0;dojo.html;3079.276370053037;3101.8914337998176;3114.7663925205607;3128.124125244932;3156;6261.279952805428;6304.684765761711;6305.968470157649;6312.652804095773;6342.904856427157;6137.785177518787;6205.131281620576;6228.439440450358;6239.532035097363;6264.373562643734;5665.745041473133;5687.573431992597;5725.055365864628;5766.365699773021;5841.591088623798;5781.132830075501;5786.189933409326;5820.097884827858;5831.854203644922;5832.56255780816;5552.139418390156;5749.166370876242;5790.36306006339;5821.3887541808135;5841.591088623798;5643.717814109319;5701.087825947829;5736.368999410061;5749.080147176432;5756.7985120521;5667.149927510865;5678.6024978251535;5692.0608099663505;5750.511206547449;5767.971160144172;6064.06007068905;6064.727087281083;6126.326104128542;6162.952298109332;6226.470749986212;6525.119108920282;6584.604923704593;6590.637514936677;6591.769288074942;6592.472602191813;6669.699863506154;6688.398044176048;6788.864222715518;6838.213605435389;6849.965750171267;6253.7185826637915;6311.810645680637;6325.240971083454;6349.206349206349;6364;6897.965510172417;6898.551594146365;6901.413379862667;6928.549644273207;6994.300569943067;6384.648844313514;6453.870922581522;6556.082148499214;6633.9336606633315;6634.469242460592;6652.83367915806;6662.300458451889;6730.158730158731;6752.628605426748;6758.898616520756;6600.669966501656;6636.568623039487;6637.004449332639;6704.530682852251;6725.226598941184;6559.934400656028;6616.305287944792;6639.867202656016;6705.161854768154;6719.529632925746;5234.162533994542;5242.213667949762;5253.290805741252;5262.236975638511;5264.31563896691;4611.7232966021475;4675.018246168343;4690;4702.177119004161;4708.835190768306;5741.684207368635;5742.799002034993;5812.447817457335;5819.912701309484;5820;6618.371254730793;6720.22717387497;6722.764703235363;6804.795856124324;6808.62552559614;5691.26013618227;5703.258576385043;5704.515116215085;5724.79963201286;5726.856828579319;5745.683987380734;5772.7113644317615;5777.19119323295;5788.652680839079;5790.94209057904;6386.07401926718;6449.838754031187;6481.546291759531;6490.4807615390655;6493.31820158886;6697.296783837627;6709.127813384229;6799.5920244785475;6807.285235050248;6823.522353435212;6235.376462353728;6263.123162757127;6319.778807741707;6347.96826015867;6392.712327945346;6483.643399613066;6505;6559.672016399066;6585.341465853568;6677.565958212701;6709.463242940651;6746.460283177236;6750.966245168743;6751.56114852533;6771.62756048422;6495.512836537184;6511.967440162769;6530;6533.477321814338;6607.80176594703;6457.160569126171;6493.253275873335;6509.479241660751;6602.603843769197;6621;6442.967785161044;6465.191851018622;6469.514786391133;6479;6482.448991835747;6541.70562324687;6611.966940165268;6617.239017513048;6634.800955971328;6639.966800165968;6358.1734374531015;6381.361863813581;6430.807075787734;6434.67826608677;6434.774782882577 12:26:57 INFO - PID 7583 | |1;ext.html;856.4903882190097;881.6429346114821;882.2236431939892;883.3198437203354;887.148337595908;39467.802660986694;42869.35695964559;42877;42996.78501607488;43046.78476607616;38463.230735385274;38496.230075398475;38547.42178867312;38730.4190437143;38754.80622596883;7023.578585284887;7095.787126386191;7143.535670181447;7732.536047837119;7932.841343173133;7817.648205830751;7906.090799558067;7911.564863932482;7914.683412663515;7921.089074756419;7885.763427097168;7916.920830791676;7936.2063793620755;7936.880946785803;7940.2059794020715;7804.063512378522;7857.1357150713375;7873.685052597889;7888.171741967069;7927.920720792776;7827.921720782776;7850.214978502104;7885.921140788576;7920.247576480225;7931.841363172704;37766.43350349747;37833.24333513342;38031;38088;38113.23773524542;3556.3598552260582;3590.982045089784;3601.567811862567;3607.314610224049;3608.0799396154075;31876.840615796773;31905.680943190502;32106.839465802754;32203.355932881444;32241.355172896645;5177.637565370425;5184.662996905189;5198.870028249286;5199.012187684328;5209.0363282792805;194391;194990;196022;196839;197135;43316.56683433188;44450.33324500135;49042.56702030823;49332.26001609979;49385.50614493881;50023;51071.74464127656;51228.74385628048;51298;51391.74304128456;15255.542333730005;15256.694866102616;15333;15377;15426;12655.746885062246;12677.175983561223;12711.61865144047;12721.109522333349;12722.745545089047;13600.911927045812;13655.112417692819;13664.93167534156;13794.65513362174;13856.792148117785;5661.405552417019;5732.525438206503;5755.625884317506;5764.5676574257095;5772.393898640582;1015.5176291261739;1031.2626472072504;1044.6500422358442;1046.2048842879467;1049.3660911113009;22608.434789130402;22819.20132795344;23077.653835192486;23105.653415198787;23267.88366058159;1124.943752812356;1132.8923752243525;1139.3619573039125;1142.868570114431;1146.2721172055637;11784.528618855149;12053.63839084829;12105.939470302592;12236.143469957196;12285.262884226797;213633;215220;215418;215673;216378;53595.19607205895;53633;53690.73154634202;53761.46238537643;53913;1424.7649137892338;1441.6035590212566;1449.4202319072456;1460.6056364781662;1462.393106860641;22778;22857.542849142592;22901.31296061121;23188.76811231866;23524.411889702893;37393.43909841409;37428.06429839276;37485.2502949934;37562.81218593999;37591.43612845755;188793;189904;190167.0491647533;190194;190423;33313.167170820925;33471.16322091967;34095.48856767101;35995.46006809952;36002.27995440129;45501.54498454973;46248.30627540522;46307.76846115748;46439.30341044819;46964.295535567675;33115.83442082774;33148.834255828566;33512;33955.660443396235;34013.14967125743;33591.16022099369;33640.663593364734;33977;34098;34295.65704343024;37185.07037324089;37254.44118338173;37471.250574988895;37594.81202594079;37607.43588846115;32858.17854553655;33133.33733325272;33209.16977075592;33297.667023330425;33436;23187.188448404224;23613.409664758517;24044.15845445471;24114.156004539756;24579 12:26:57 INFO - PID 7583 | |2;jquery.html;557.9163125531172;559.2701524510514;562.1960596347225;563.155267099351;571.7941541045222;21615.567688646217;22440.55118897621;22514.324570262917;22529.324120276397;22545.323640290775;22561.887190564023;23700.881495592497;23999.76000239997;24019.759802402015;24056.639150412757;6352;6445.967770161143;6478.157839480861;6478.967605161967;6485.6108633482145;6381.744730210786;6497.740090396378;6522.771702990396;6525;6529.575577587465;6269.749210031616;6516.859549578829;6550.606963582177;6551.279359270477;6551.901721474182;6497.772577959749;6512.837179070514;6546.27990921003;6566.967165164167;6568.540202185849;6038;6074.96962515189;6078.939210607926;6084.665343406111;6085.847853803646;27162;27708.861455692793;28270.293242669097;28311;28352.574711379344;2067.2764532413685;2094.7276854009033;2111.4088055344528;2118.4386137673528;2119.4065661614773;18871.433856984175;19430.514237144183;19473.805261947342;19478.02609869515;19537.902310488356;2393.305941277045;2453.963190552125;2496.014074440604;2496.9512804621922;2502.436951685856;119625;124507;133246;137109;139345.99999999898;14232.85767142336;14265.215413152377;14424.639384015483;14582.781258281135;14714.264286785618;22628.547429051327;22663.773362266496;22664.773352266267;22675.773242267696;22713.886430568073;9579.425234485814;9898.703038908845;9928.602855885683;9941.900580994243;9961.252906031932;9049.230815380635;9053.411528250643;9131.58013126522;9145;9299.163075323253;9018.458892466473;9083.909160908439;9122.589483473259;9126.908730912739;9299.90700093004;2594.15689900783;2621.868906554703;2648.6556747622685;2662.0948877381784;2745.6018877262686;942.4769253064478;958.3148049144895;964.1081999150757;966.9122237482832;980.8724865767405;18616.255349786126;18636.44090677282;18657.818580979834;18718.71921921172;18995.240190392506;1006.8993100690019;1026.2508368890751;1026.5380578739598;1031.5512751952924;1039.2880876599554;9991.750206244902;10188.286819922534;10516.053555180071;10550;10609.734756631146;484014;499711;507170;513993;518024;103706.4814675937;107910;108157;108699.45650271856;110675.44662276637;1035.1304903880775;1073.5330131392868;1090.2041509697806;1139.1659428320397;1150.9482073306888;19552.60894782125;19702;19841.404757857286;19921.601567968268;20040.098195581526;54776.726116369166;55262.447375527336;55705.72147139238;55920;56791.432085678614;69445;70931.93602096075;70998;71064.64467677628;71331;13875.375608097458;13948.093373930662;13969.231692256617;14088.295585220903;14097;21456.463588410414;21790.673139903232;21846.563068738855;22011.77988220097;22074;17593.384231551834;17844.732329014816;18027.72958405599;18030.63938721192;18169.364072257937;15979.680406392039;16242.837571624605;16614.833851661813;16671.166441677626;16675.416360427327;17804;18231;18276.72584911201;18318;18543.90728046351;13894.930525347309;14023.298835058002;14049.227292498601;14072.718545629235;14083.577492675236;17532.561685957953;17603.383881564085;17677.823221767616;17785.555361116076;17872.285108596032 12:26:57 INFO - PID 7583 | |3;mootools.html;1302.9869701302985;1342.5502456677013;1369.732902084094;1371.51311284494;1372.0807059270294;14956.177410242432;15447.845521544781;15528.301226444808;15602.063876167424;15613.765793513106;15050.473233436833;15101.093934363946;15155.6968860623;15266.389344426238;15300.31148598313;2684.8657567121613;2805.172474120129;2817.915462536127;2821.3369858083333;2823.1389426225096;2668.2262143978223;2684.087410280504;2691.4886171627427;2691.6904555976116;2699.8650067496646;2684.2484104450787;2811.9578206326923;2917.8541072946373;2923.6112846397896;2924.1958461423174;2667.0931883159624;2778.097118436506;2793.7206279372103;2799.510085734988;2833.5183018886837;2695.716949720271;2819.915402537927;2820.393615372684;2823.1530540837666;2824.844633545154;15455.768163477556;15657.373705051785;15703.136327501983;15720;15747.606309842346;8009.118996910335;8238.393513264915;8262.42163048587;8270.958645206796;8288.21261980111;5417.878210899719;5470.425605311425;5651.124075768263;5657.405972372924;5674.262345895048;1901.7242499837585;1935.1195206181153;1950.3466338776527;1994.9401517954338;2008.1766475744873;25636.74363256381;26006.219813405627;26200.08299709501;26254;26277.868610656824;7271.927280727231;7278.126624805057;7338.6697598607125;7441;7449.106107267163;20135.194592216147;20184.89907550482;20186.697199542017;20192;20196.899015504827;8195.385346099067;8522;8534;8538.188872057144;8543.871841922377;7307.415406767552;7398.482106252511;7421.851562968711;7587.848243035219;7591.089069311607;5487.094629386186;5728.1694154348215;5741;5753.942460575425;5812.476877081083;1749.09921390484;1775.325376356976;1796.7484552162707;1814.6189300247097;1829.826166514179;853.9274161696326;856.4176360075209;878.2183856367838;899.1008991008991;900.8828852249165;7263.455240856957;7321.341079302888;7357.963210183915;7371.88942165868;7543.547387156678;673.5621845800171;698.8532408194235;704.2499737779249;728.1371574684016;740.0379506641344;4412.713173643703;4420.690551661352;4476.955230447719;4480.820767169342;4486.214912390318;21262.893685531475;21549;22052.228172014224;22083.558328833653;22101;19880;21641.458963526038;21674;21685.783142168377;22080.779192207872;9693.273004524688;10194.133498652549;11114.22200445961;11125.499352529156;11211.943940280247;7132.85734285301;8405.70580029696;8504.957475212585;8657.437266577652;8667.393282470419;17594.82405175932;17651.735223971395;17725.645487090445;17899.910500447415;17992.640147197246;18207.635847283243;18406.631867362845;18411.53971150732;18636.161372737988;18677.066146692883;11856.585019524275;12072.577459788865;12085.335306558223;12091.879081209427;12118.091143164474;13645.863541364459;13768.65578360549;13826.516071937434;13896.16623002623;13909.234992075128;11227.607033753779;11247.7750444989;11510.827337589775;11612.825807613062;11667.35829529384;10977.17671174681;10993.285436446606;11039.061679757311;11041.39272340029;11132.721681958015;9875.950620246853;9915.95042024814;10060.899391005996;10064.144547713526;10121.544530495998;6996.860062798613;7049.929500704928;7092.326229008236;7155.427565794828;7256;7163.283671632795;7184.640767961686;7250.9637451812405;7269.200387957426;7276.05411296528 12:26:57 INFO - PID 7583 | |4;prototype.html;493.0877875929533;508.30616208874886;525.7187404738465;528.5186051488264;533.4318950317911;6179.331580473275;6273.463001564617;6351.428371446569;6355.872882542358;6368.777092801753;6044.758209671608;6084.209052823137;6209.813705588828;6245.937540624593;6291.40231677991;4866.805327786884;5016.799328026875;5029.572486338666;5029.924551131736;5053.696778193302;5007.724575148365;5035.899282014358;5045.142325804622;5052.065367906931;5075.365579302587;4891.706497610155;4907.1657818171;4939.53074457926;4994;5050.949490505103;4957.529034741712;4968.87577810554;4974.4776798436005;5044.9495505045215;5051.1413059779925;4476.686631935766;4501.27979523278;4516.458025037016;4529.2526733089;4533.863984080483;6119.357467465941;6126.100908718267;6153.538484613627;6164.506839452833;6178.443940045373;5672.262605861253;5691.8292451226525;5709.62887412321;5718.971405142947;5722.965309456823;6567.277599464055;6579.703913323912;6586.308437614078;6619.271880093186;6654.800355989328;4907.141250281186;4914.803407863681;4917.237828136645;4918.532739389788;4919.163742163841;6864.38220560147;6868.9313106869295;6900.413464855544;6902.965485172542;6917.227113291812;4996.125678006407;4997.575206107522;5011.498850114959;5037.29477873098;5041.495850414929;5080.669756465892;5108.438071812097;5118.923216151761;5121.359830021247;5136.075506408883;5642.492175704207;5671.064274394678;5708.20085188074;5713.628614140068;5765.423457654201;6195;6304.684765761694;6537.509686773511;6595.901061484082;6627.271000189975;1715.836995485427;1727.3781438682065;1738.4176300939075;1748.1870930017603;1761.2338632694818;1188.1089183112665;1209.304649826336;1218.597862705305;1227.1226073357593;1241.075398827861;5600.359766152033;5652.717364131777;5671;5737.942620573741;5787.131930210502;6654.301298363699;6682.331766823376;6714.448509566333;6758.222804377461;6802.693878775368;5691.317041954909;5709.65742055478;5714.7999820006935;5740.0815869460685;5755.3381361144;3469.7397695172963;3491.581010278732;3518;3541.2563361694333;3541.9645803541634;6419.807405777834;6423.357664233633;6466.385693359123;6477.870442591122;6482.092507049017;6816.897746533806;6857.011466674709;6878.828029299208;6954.721811127498;7008.509404341749;6438.90341644885;6491.285958544557;6513.772017979442;6522.315156908551;6529.54293199481;6597.934020659732;6632.668366581748;6656;6708.496862735412;6721.899171512529;6561.934380656132;6603.636799976048;6694.59832410057;6695.665216739241;6717.429018533479;6377.1390862233;6480.630072362439;6484.902726459201;6531.92223283172;6539.836504087246;4921.680090794087;4943.283223932438;4952.678075925053;4979.253112033224;5015.648904576645;6608.6034837907955;6614.239362473377;6617.66911654425;6697.832554186184;6698.899516507354;6397.776077837253;6451.290358060516;6463.612183269019;6527.608343499405;6562;6452.419282264619;6460.806175814733;6465.545252318191;6534.150560427304;6539.049464986839;6274.1843560336865;6282.842928926813;6344.968275158594;6397.712102955471;6438.259600145856;6015.849603759941;6391.424771770563;6411.134496842867;6435.324290949383;6480.837979050561;6283.2460104787715;6328.177336946167;6330.8733825322315;6354.17395738551;6365.745370185141 12:26:57 INFO - PID 7583 | |5;yui.html;878.991210087899;906.5240748606976;912.2200518556634;912.6988093929004;931.9953400233;18131.365402210926;18388.356407525738;18394.356197533056;18394.816051839476;18566.535836604093;15890.682186356265;16484.83515164848;16593.419230326912;16604.169791510405;16634.50096497104;965.0590674092739;985.6796541124128;987.195435719886;988.0514705882362;989.0554520432969;936;969.4716379573115;971.1647982734878;975.3562648651891;976.6484065736365;1034.7361422837166;1036.1400037968506;1037.1391744851792;1037.5123691864812;1038.9896101039005;964.2960638733764;1003.1423133221136;1023.355286169712;1025.5897640943608;1027.121810851726;735.0627949364549;738.7119023580808;740.9222031686703;741.1735914455397;741.5698894641096;16543.83456165435;16772.83227167725;16894.324227030906;16899.83100168995;16930;21546;21590.784092159036;22277.220297289517;22323.772192529403;22500.21249256268;16616.086115263657;16734.74897876533;16823.66352672952;16861.747073793904;16964.575885602837;3712.9071773205887;3743.606921273254;3755.43668449732;3756.624337566249;3781.5840257571645;137722.31138844343;143440;144746;146126;148460.25769871188;16919.407820726214;17466.476005719847;17527.298908043536;17580.38468653591;17586.120693965506;32415.18962025921;32925.17687057843;33272;33312.666873331444;33322.83338583291;8557.529335886586;8636;8727.214550690469;8752.69365572202;8782.077881822317;4169.478815148106;4299.570042995738;4311;4314.460692413449;4322.5677432256525;4664.930026049612;4737.2183589708;4760.047990401864;4762.52374762521;4780.091782561302;1073.4364458659265;1100.5597760895705;1101.5043230546287;1113.1762495753085;1120.8710998235308;648.7567162314132;657.2080642825384;671.5467059734659;677.434342324155;683.3542302524166;10683.252172348017;10774.569017239222;10776.29954052984;11287.209895307415;11557.595484158182;656.264983218798;658.3054877104605;659.5712786688595;666.8566258254401;671.5668393885916;5366.946330536723;5422.132458806572;5441.401445840955;5462.333988133658;5518.889622207534;123691;123804.38097809453;124217.37891310666;124662;125531.3723431377;109615.45192273987;114696;115916;116310;117099;98020.50989745006;101338.49330753299;101505.49247253864;103222.48388758158;103229;9944.602215911282;10039.648612298826;10133.746656333356;10216.387016779017;10249.74375640615;43909.56090439055;43941.78029109834;44047.55952440434;44441.55558444374;45114.548854511035;57002;57220.71389643025;58377;58930.41069589249;60679;15735.830005048443;16088.517344479216;16168.272427740545;16268.837311626732;16296;17648.1175941205;17676.2045707946;17686.20412081434;17734.46796596104;17864.46406607804;15432;15474.767878481607;15619.609509762347;15653.295601697722;15865.762013569574;16091.839081609034;16119.516414507583;16202.756958645394;16371.09958952269;16504.83495165081;15126;15546.37814487408;15594.766078508605;15602.687946241238;15729.134897580285;10911.618093366695;10988.945055274673;11189.664310070712;11390.886091138982;11507;11350.772984540097;11482.311061336346;11529.82705259405;11602.303861767983;11672.416379181177 12:26:57 INFO - PID 7583 | __end_tp_report 12:26:57 INFO - PID 7583 | __start_cc_report 12:26:57 INFO - PID 7583 | _x_x_mozilla_cycle_collect,380 12:26:57 INFO - PID 7583 | __end_cc_report 12:26:57 INFO - PID 7583 | __startTimestamp1505503617731__endTimestamp 12:26:57 INFO - PID 7583 | 12:26:57 INFO - PID 7583 | ------- Summary: start ------- 12:26:57 INFO - PID 7583 | Number of tests: 6 12:26:57 INFO - PID 7583 | 12:26:57 INFO - PID 7583 | [#0] dojo.html Cycles:165 Average:6150.78 Median:6386.07 stddev:736.48 (11.5%) stddev-sans-first:698.21 12:26:57 INFO - PID 7583 | Values: 3079.3 3101.9 3114.8 3128.1 3156.0 6261.3 6304.7 6306.0 6312.7 6342.9 6137.8 6205.1 6228.4 6239.5 6264.4 5665.7 5687.6 5725.1 5766.4 5841.6 5781.1 5786.2 5820.1 5831.9 5832.6 5552.1 5749.2 5790.4 5821.4 5841.6 5643.7 5701.1 5736.4 5749.1 5756.8 5667.1 5678.6 5692.1 5750.5 5768.0 6064.1 6064.7 6126.3 6163.0 6226.5 6525.1 6584.6 6590.6 6591.8 6592.5 6669.7 6688.4 6788.9 6838.2 6850.0 6253.7 6311.8 6325.2 6349.2 6364.0 6898.0 6898.6 6901.4 6928.5 6994.3 6384.6 6453.9 6556.1 6633.9 6634.5 6652.8 6662.3 6730.2 6752.6 6758.9 6600.7 6636.6 6637.0 6704.5 6725.2 6559.9 6616.3 6639.9 6705.2 6719.5 5234.2 5242.2 5253.3 5262.2 5264.3 4611.7 4675.0 4690.0 4702.2 4708.8 5741.7 5742.8 5812.4 5819.9 5820.0 6618.4 6720.2 6722.8 6804.8 6808.6 5691.3 5703.3 5704.5 5724.8 5726.9 5745.7 5772.7 5777.2 5788.7 5790.9 6386.1 6449.8 6481.5 6490.5 6493.3 6697.3 6709.1 6799.6 6807.3 6823.5 6235.4 6263.1 6319.8 6348.0 6392.7 6483.6 6505.0 6559.7 6585.3 6677.6 6709.5 6746.5 6751.0 6751.6 6771.6 6495.5 6512.0 6530.0 6533.5 6607.8 6457.2 6493.3 6509.5 6602.6 6621.0 6443.0 6465.2 6469.5 6479.0 6482.4 6541.7 6612.0 6617.2 6634.8 6640.0 6358.2 6381.4 6430.8 6434.7 6434.8 12:26:57 INFO - PID 7583 | 12:26:57 INFO - PID 7583 | [#1] ext.html Cycles:180 Average:37275.95 Median:35998.87 stddev:51911.63 (144.2%) stddev-sans-first:51985.23 12:26:57 INFO - PID 7583 | Values: 856.5 881.6 882.2 883.3 887.1 39467.8 42869.4 42877.0 42996.8 43046.8 38463.2 38496.2 38547.4 38730.4 38754.8 7023.6 7095.8 7143.5 7732.5 7932.8 7817.6 7906.1 7911.6 7914.7 7921.1 7885.8 7916.9 7936.2 7936.9 7940.2 7804.1 7857.1 7873.7 7888.2 7927.9 7827.9 7850.2 7885.9 7920.2 7931.8 37766.4 37833.2 38031.0 38088.0 38113.2 3556.4 3591.0 3601.6 3607.3 3608.1 31876.8 31905.7 32106.8 32203.4 32241.4 5177.6 5184.7 5198.9 5199.0 5209.0 194391.0 194990.0 196022.0 196839.0 197135.0 43316.6 44450.3 49042.6 49332.3 49385.5 50023.0 51071.7 51228.7 51298.0 51391.7 15255.5 15256.7 15333.0 15377.0 15426.0 12655.7 12677.2 12711.6 12721.1 12722.7 13600.9 13655.1 13664.9 13794.7 13856.8 5661.4 5732.5 5755.6 5764.6 5772.4 1015.5 1031.3 1044.7 1046.2 1049.4 22608.4 22819.2 23077.7 23105.7 23267.9 1124.9 1132.9 1139.4 1142.9 1146.3 11784.5 12053.6 12105.9 12236.1 12285.3 213633.0 215220.0 215418.0 215673.0 216378.0 53595.2 53633.0 53690.7 53761.5 53913.0 1424.8 1441.6 1449.4 1460.6 1462.4 22778.0 22857.5 22901.3 23188.8 23524.4 37393.4 37428.1 37485.3 37562.8 37591.4 188793.0 189904.0 190167.0 190194.0 190423.0 33313.2 33471.2 34095.5 35995.5 36002.3 45501.5 46248.3 46307.8 46439.3 46964.3 33115.8 33148.8 33512.0 33955.7 34013.1 33591.2 33640.7 33977.0 34098.0 34295.7 37185.1 37254.4 37471.3 37594.8 37607.4 32858.2 33133.3 33209.2 33297.7 33436.0 23187.2 23613.4 24044.2 24114.2 24579.0 12:26:57 INFO - PID 7583 | 12:26:57 INFO - PID 7583 | [#2] jquery.html Cycles:180 Average:34448.82 Median:22646.16 stddev:84448.68 (372.9%) stddev-sans-first:84647.25 12:26:57 INFO - PID 7583 | Values: 557.9 559.3 562.2 563.2 571.8 21615.6 22440.6 22514.3 22529.3 22545.3 22561.9 23700.9 23999.8 24019.8 24056.6 6352.0 6446.0 6478.2 6479.0 6485.6 6381.7 6497.7 6522.8 6525.0 6529.6 6269.7 6516.9 6550.6 6551.3 6551.9 6497.8 6512.8 6546.3 6567.0 6568.5 6038.0 6075.0 6078.9 6084.7 6085.8 27162.0 27708.9 28270.3 28311.0 28352.6 2067.3 2094.7 2111.4 2118.4 2119.4 18871.4 19430.5 19473.8 19478.0 19537.9 2393.3 2454.0 2496.0 2497.0 2502.4 119625.0 124507.0 133246.0 137109.0 139346.0 14232.9 14265.2 14424.6 14582.8 14714.3 22628.5 22663.8 22664.8 22675.8 22713.9 9579.4 9898.7 9928.6 9941.9 9961.3 9049.2 9053.4 9131.6 9145.0 9299.2 9018.5 9083.9 9122.6 9126.9 9299.9 2594.2 2621.9 2648.7 2662.1 2745.6 942.5 958.3 964.1 966.9 980.9 18616.3 18636.4 18657.8 18718.7 18995.2 1006.9 1026.3 1026.5 1031.6 1039.3 9991.8 10188.3 10516.1 10550.0 10609.7 484014.0 499711.0 507170.0 513993.0 518024.0 103706.5 107910.0 108157.0 108699.5 110675.4 1035.1 1073.5 1090.2 1139.2 1150.9 19552.6 19702.0 19841.4 19921.6 20040.1 54776.7 55262.4 55705.7 55920.0 56791.4 69445.0 70931.9 70998.0 71064.6 71331.0 13875.4 13948.1 13969.2 14088.3 14097.0 21456.5 21790.7 21846.6 22011.8 22074.0 17593.4 17844.7 18027.7 18030.6 18169.4 15979.7 16242.8 16614.8 16671.2 16675.4 17804.0 18231.0 18276.7 18318.0 18543.9 13894.9 14023.3 14049.2 14072.7 14083.6 17532.6 17603.4 17677.8 17785.6 17872.3 12:26:57 INFO - PID 7583 | 12:26:57 INFO - PID 7583 | [#3] mootools.html Cycles:180 Average:9410.69 Median:2684.56 stddev:6714.34 (250.1%) stddev-sans-first:6705.54 12:26:57 INFO - PID 7583 | Values: 1303.0 1342.6 1369.7 1371.5 1372.1 14956.2 15447.8 15528.3 15602.1 15613.8 15050.5 15101.1 15155.7 15266.4 15300.3 2684.9 2805.2 2817.9 2821.3 2823.1 2668.2 2684.1 2691.5 2691.7 2699.9 2684.2 2812.0 2917.9 2923.6 2924.2 2667.1 2778.1 2793.7 2799.5 2833.5 2695.7 2819.9 2820.4 2823.2 2824.8 15455.8 15657.4 15703.1 15720.0 15747.6 8009.1 8238.4 8262.4 8271.0 8288.2 5417.9 5470.4 5651.1 5657.4 5674.3 1901.7 1935.1 1950.3 1994.9 2008.2 25636.7 26006.2 26200.1 26254.0 26277.9 7271.9 7278.1 7338.7 7441.0 7449.1 20135.2 20184.9 20186.7 20192.0 20196.9 8195.4 8522.0 8534.0 8538.2 8543.9 7307.4 7398.5 7421.9 7587.8 7591.1 5487.1 5728.2 5741.0 5753.9 5812.5 1749.1 1775.3 1796.7 1814.6 1829.8 853.9 856.4 878.2 899.1 900.9 7263.5 7321.3 7358.0 7371.9 7543.5 673.6 698.9 704.2 728.1 740.0 4412.7 4420.7 4477.0 4480.8 4486.2 21262.9 21549.0 22052.2 22083.6 22101.0 19880.0 21641.5 21674.0 21685.8 22080.8 9693.3 10194.1 11114.2 11125.5 11211.9 7132.9 8405.7 8505.0 8657.4 8667.4 17594.8 17651.7 17725.6 17899.9 17992.6 18207.6 18406.6 18411.5 18636.2 18677.1 11856.6 12072.6 12085.3 12091.9 12118.1 13645.9 13768.7 13826.5 13896.2 13909.2 11227.6 11247.8 11510.8 11612.8 11667.4 10977.2 10993.3 11039.1 11041.4 11132.7 9876.0 9916.0 10060.9 10064.1 10121.5 6996.9 7049.9 7092.3 7155.4 7256.0 7163.3 7184.6 7251.0 7269.2 7276.1 12:26:57 INFO - PID 7583 | 12:26:57 INFO - PID 7583 | [#4] prototype.html Cycles:180 Average:5497.70 Median:6159.02 stddev:1536.77 (25.0%) stddev-sans-first:1494.47 12:26:57 INFO - PID 7583 | Values: 493.1 508.3 525.7 528.5 533.4 6179.3 6273.5 6351.4 6355.9 6368.8 6044.8 6084.2 6209.8 6245.9 6291.4 4866.8 5016.8 5029.6 5029.9 5053.7 5007.7 5035.9 5045.1 5052.1 5075.4 4891.7 4907.2 4939.5 4994.0 5050.9 4957.5 4968.9 4974.5 5044.9 5051.1 4476.7 4501.3 4516.5 4529.3 4533.9 6119.4 6126.1 6153.5 6164.5 6178.4 5672.3 5691.8 5709.6 5719.0 5723.0 6567.3 6579.7 6586.3 6619.3 6654.8 4907.1 4914.8 4917.2 4918.5 4919.2 6864.4 6868.9 6900.4 6903.0 6917.2 4996.1 4997.6 5011.5 5037.3 5041.5 5080.7 5108.4 5118.9 5121.4 5136.1 5642.5 5671.1 5708.2 5713.6 5765.4 6195.0 6304.7 6537.5 6595.9 6627.3 1715.8 1727.4 1738.4 1748.2 1761.2 1188.1 1209.3 1218.6 1227.1 1241.1 5600.4 5652.7 5671.0 5737.9 5787.1 6654.3 6682.3 6714.4 6758.2 6802.7 5691.3 5709.7 5714.8 5740.1 5755.3 3469.7 3491.6 3518.0 3541.3 3542.0 6419.8 6423.4 6466.4 6477.9 6482.1 6816.9 6857.0 6878.8 6954.7 7008.5 6438.9 6491.3 6513.8 6522.3 6529.5 6597.9 6632.7 6656.0 6708.5 6721.9 6561.9 6603.6 6694.6 6695.7 6717.4 6377.1 6480.6 6484.9 6531.9 6539.8 4921.7 4943.3 4952.7 4979.3 5015.6 6608.6 6614.2 6617.7 6697.8 6698.9 6397.8 6451.3 6463.6 6527.6 6562.0 6452.4 6460.8 6465.5 6534.2 6539.0 6274.2 6282.8 6345.0 6397.7 6438.3 6015.8 6391.4 6411.1 6435.3 6480.8 6283.2 6328.2 6330.9 6354.2 6365.7 12:26:57 INFO - PID 7583 | 12:26:57 INFO - PID 7583 | [#5] yui.html Cycles:180 Average:24677.83 Median:17583.25 stddev:36692.48 (208.7%) stddev-sans-first:36751.90 12:26:57 INFO - PID 7583 | Values: 879.0 906.5 912.2 912.7 932.0 18131.4 18388.4 18394.4 18394.8 18566.5 15890.7 16484.8 16593.4 16604.2 16634.5 965.1 985.7 987.2 988.1 989.1 936.0 969.5 971.2 975.4 976.6 1034.7 1036.1 1037.1 1037.5 1039.0 964.3 1003.1 1023.4 1025.6 1027.1 735.1 738.7 740.9 741.2 741.6 16543.8 16772.8 16894.3 16899.8 16930.0 21546.0 21590.8 22277.2 22323.8 22500.2 16616.1 16734.7 16823.7 16861.7 16964.6 3712.9 3743.6 3755.4 3756.6 3781.6 137722.3 143440.0 144746.0 146126.0 148460.3 16919.4 17466.5 17527.3 17580.4 17586.1 32415.2 32925.2 33272.0 33312.7 33322.8 8557.5 8636.0 8727.2 8752.7 8782.1 4169.5 4299.6 4311.0 4314.5 4322.6 4664.9 4737.2 4760.0 4762.5 4780.1 1073.4 1100.6 1101.5 1113.2 1120.9 648.8 657.2 671.5 677.4 683.4 10683.3 10774.6 10776.3 11287.2 11557.6 656.3 658.3 659.6 666.9 671.6 5366.9 5422.1 5441.4 5462.3 5518.9 123691.0 123804.4 124217.4 124662.0 125531.4 109615.5 114696.0 115916.0 116310.0 117099.0 98020.5 101338.5 101505.5 103222.5 103229.0 9944.6 10039.6 10133.7 10216.4 10249.7 43909.6 43941.8 44047.6 44441.6 45114.5 57002.0 57220.7 58377.0 58930.4 60679.0 15735.8 16088.5 16168.3 16268.8 16296.0 17648.1 17676.2 17686.2 17734.5 17864.5 15432.0 15474.8 15619.6 15653.3 15865.8 16091.8 16119.5 16202.8 16371.1 16504.8 15126.0 15546.4 15594.8 15602.7 15729.1 10911.6 10988.9 11189.7 11390.9 11507.0 11350.8 11482.3 11529.8 11602.3 11672.4 12:26:57 INFO - PID 7583 | -------- Summary: end -------- 12:26:57 INFO - PID 7583 | 12:26:58 INFO - TEST-INFO | 7583: exit 0 12:26:58 INFO - TEST-OK | dromaeo_css | took 1108355ms 12:26:58 INFO - TEST-START | kraken 12:26:58 INFO - Initialising browser for kraken test... 12:26:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46960/getInfo.html -profile /tmp/tmp5RfAyK/profile 12:26:58 INFO - TEST-INFO | started process 7955 (/builds/slave/test/build/application/firefox/firefox http://localhost:46960/getInfo.html) 12:27:03 INFO - TEST-INFO | 7955: exit 0 12:27:03 INFO - Browser initialized. 12:27:03 INFO - Running cycle 1/1 for kraken test... 12:27:03 INFO - Using env: {'DISPLAY': ':0', 12:27:03 INFO - 'HOME': '/home/cltbld', 12:27:03 INFO - 'JSGC_DISABLE_POISONING': '1', 12:27:03 INFO - 'LANG': 'en_US.UTF-8', 12:27:03 INFO - 'LANGUAGE': 'en_US:en', 12:27:03 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:27:03 INFO - 'LOGNAME': 'cltbld', 12:27:03 INFO - 'MAIL': '/var/mail/cltbld', 12:27:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:27:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:27:03 INFO - 'MOZ_CRASHREPORTER': '1', 12:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:27:03 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:27:03 INFO - 'MOZ_NO_REMOTE': '1', 12:27:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:27:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:27:03 INFO - 'NO_EM_RESTART': '1', 12:27:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:27:03 INFO - 'PWD': '/builds/slave/test', 12:27:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:27:03 INFO - 'RUST_BACKTRACE': 'full', 12:27:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:27:03 INFO - 'SHELL': '/bin/bash', 12:27:03 INFO - 'SHLVL': '1', 12:27:03 INFO - 'TERM': 'linux', 12:27:03 INFO - 'TMOUT': '86400', 12:27:03 INFO - 'USER': 'cltbld', 12:27:03 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123', 12:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:27:03 INFO - '_': '/tools/buildbot/bin/python'} 12:27:03 INFO - TEST-INFO | started process 8186 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5RfAyK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:27:04 INFO - PID 8186 | 12:27:04 INFO - PID 8186 | (/builds/slave/test/build/application/firefox/firefox:8235): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:27:04 INFO - PID 8186 | 12:27:14 INFO - PID 8186 | RSS: Main: 173867008 12:27:14 INFO - PID 8186 | 12:27:17 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46960/tests/kraken/driver.html?testName=audio-beat-detection) 12:27:18 INFO - PID 8186 | RSS: Main: 178671616 12:27:18 INFO - PID 8186 | 12:27:21 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46960/tests/kraken/driver.html?testName=audio-dft) 12:27:21 INFO - PID 8186 | RSS: Main: 178589696 12:27:21 INFO - PID 8186 | 12:27:24 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46960/tests/kraken/driver.html?testName=audio-fft) 12:27:25 INFO - PID 8186 | RSS: Main: 174276608 12:27:25 INFO - PID 8186 | 12:27:27 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46960/tests/kraken/driver.html?testName=audio-oscillator) 12:27:28 INFO - PID 8186 | RSS: Main: 175222784 12:27:28 INFO - PID 8186 | 12:27:30 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46960/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:27:30 INFO - PID 8186 | RSS: Main: 175599616 12:27:30 INFO - PID 8186 | 12:27:34 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46960/tests/kraken/driver.html?testName=imaging-darkroom) 12:27:34 INFO - PID 8186 | RSS: Main: 177999872 12:27:34 INFO - PID 8186 | 12:27:38 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46960/tests/kraken/driver.html?testName=imaging-desaturate) 12:27:39 INFO - PID 8186 | RSS: Main: 177999872 12:27:39 INFO - PID 8186 | 12:27:42 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46960/tests/kraken/driver.html?testName=json-parse-financial) 12:27:42 INFO - PID 8186 | RSS: Main: 178257920 12:27:42 INFO - PID 8186 | 12:27:44 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46960/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:27:44 INFO - PID 8186 | RSS: Main: 178343936 12:27:44 INFO - PID 8186 | 12:27:46 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46960/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:27:47 INFO - PID 8186 | RSS: Main: 175599616 12:27:47 INFO - PID 8186 | 12:27:49 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46960/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:27:50 INFO - PID 8186 | RSS: Main: 175366144 12:27:50 INFO - PID 8186 | 12:27:52 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46960/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:27:53 INFO - PID 8186 | RSS: Main: 175869952 12:27:53 INFO - PID 8186 | 12:27:56 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46960/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:27:56 INFO - PID 8186 | RSS: Main: 175751168 12:27:56 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | Cycle 1(1): loaded http://localhost:46960/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46960/tests/kraken/driver.html?testName=ai-astar) 12:27:58 INFO - PID 8186 | RSS: Main: 177594368 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | __start_tp_report 12:27:58 INFO - PID 8186 | _x_x_mozilla_page_load 12:27:58 INFO - PID 8186 | _x_x_mozilla_page_load_details 12:27:58 INFO - PID 8186 | |i|pagename|runs| 12:27:58 INFO - PID 8186 | |0;ai-astar;108;107;108;108;113;107;107;107;107;108 12:27:58 INFO - PID 8186 | |1;audio-beat-detection;115;116;116;120;118;122;118;118;121;117 12:27:58 INFO - PID 8186 | |2;audio-dft;163;162;165;163;164;162;164;168;171;167 12:27:58 INFO - PID 8186 | |3;audio-fft;74;74;74;76;73;73;75;74;73;75 12:27:58 INFO - PID 8186 | |4;audio-oscillator;81;80;78;79;78;82;79;79;79;80 12:27:58 INFO - PID 8186 | |5;imaging-gaussian-blur;113;114;113;114;109;114;114;114;114;115 12:27:58 INFO - PID 8186 | |6;imaging-darkroom;148;147;148;148;148;148;148;148;148;148 12:27:58 INFO - PID 8186 | |7;imaging-desaturate;100;94;94;95;93;99;94;95;96;96 12:27:58 INFO - PID 8186 | |8;json-parse-financial;56;54;56;55;55;55;55;56;54;55 12:27:58 INFO - PID 8186 | |9;json-stringify-tinderbox;38;38;39;38;38;37;38;38;39;38 12:27:58 INFO - PID 8186 | |10;stanford-crypto-aes;74;75;75;78;75;75;74;78;73;85 12:27:58 INFO - PID 8186 | |11;stanford-crypto-ccm;115;112;113;111;115;111;118;112;117;112 12:27:58 INFO - PID 8186 | |12;stanford-crypto-pbkdf2;184;172;172;173;174;165;171;167;166;173 12:27:58 INFO - PID 8186 | |13;stanford-crypto-sha256-iterative;71;68;65;65;65;63;64;73;64;64 12:27:58 INFO - PID 8186 | __end_tp_report 12:27:58 INFO - PID 8186 | __start_cc_report 12:27:58 INFO - PID 8186 | _x_x_mozilla_cycle_collect,975 12:27:58 INFO - PID 8186 | __end_cc_report 12:27:58 INFO - PID 8186 | __startTimestamp1505503678948__endTimestamp 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | ------- Summary: start ------- 12:27:58 INFO - PID 8186 | Number of tests: 14 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#0] ai-astar Cycles:10 Average:108.00 Median:108.00 stddev:1.83 (1.7%) stddev-sans-first:1.94 12:27:58 INFO - PID 8186 | Values: 108.0 107.0 108.0 108.0 113.0 107.0 107.0 107.0 107.0 108.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#1] audio-beat-detection Cycles:10 Average:118.10 Median:118.00 stddev:2.28 (1.9%) stddev-sans-first:2.13 12:27:58 INFO - PID 8186 | Values: 115.0 116.0 116.0 120.0 118.0 122.0 118.0 118.0 121.0 117.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#2] audio-dft Cycles:10 Average:164.90 Median:164.50 stddev:2.92 (1.8%) stddev-sans-first:3.02 12:27:58 INFO - PID 8186 | Values: 163.0 162.0 165.0 163.0 164.0 162.0 164.0 168.0 171.0 167.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#3] audio-fft Cycles:10 Average:74.10 Median:74.00 stddev:0.99 (1.3%) stddev-sans-first:1.05 12:27:58 INFO - PID 8186 | Values: 74.0 74.0 74.0 76.0 73.0 73.0 75.0 74.0 73.0 75.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#4] audio-oscillator Cycles:10 Average:79.50 Median:79.50 stddev:1.27 (1.6%) stddev-sans-first:1.22 12:27:58 INFO - PID 8186 | Values: 81.0 80.0 78.0 79.0 78.0 82.0 79.0 79.0 79.0 80.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:114.00 stddev:1.65 (1.4%) stddev-sans-first:1.74 12:27:58 INFO - PID 8186 | Values: 113.0 114.0 113.0 114.0 109.0 114.0 114.0 114.0 114.0 115.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 12:27:58 INFO - PID 8186 | Values: 148.0 147.0 148.0 148.0 148.0 148.0 148.0 148.0 148.0 148.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:95.00 stddev:2.27 (2.4%) stddev-sans-first:1.76 12:27:58 INFO - PID 8186 | Values: 100.0 94.0 94.0 95.0 93.0 99.0 94.0 95.0 96.0 96.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:0.74 (1.3%) stddev-sans-first:0.71 12:27:58 INFO - PID 8186 | Values: 56.0 54.0 56.0 55.0 55.0 55.0 55.0 56.0 54.0 55.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.57 (1.5%) stddev-sans-first:0.60 12:27:58 INFO - PID 8186 | Values: 38.0 38.0 39.0 38.0 38.0 37.0 38.0 38.0 39.0 38.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:75.00 stddev:3.49 (4.7%) stddev-sans-first:3.61 12:27:58 INFO - PID 8186 | Values: 74.0 75.0 75.0 78.0 75.0 75.0 74.0 78.0 73.0 85.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#11] stanford-crypto-ccm Cycles:10 Average:113.60 Median:114.00 stddev:2.50 (2.2%) stddev-sans-first:2.60 12:27:58 INFO - PID 8186 | Values: 115.0 112.0 113.0 111.0 115.0 111.0 118.0 112.0 117.0 112.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:172.50 stddev:5.38 (3.1%) stddev-sans-first:3.39 12:27:58 INFO - PID 8186 | Values: 184.0 172.0 172.0 173.0 174.0 165.0 171.0 167.0 166.0 173.0 12:27:58 INFO - PID 8186 | 12:27:58 INFO - PID 8186 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:65.00 stddev:3.36 (5.2%) stddev-sans-first:3.08 12:27:58 INFO - PID 8186 | Values: 71.0 68.0 65.0 65.0 65.0 63.0 64.0 73.0 64.0 64.0 12:27:58 INFO - PID 8186 | -------- Summary: end -------- 12:27:58 INFO - PID 8186 | 12:27:59 INFO - PID 8186 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:27:59 INFO - TEST-INFO | 8186: exit 0 12:27:59 INFO - TEST-OK | kraken | took 61250ms 12:27:59 INFO - SUITE-END | took 1169s 12:28:00 INFO - Completed test suite (00:19:30) 12:28:00 INFO - javascript benchmark 12:28:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8464.355352393932, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3079.276370053037, 3101.8914337998176, 3114.7663925205607, 3128.124125244932, 3156.0, 6261.279952805428, 6304.684765761711, 6305.968470157649, 6312.652804095773, 6342.904856427157, 6137.785177518787, 6205.131281620576, 6228.439440450358, 6239.532035097363, 6264.373562643734, 5665.745041473133, 5687.573431992597, 5725.055365864628, 5766.365699773021, 5841.591088623798, 5781.132830075501, 5786.189933409326, 5820.097884827858, 5831.854203644922, 5832.56255780816, 5552.139418390156, 5749.166370876242, 5790.36306006339, 5821.3887541808135, 5841.591088623798, 5643.717814109319, 5701.087825947829, 5736.368999410061, 5749.080147176432, 5756.7985120521, 5667.149927510865, 5678.6024978251535, 5692.0608099663505, 5750.511206547449, 5767.971160144172, 6064.06007068905, 6064.727087281083, 6126.326104128542, 6162.952298109332, 6226.470749986212, 6525.119108920282, 6584.604923704593, 6590.637514936677, 6591.769288074942, 6592.472602191813, 6669.699863506154, 6688.398044176048, 6788.864222715518, 6838.213605435389, 6849.965750171267, 6253.7185826637915, 6311.810645680637, 6325.240971083454, 6349.206349206349, 6364.0, 6897.965510172417, 6898.551594146365, 6901.413379862667, 6928.549644273207, 6994.300569943067, 6384.648844313514, 6453.870922581522, 6556.082148499214, 6633.9336606633315, 6634.469242460592, 6652.83367915806, 6662.300458451889, 6730.158730158731, 6752.628605426748, 6758.898616520756, 6600.669966501656, 6636.568623039487, 6637.004449332639, 6704.530682852251, 6725.226598941184, 6559.934400656028, 6616.305287944792, 6639.867202656016, 6705.161854768154, 6719.529632925746, 5234.162533994542, 5242.213667949762, 5253.290805741252, 5262.236975638511, 5264.31563896691, 4611.7232966021475, 4675.018246168343, 4690.0, 4702.177119004161, 4708.835190768306, 5741.684207368635, 5742.799002034993, 5812.447817457335, 5819.912701309484, 5820.0, 6618.371254730793, 6720.22717387497, 6722.764703235363, 6804.795856124324, 6808.62552559614, 5691.26013618227, 5703.258576385043, 5704.515116215085, 5724.79963201286, 5726.856828579319, 5745.683987380734, 5772.7113644317615, 5777.19119323295, 5788.652680839079, 5790.94209057904, 6386.07401926718, 6449.838754031187, 6481.546291759531, 6490.4807615390655, 6493.31820158886, 6697.296783837627, 6709.127813384229, 6799.5920244785475, 6807.285235050248, 6823.522353435212, 6235.376462353728, 6263.123162757127, 6319.778807741707, 6347.96826015867, 6392.712327945346, 6483.643399613066, 6505.0, 6559.672016399066, 6585.341465853568, 6677.565958212701, 6709.463242940651, 6746.460283177236, 6750.966245168743, 6751.56114852533, 6771.62756048422, 6495.512836537184, 6511.967440162769, 6530.0, 6533.477321814338, 6607.80176594703, 6457.160569126171, 6493.253275873335, 6509.479241660751, 6602.603843769197, 6621.0, 6442.967785161044, 6465.191851018622, 6469.514786391133, 6479.0, 6482.448991835747, 6541.70562324687, 6611.966940165268, 6617.239017513048, 6634.800955971328, 6639.966800165968, 6358.1734374531015, 6381.361863813581, 6430.807075787734, 6434.67826608677, 6434.774782882577], "value": 6094.490211876391, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [856.4903882190097, 881.6429346114821, 882.2236431939892, 883.3198437203354, 887.148337595908, 39467.802660986694, 42869.35695964559, 42877.0, 42996.78501607488, 43046.78476607616, 38463.230735385274, 38496.230075398475, 38547.42178867312, 38730.4190437143, 38754.80622596883, 7023.578585284887, 7095.787126386191, 7143.535670181447, 7732.536047837119, 7932.841343173133, 7817.648205830751, 7906.090799558067, 7911.564863932482, 7914.683412663515, 7921.089074756419, 7885.763427097168, 7916.920830791676, 7936.2063793620755, 7936.880946785803, 7940.2059794020715, 7804.063512378522, 7857.1357150713375, 7873.685052597889, 7888.171741967069, 7927.920720792776, 7827.921720782776, 7850.214978502104, 7885.921140788576, 7920.247576480225, 7931.841363172704, 37766.43350349747, 37833.24333513342, 38031.0, 38088.0, 38113.23773524542, 3556.3598552260582, 3590.982045089784, 3601.567811862567, 3607.314610224049, 3608.0799396154075, 31876.840615796773, 31905.680943190502, 32106.839465802754, 32203.355932881444, 32241.355172896645, 5177.637565370425, 5184.662996905189, 5198.870028249286, 5199.012187684328, 5209.0363282792805, 194391.0, 194990.0, 196022.0, 196839.0, 197135.0, 43316.56683433188, 44450.33324500135, 49042.56702030823, 49332.26001609979, 49385.50614493881, 50023.0, 51071.74464127656, 51228.74385628048, 51298.0, 51391.74304128456, 15255.542333730005, 15256.694866102616, 15333.0, 15377.0, 15426.0, 12655.746885062246, 12677.175983561223, 12711.61865144047, 12721.109522333349, 12722.745545089047, 13600.911927045812, 13655.112417692819, 13664.93167534156, 13794.65513362174, 13856.792148117785, 5661.405552417019, 5732.525438206503, 5755.625884317506, 5764.5676574257095, 5772.393898640582, 1015.5176291261739, 1031.2626472072504, 1044.6500422358442, 1046.2048842879467, 1049.3660911113009, 22608.434789130402, 22819.20132795344, 23077.653835192486, 23105.653415198787, 23267.88366058159, 1124.943752812356, 1132.8923752243525, 1139.3619573039125, 1142.868570114431, 1146.2721172055637, 11784.528618855149, 12053.63839084829, 12105.939470302592, 12236.143469957196, 12285.262884226797, 213633.0, 215220.0, 215418.0, 215673.0, 216378.0, 53595.19607205895, 53633.0, 53690.73154634202, 53761.46238537643, 53913.0, 1424.7649137892338, 1441.6035590212566, 1449.4202319072456, 1460.6056364781662, 1462.393106860641, 22778.0, 22857.542849142592, 22901.31296061121, 23188.76811231866, 23524.411889702893, 37393.43909841409, 37428.06429839276, 37485.2502949934, 37562.81218593999, 37591.43612845755, 188793.0, 189904.0, 190167.0491647533, 190194.0, 190423.0, 33313.167170820925, 33471.16322091967, 34095.48856767101, 35995.46006809952, 36002.27995440129, 45501.54498454973, 46248.30627540522, 46307.76846115748, 46439.30341044819, 46964.295535567675, 33115.83442082774, 33148.834255828566, 33512.0, 33955.660443396235, 34013.14967125743, 33591.16022099369, 33640.663593364734, 33977.0, 34098.0, 34295.65704343024, 37185.07037324089, 37254.44118338173, 37471.250574988895, 37594.81202594079, 37607.43588846115, 32858.17854553655, 33133.33733325272, 33209.16977075592, 33297.667023330425, 33436.0, 23187.188448404224, 23613.409664758517, 24044.15845445471, 24114.156004539756, 24579.0], "value": 17241.4295407569, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.9163125531172, 559.2701524510514, 562.1960596347225, 563.155267099351, 571.7941541045222, 21615.567688646217, 22440.55118897621, 22514.324570262917, 22529.324120276397, 22545.323640290775, 22561.887190564023, 23700.881495592497, 23999.76000239997, 24019.759802402015, 24056.639150412757, 6352.0, 6445.967770161143, 6478.157839480861, 6478.967605161967, 6485.6108633482145, 6381.744730210786, 6497.740090396378, 6522.771702990396, 6525.0, 6529.575577587465, 6269.749210031616, 6516.859549578829, 6550.606963582177, 6551.279359270477, 6551.901721474182, 6497.772577959749, 6512.837179070514, 6546.27990921003, 6566.967165164167, 6568.540202185849, 6038.0, 6074.96962515189, 6078.939210607926, 6084.665343406111, 6085.847853803646, 27162.0, 27708.861455692793, 28270.293242669097, 28311.0, 28352.574711379344, 2067.2764532413685, 2094.7276854009033, 2111.4088055344528, 2118.4386137673528, 2119.4065661614773, 18871.433856984175, 19430.514237144183, 19473.805261947342, 19478.02609869515, 19537.902310488356, 2393.305941277045, 2453.963190552125, 2496.014074440604, 2496.9512804621922, 2502.436951685856, 119625.0, 124507.0, 133246.0, 137109.0, 139345.99999999898, 14232.85767142336, 14265.215413152377, 14424.639384015483, 14582.781258281135, 14714.264286785618, 22628.547429051327, 22663.773362266496, 22664.773352266267, 22675.773242267696, 22713.886430568073, 9579.425234485814, 9898.703038908845, 9928.602855885683, 9941.900580994243, 9961.252906031932, 9049.230815380635, 9053.411528250643, 9131.58013126522, 9145.0, 9299.163075323253, 9018.458892466473, 9083.909160908439, 9122.589483473259, 9126.908730912739, 9299.90700093004, 2594.15689900783, 2621.868906554703, 2648.6556747622685, 2662.0948877381784, 2745.6018877262686, 942.4769253064478, 958.3148049144895, 964.1081999150757, 966.9122237482832, 980.8724865767405, 18616.255349786126, 18636.44090677282, 18657.818580979834, 18718.71921921172, 18995.240190392506, 1006.8993100690019, 1026.2508368890751, 1026.5380578739598, 1031.5512751952924, 1039.2880876599554, 9991.750206244902, 10188.286819922534, 10516.053555180071, 10550.0, 10609.734756631146, 484014.0, 499711.0, 507170.0, 513993.0, 518024.0, 103706.4814675937, 107910.0, 108157.0, 108699.45650271856, 110675.44662276637, 1035.1304903880775, 1073.5330131392868, 1090.2041509697806, 1139.1659428320397, 1150.9482073306888, 19552.60894782125, 19702.0, 19841.404757857286, 19921.601567968268, 20040.098195581526, 54776.726116369166, 55262.447375527336, 55705.72147139238, 55920.0, 56791.432085678614, 69445.0, 70931.93602096075, 70998.0, 71064.64467677628, 71331.0, 13875.375608097458, 13948.093373930662, 13969.231692256617, 14088.295585220903, 14097.0, 21456.463588410414, 21790.673139903232, 21846.563068738855, 22011.77988220097, 22074.0, 17593.384231551834, 17844.732329014816, 18027.72958405599, 18030.63938721192, 18169.364072257937, 15979.680406392039, 16242.837571624605, 16614.833851661813, 16671.166441677626, 16675.416360427327, 17804.0, 18231.0, 18276.72584911201, 18318.0, 18543.90728046351, 13894.930525347309, 14023.298835058002, 14049.227292498601, 14072.718545629235, 14083.577492675236, 17532.561685957953, 17603.383881564085, 17677.823221767616, 17785.555361116076, 17872.285108596032], "value": 11726.01505125751, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.9869701302985, 1342.5502456677013, 1369.732902084094, 1371.51311284494, 1372.0807059270294, 14956.177410242432, 15447.845521544781, 15528.301226444808, 15602.063876167424, 15613.765793513106, 15050.473233436833, 15101.093934363946, 15155.6968860623, 15266.389344426238, 15300.31148598313, 2684.8657567121613, 2805.172474120129, 2817.915462536127, 2821.3369858083333, 2823.1389426225096, 2668.2262143978223, 2684.087410280504, 2691.4886171627427, 2691.6904555976116, 2699.8650067496646, 2684.2484104450787, 2811.9578206326923, 2917.8541072946373, 2923.6112846397896, 2924.1958461423174, 2667.0931883159624, 2778.097118436506, 2793.7206279372103, 2799.510085734988, 2833.5183018886837, 2695.716949720271, 2819.915402537927, 2820.393615372684, 2823.1530540837666, 2824.844633545154, 15455.768163477556, 15657.373705051785, 15703.136327501983, 15720.0, 15747.606309842346, 8009.118996910335, 8238.393513264915, 8262.42163048587, 8270.958645206796, 8288.21261980111, 5417.878210899719, 5470.425605311425, 5651.124075768263, 5657.405972372924, 5674.262345895048, 1901.7242499837585, 1935.1195206181153, 1950.3466338776527, 1994.9401517954338, 2008.1766475744873, 25636.74363256381, 26006.219813405627, 26200.08299709501, 26254.0, 26277.868610656824, 7271.927280727231, 7278.126624805057, 7338.6697598607125, 7441.0, 7449.106107267163, 20135.194592216147, 20184.89907550482, 20186.697199542017, 20192.0, 20196.899015504827, 8195.385346099067, 8522.0, 8534.0, 8538.188872057144, 8543.871841922377, 7307.415406767552, 7398.482106252511, 7421.851562968711, 7587.848243035219, 7591.089069311607, 5487.094629386186, 5728.1694154348215, 5741.0, 5753.942460575425, 5812.476877081083, 1749.09921390484, 1775.325376356976, 1796.7484552162707, 1814.6189300247097, 1829.826166514179, 853.9274161696326, 856.4176360075209, 878.2183856367838, 899.1008991008991, 900.8828852249165, 7263.455240856957, 7321.341079302888, 7357.963210183915, 7371.88942165868, 7543.547387156678, 673.5621845800171, 698.8532408194235, 704.2499737779249, 728.1371574684016, 740.0379506641344, 4412.713173643703, 4420.690551661352, 4476.955230447719, 4480.820767169342, 4486.214912390318, 21262.893685531475, 21549.0, 22052.228172014224, 22083.558328833653, 22101.0, 19880.0, 21641.458963526038, 21674.0, 21685.783142168377, 22080.779192207872, 9693.273004524688, 10194.133498652549, 11114.22200445961, 11125.499352529156, 11211.943940280247, 7132.85734285301, 8405.70580029696, 8504.957475212585, 8657.437266577652, 8667.393282470419, 17594.82405175932, 17651.735223971395, 17725.645487090445, 17899.910500447415, 17992.640147197246, 18207.635847283243, 18406.631867362845, 18411.53971150732, 18636.161372737988, 18677.066146692883, 11856.585019524275, 12072.577459788865, 12085.335306558223, 12091.879081209427, 12118.091143164474, 13645.863541364459, 13768.65578360549, 13826.516071937434, 13896.16623002623, 13909.234992075128, 11227.607033753779, 11247.7750444989, 11510.827337589775, 11612.825807613062, 11667.35829529384, 10977.17671174681, 10993.285436446606, 11039.061679757311, 11041.39272340029, 11132.721681958015, 9875.950620246853, 9915.95042024814, 10060.899391005996, 10064.144547713526, 10121.544530495998, 6996.860062798613, 7049.929500704928, 7092.326229008236, 7155.427565794828, 7256.0, 7163.283671632795, 7184.640767961686, 7250.9637451812405, 7269.200387957426, 7276.05411296528], "value": 6701.654262826897, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.0877875929533, 508.30616208874886, 525.7187404738465, 528.5186051488264, 533.4318950317911, 6179.331580473275, 6273.463001564617, 6351.428371446569, 6355.872882542358, 6368.777092801753, 6044.758209671608, 6084.209052823137, 6209.813705588828, 6245.937540624593, 6291.40231677991, 4866.805327786884, 5016.799328026875, 5029.572486338666, 5029.924551131736, 5053.696778193302, 5007.724575148365, 5035.899282014358, 5045.142325804622, 5052.065367906931, 5075.365579302587, 4891.706497610155, 4907.1657818171, 4939.53074457926, 4994.0, 5050.949490505103, 4957.529034741712, 4968.87577810554, 4974.4776798436005, 5044.9495505045215, 5051.1413059779925, 4476.686631935766, 4501.27979523278, 4516.458025037016, 4529.2526733089, 4533.863984080483, 6119.357467465941, 6126.100908718267, 6153.538484613627, 6164.506839452833, 6178.443940045373, 5672.262605861253, 5691.8292451226525, 5709.62887412321, 5718.971405142947, 5722.965309456823, 6567.277599464055, 6579.703913323912, 6586.308437614078, 6619.271880093186, 6654.800355989328, 4907.141250281186, 4914.803407863681, 4917.237828136645, 4918.532739389788, 4919.163742163841, 6864.38220560147, 6868.9313106869295, 6900.413464855544, 6902.965485172542, 6917.227113291812, 4996.125678006407, 4997.575206107522, 5011.498850114959, 5037.29477873098, 5041.495850414929, 5080.669756465892, 5108.438071812097, 5118.923216151761, 5121.359830021247, 5136.075506408883, 5642.492175704207, 5671.064274394678, 5708.20085188074, 5713.628614140068, 5765.423457654201, 6195.0, 6304.684765761694, 6537.509686773511, 6595.901061484082, 6627.271000189975, 1715.836995485427, 1727.3781438682065, 1738.4176300939075, 1748.1870930017603, 1761.2338632694818, 1188.1089183112665, 1209.304649826336, 1218.597862705305, 1227.1226073357593, 1241.075398827861, 5600.359766152033, 5652.717364131777, 5671.0, 5737.942620573741, 5787.131930210502, 6654.301298363699, 6682.331766823376, 6714.448509566333, 6758.222804377461, 6802.693878775368, 5691.317041954909, 5709.65742055478, 5714.7999820006935, 5740.0815869460685, 5755.3381361144, 3469.7397695172963, 3491.581010278732, 3518.0, 3541.2563361694333, 3541.9645803541634, 6419.807405777834, 6423.357664233633, 6466.385693359123, 6477.870442591122, 6482.092507049017, 6816.897746533806, 6857.011466674709, 6878.828029299208, 6954.721811127498, 7008.509404341749, 6438.90341644885, 6491.285958544557, 6513.772017979442, 6522.315156908551, 6529.54293199481, 6597.934020659732, 6632.668366581748, 6656.0, 6708.496862735412, 6721.899171512529, 6561.934380656132, 6603.636799976048, 6694.59832410057, 6695.665216739241, 6717.429018533479, 6377.1390862233, 6480.630072362439, 6484.902726459201, 6531.92223283172, 6539.836504087246, 4921.680090794087, 4943.283223932438, 4952.678075925053, 4979.253112033224, 5015.648904576645, 6608.6034837907955, 6614.239362473377, 6617.66911654425, 6697.832554186184, 6698.899516507354, 6397.776077837253, 6451.290358060516, 6463.612183269019, 6527.608343499405, 6562.0, 6452.419282264619, 6460.806175814733, 6465.545252318191, 6534.150560427304, 6539.049464986839, 6274.1843560336865, 6282.842928926813, 6344.968275158594, 6397.712102955471, 6438.259600145856, 6015.849603759941, 6391.424771770563, 6411.134496842867, 6435.324290949383, 6480.837979050561, 6283.2460104787715, 6328.177336946167, 6330.8733825322315, 6354.17395738551, 6365.745370185141], "value": 5045.621768642168, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [878.991210087899, 906.5240748606976, 912.2200518556634, 912.6988093929004, 931.9953400233, 18131.365402210926, 18388.356407525738, 18394.356197533056, 18394.816051839476, 18566.535836604093, 15890.682186356265, 16484.83515164848, 16593.419230326912, 16604.169791510405, 16634.50096497104, 965.0590674092739, 985.6796541124128, 987.195435719886, 988.0514705882362, 989.0554520432969, 936.0, 969.4716379573115, 971.1647982734878, 975.3562648651891, 976.6484065736365, 1034.7361422837166, 1036.1400037968506, 1037.1391744851792, 1037.5123691864812, 1038.9896101039005, 964.2960638733764, 1003.1423133221136, 1023.355286169712, 1025.5897640943608, 1027.121810851726, 735.0627949364549, 738.7119023580808, 740.9222031686703, 741.1735914455397, 741.5698894641096, 16543.83456165435, 16772.83227167725, 16894.324227030906, 16899.83100168995, 16930.0, 21546.0, 21590.784092159036, 22277.220297289517, 22323.772192529403, 22500.21249256268, 16616.086115263657, 16734.74897876533, 16823.66352672952, 16861.747073793904, 16964.575885602837, 3712.9071773205887, 3743.606921273254, 3755.43668449732, 3756.624337566249, 3781.5840257571645, 137722.31138844343, 143440.0, 144746.0, 146126.0, 148460.25769871188, 16919.407820726214, 17466.476005719847, 17527.298908043536, 17580.38468653591, 17586.120693965506, 32415.18962025921, 32925.17687057843, 33272.0, 33312.666873331444, 33322.83338583291, 8557.529335886586, 8636.0, 8727.214550690469, 8752.69365572202, 8782.077881822317, 4169.478815148106, 4299.570042995738, 4311.0, 4314.460692413449, 4322.5677432256525, 4664.930026049612, 4737.2183589708, 4760.047990401864, 4762.52374762521, 4780.091782561302, 1073.4364458659265, 1100.5597760895705, 1101.5043230546287, 1113.1762495753085, 1120.8710998235308, 648.7567162314132, 657.2080642825384, 671.5467059734659, 677.434342324155, 683.3542302524166, 10683.252172348017, 10774.569017239222, 10776.29954052984, 11287.209895307415, 11557.595484158182, 656.264983218798, 658.3054877104605, 659.5712786688595, 666.8566258254401, 671.5668393885916, 5366.946330536723, 5422.132458806572, 5441.401445840955, 5462.333988133658, 5518.889622207534, 123691.0, 123804.38097809453, 124217.37891310666, 124662.0, 125531.3723431377, 109615.45192273987, 114696.0, 115916.0, 116310.0, 117099.0, 98020.50989745006, 101338.49330753299, 101505.49247253864, 103222.48388758158, 103229.0, 9944.602215911282, 10039.648612298826, 10133.746656333356, 10216.387016779017, 10249.74375640615, 43909.56090439055, 43941.78029109834, 44047.55952440434, 44441.55558444374, 45114.548854511035, 57002.0, 57220.71389643025, 58377.0, 58930.41069589249, 60679.0, 15735.830005048443, 16088.517344479216, 16168.272427740545, 16268.837311626732, 16296.0, 17648.1175941205, 17676.2045707946, 17686.20412081434, 17734.46796596104, 17864.46406607804, 15432.0, 15474.767878481607, 15619.609509762347, 15653.295601697722, 15865.762013569574, 16091.839081609034, 16119.516414507583, 16202.756958645394, 16371.09958952269, 16504.83495165081, 15126.0, 15546.37814487408, 15594.766078508605, 15602.687946241238, 15729.134897580285, 10911.618093366695, 10988.945055274673, 11189.664310070712, 11390.886091138982, 11507.0, 11350.772984540097, 11482.311061336346, 11529.82705259405, 11602.303861767983, 11672.416379181177], "value": 8826.322719869842, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1422.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 108.0, 108.0, 113.0, 107.0, 107.0, 107.0, 107.0, 108.0], "value": 108.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 116.0, 120.0, 118.0, 122.0, 118.0, 118.0, 121.0, 117.0], "value": 118.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 165.0, 163.0, 164.0, 162.0, 164.0, 168.0, 171.0, 167.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 76.0, 73.0, 73.0, 75.0, 74.0, 73.0, 75.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 78.0, 79.0, 78.0, 82.0, 79.0, 79.0, 79.0, 80.0], "value": 79.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 114.0, 109.0, 114.0, 114.0, 114.0, 114.0, 115.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 148.0, 148.0, 148.0, 148.0, 148.0, 148.0, 148.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 94.0, 94.0, 95.0, 93.0, 99.0, 94.0, 95.0, 96.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 56.0, 55.0, 55.0, 55.0, 55.0, 56.0, 54.0, 55.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 38.0, 38.0, 37.0, 38.0, 38.0, 39.0, 38.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 78.0, 75.0, 75.0, 74.0, 78.0, 73.0, 85.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 112.0, 113.0, 111.0, 115.0, 111.0, 118.0, 112.0, 117.0, 112.0], "value": 113.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 172.0, 172.0, 173.0, 174.0, 165.0, 171.0, 167.0, 166.0, 173.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 65.0, 65.0, 65.0, 63.0, 64.0, 73.0, 64.0, 64.0], "value": 66.2, "unit": "score"}]}]} 12:28:00 INFO - Return code: 0 12:28:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:28:00 INFO - # TBPL SUCCESS # 12:28:00 INFO - Running post-action listener: _package_coverage_data 12:28:00 INFO - Running post-action listener: _resource_record_post_action 12:28:00 INFO - [mozharness: 2017-09-15 19:28:00.246937Z] Finished run-tests step (success) 12:28:00 INFO - Running post-run listener: _resource_record_post_run 12:28:00 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 421888; Write bytes: 333393920; Read time: 28; Write time: 189800 12:28:00 INFO - TinderboxPrint: CPU usage
13.1% 12:28:00 INFO - TinderboxPrint: I/O read bytes / time
421,888 / 28 12:28:00 INFO - TinderboxPrint: I/O write bytes / time
333,393,920 / 189,800 12:28:00 INFO - TinderboxPrint: CPU idle
8,231.0 (86.9%) 12:28:00 INFO - TinderboxPrint: CPU user
1,193.7 (12.6%) 12:28:00 INFO - TinderboxPrint: Swap in / out
0 / 0 12:28:00 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10051584; Read time: 0; Write time: 728 12:28:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:28:00 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 417792; Write bytes: 323342336; Read time: 20; Write time: 189072 12:28:00 INFO - Running post-run listener: _upload_blobber_files 12:28:00 INFO - Blob upload gear active. 12:28:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:28:00 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 12:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:28:01 INFO - (blobuploader) - INFO - Open directory for files ... 12:28:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:28:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:28:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:02 INFO - (blobuploader) - INFO - Done attempting. 12:28:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:28:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:28:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:03 INFO - (blobuploader) - INFO - Done attempting. 12:28:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:28:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:28:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:10 INFO - (blobuploader) - INFO - Done attempting. 12:28:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:28:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:28:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:28:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:28:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:28:11 INFO - (blobuploader) - INFO - Done attempting. 12:28:11 INFO - (blobuploader) - INFO - Iteration through files over. 12:28:11 INFO - Return code: 0 12:28:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:28:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:28:11 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f5e61e959f71b26ebeb741c8437cb194a2da618a439bf65cdbb64017730ef62a2cf52acc6104e28254114e1f2385807dd15cafdcd04f1d0d3e9146a261c49c2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6acbdf236f3c7e1aa134af1e5112ef66ca9613e477f5bf64319c65b489a17ad3a0a8e4af5ff0f7180bd48dbaece09c9d54374b4cf2abd817f6c580ec03beb93", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c0e592857c9998e602ec1eea83fc6de44ba9030830f04bffcf7b77d60753cb8d4bf4b7b3165aae221b9f86a7b626079b4875fdf643c454799030def22e387bb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/837f638ee43bb8ba77e4dd95a8f43193f37a78f90b93f4d7e8788681f8f25f6c40976ebf868e7ccaaa25a87b773507838520931435f9025fe64af39ee59aebc1"} 12:28:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:28:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:28:11 INFO - Contents: 12:28:11 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f5e61e959f71b26ebeb741c8437cb194a2da618a439bf65cdbb64017730ef62a2cf52acc6104e28254114e1f2385807dd15cafdcd04f1d0d3e9146a261c49c2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6acbdf236f3c7e1aa134af1e5112ef66ca9613e477f5bf64319c65b489a17ad3a0a8e4af5ff0f7180bd48dbaece09c9d54374b4cf2abd817f6c580ec03beb93", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c0e592857c9998e602ec1eea83fc6de44ba9030830f04bffcf7b77d60753cb8d4bf4b7b3165aae221b9f86a7b626079b4875fdf643c454799030def22e387bb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/837f638ee43bb8ba77e4dd95a8f43193f37a78f90b93f4d7e8788681f8f25f6c40976ebf868e7ccaaa25a87b773507838520931435f9025fe64af39ee59aebc1"} 12:28:11 INFO - Running post-run listener: copy_logs_to_upload_dir 12:28:11 INFO - Copying logs to upload dir... 12:28:11 INFO - mkdir: /builds/slave/test/build/upload/logs 12:28:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.761101 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-15 12:28:11.701715) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 12:28:11.705110) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f5e61e959f71b26ebeb741c8437cb194a2da618a439bf65cdbb64017730ef62a2cf52acc6104e28254114e1f2385807dd15cafdcd04f1d0d3e9146a261c49c2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6acbdf236f3c7e1aa134af1e5112ef66ca9613e477f5bf64319c65b489a17ad3a0a8e4af5ff0f7180bd48dbaece09c9d54374b4cf2abd817f6c580ec03beb93", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c0e592857c9998e602ec1eea83fc6de44ba9030830f04bffcf7b77d60753cb8d4bf4b7b3165aae221b9f86a7b626079b4875fdf643c454799030def22e387bb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/837f638ee43bb8ba77e4dd95a8f43193f37a78f90b93f4d7e8788681f8f25f6c40976ebf868e7ccaaa25a87b773507838520931435f9025fe64af39ee59aebc1"} build_url:https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011465 build_url: 'https://queue.taskcluster.net/v1/task/SAlNeWrsQmaSXz_lbxgWHA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f5e61e959f71b26ebeb741c8437cb194a2da618a439bf65cdbb64017730ef62a2cf52acc6104e28254114e1f2385807dd15cafdcd04f1d0d3e9146a261c49c2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6acbdf236f3c7e1aa134af1e5112ef66ca9613e477f5bf64319c65b489a17ad3a0a8e4af5ff0f7180bd48dbaece09c9d54374b4cf2abd817f6c580ec03beb93", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c0e592857c9998e602ec1eea83fc6de44ba9030830f04bffcf7b77d60753cb8d4bf4b7b3165aae221b9f86a7b626079b4875fdf643c454799030def22e387bb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/837f638ee43bb8ba77e4dd95a8f43193f37a78f90b93f4d7e8788681f8f25f6c40976ebf868e7ccaaa25a87b773507838520931435f9025fe64af39ee59aebc1"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 12:28:11.777801) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:28:11.778365) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1505501795.499576-1478597123 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004469 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:28:11.859601) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 12:28:11.859944) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 12:28:11.860278) ========= ========= Total master_lag: 0.24 =========