builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-049 starttime: 1505413830.54 results: success (0) revision: 593158cd491002031b4527a95d9bfac79c0cdcef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:30.543015) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:30.543484) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:30.543762) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:30.652357) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:30.652813) ========= 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=db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-14 11:30: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2017-09-14 11:30:30 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106333 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:30.816938) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:30.817319) ========= 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=db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018314 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:30.878492) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:30.878783) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 593158cd491002031b4527a95d9bfac79c0cdcef --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 593158cd491002031b4527a95d9bfac79c0cdcef --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=db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-14 11:30:30,931 truncating revision to first 12 chars 2017-09-14 11:30:30,932 Setting DEBUG logging. 2017-09-14 11:30:30,932 attempt 1/10 2017-09-14 11:30:30,932 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/593158cd4910?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-14 11:30:31,482 unpacking tar archive at: mozilla-central-593158cd4910/testing/mozharness/ program finished with exit code 0 elapsedTime=0.879336 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:31.775152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:31.775469) ========= script_repo_revision: 593158cd491002031b4527a95d9bfac79c0cdcef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:31.775864) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:31.776127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:31.788690) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 25 secs) (at 2017-09-14 11:30:31.788950) ========= /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=db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:30:31 INFO - MultiFileLogger online at 20170914 11:30:31 in /builds/slave/test 11:30:31 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 11:30:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:30:31 INFO - {'append_to_log': False, 11:30:31 INFO - 'base_work_dir': '/builds/slave/test', 11:30:31 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:30:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:30:31 INFO - 'branch': 'Firefox-Non-PGO', 11:30:31 INFO - 'buildbot_json_path': 'buildprops.json', 11:30:31 INFO - 'code_coverage': False, 11:30:31 INFO - 'config_files': ('talos/linux_config.py',), 11:30:31 INFO - 'default_actions': ('clobber', 11:30:31 INFO - 'read-buildbot-config', 11:30:31 INFO - 'download-and-extract', 11:30:31 INFO - 'populate-webroot', 11:30:31 INFO - 'create-virtualenv', 11:30:31 INFO - 'install', 11:30:31 INFO - 'setup-mitmproxy', 11:30:31 INFO - 'run-tests'), 11:30:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:30:31 INFO - 'disable_ccov_upload': False, 11:30:31 INFO - 'disable_stylo': False, 11:30:31 INFO - 'download_minidump_stackwalk': True, 11:30:31 INFO - 'download_symbols': 'ondemand', 11:30:31 INFO - 'enable_stylo': False, 11:30:31 INFO - 'enable_webrender': False, 11:30:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:30:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:30:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:30:31 INFO - 'gecko_profile': False, 11:30:31 INFO - 'gecko_profile_interval': 0, 11:30:31 INFO - 'installer_path': 'installer.exe', 11:30:31 INFO - 'log_level': 'info', 11:30:31 INFO - 'log_name': 'talos', 11:30:31 INFO - 'log_to_console': True, 11:30:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:30:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:30:31 INFO - 'opt_config_files': (), 11:30:31 INFO - 'pip_index': False, 11:30:31 INFO - 'suite': 'dromaeojs-e10s', 11:30:31 INFO - 'system_bits': '32', 11:30:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:30:31 INFO - 'title': 'talos-linux64-ix-049', 11:30:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:30:31 INFO - 'use_talos_json': True, 11:30:31 INFO - 'verify': 'False', 11:30:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:30:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:30:31 INFO - 'work_dir': 'build'} 11:30:31 INFO - [mozharness: 2017-09-14 18:30:31.992385Z] Running clobber step. 11:30:31 INFO - Running pre-action listener: _resource_record_pre_action 11:30:31 INFO - Running main action method: clobber 11:30:31 INFO - rmtree: /builds/slave/test/build 11:30:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:30:33 INFO - Running post-action listener: _resource_record_post_action 11:30:33 INFO - [mozharness: 2017-09-14 18:30:33.534528Z] Finished clobber step (success) 11:30:33 INFO - [mozharness: 2017-09-14 18:30:33.534626Z] Running read-buildbot-config step. 11:30:33 INFO - Running pre-action listener: _resource_record_pre_action 11:30:33 INFO - Running main action method: read_buildbot_config 11:30:33 INFO - Using buildbot properties: 11:30:33 INFO - { 11:30:33 INFO - "project": "", 11:30:33 INFO - "product": "firefox", 11:30:33 INFO - "who": "ffxbld@noreply.mozilla.org", 11:30:33 INFO - "installer_path": "public/build/target.tar.bz2", 11:30:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:30:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:30:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:30:33 INFO - "stage_platform": "linux64", 11:30:33 INFO - "basedir": "/builds/slave/test", 11:30:33 INFO - "buildnumber": 1148, 11:30:33 INFO - "platform": "ubuntu64_hw", 11:30:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:30:33 INFO - "slavebuilddir": "test", 11:30:33 INFO - "taskId": "cmX4NNqFRfOQB_sE_jpWmA", 11:30:33 INFO - "branch": "mozilla-central", 11:30:33 INFO - "script_repo_revision": "production", 11:30:33 INFO - "revision": "593158cd491002031b4527a95d9bfac79c0cdcef", 11:30:33 INFO - "slavename": "talos-linux64-ix-049", 11:30:33 INFO - "repo_path": "mozilla-central" 11:30:33 INFO - } 11:30:33 INFO - Finding installer, test and symbols from parent task. 11:30:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cmX4NNqFRfOQB_sE_jpWmA'}, attempt #1 11:30:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cmX4NNqFRfOQB_sE_jpWmA'}, attempt #1 11:30:34 INFO - Task dependencies: IDcefCo4SICyNXkppCNLZA 11:30:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA'}, attempt #1 11:30:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 11:30:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.test_packages.json 11:30:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.crashreporter-symbols.zip 11:30:34 INFO - Running post-action listener: _resource_record_post_action 11:30:34 INFO - [mozharness: 2017-09-14 18:30:34.898334Z] Finished read-buildbot-config step (success) 11:30:34 INFO - [mozharness: 2017-09-14 18:30:34.898550Z] Running download-and-extract step. 11:30:34 INFO - Running pre-action listener: _resource_record_pre_action 11:30:34 INFO - Running main action method: download_and_extract 11:30:34 INFO - mkdir: /builds/slave/test/build/tests 11:30: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')]} 11:30:34 INFO - trying https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.test_packages.json 11:30:34 INFO - Downloading https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:30:35 INFO - Downloaded 989 bytes. 11:30:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:30:35 INFO - Using the following test package requirements: 11:30:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:30:35 INFO - u'common': [u'target.common.tests.zip'], 11:30:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:30:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:30:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:30:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:30:35 INFO - u'mozbase': [u'target.common.tests.zip'], 11:30:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:30:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:30:35 INFO - u'web-platform': [u'target.common.tests.zip', 11:30:35 INFO - u'target.web-platform.tests.tar.gz'], 11:30:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:30:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:30:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.common.tests.zip 11:30:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:30:35 INFO - Fetch https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.common.tests.zip into memory 11:30:37 INFO - Content-Length response header: 54593770 11:30:37 INFO - Bytes received: 54593770 11:30:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.talos.tests.zip 11:30:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:30:48 INFO - Fetch https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.talos.tests.zip into memory 11:30:49 INFO - Content-Length response header: 14500356 11:30:49 INFO - Bytes received: 14500356 11:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:50 INFO - trying https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 11:30:50 INFO - Downloading https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:30:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:30:52 INFO - Downloaded 61304833 bytes. 11:30:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 11:30:53 INFO - mkdir: /builds/slave/test/properties 11:30:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:30:53 INFO - Writing to file /builds/slave/test/properties/build_url 11:30:53 INFO - Contents: 11:30:53 INFO - build_url:https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 11:30:53 INFO - Running post-action listener: _resource_record_post_action 11:30:53 INFO - Running post-action listener: find_tests_for_verification 11:30:53 INFO - Running post-action listener: set_extra_try_arguments 11:30:53 INFO - [mozharness: 2017-09-14 18:30:53.075388Z] Finished download-and-extract step (success) 11:30:53 INFO - [mozharness: 2017-09-14 18:30:53.075620Z] Running populate-webroot step. 11:30:53 INFO - Running pre-action listener: _resource_record_pre_action 11:30:53 INFO - Running main action method: populate_webroot 11:30:53 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:30:53 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['tresize', 11:30:53 INFO - 'tcanvasmark']}, 11:30:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:30:53 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['dromaeo_css', 11:30:53 INFO - 'kraken']}, 11:30:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:30:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:53 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:53 INFO - 'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:30:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:30:53 INFO - 'tests': ['damp', 'tps']}, 11:30:53 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:53 INFO - 'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['damp', 'tps']}, 11:30:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:30:53 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['dromaeo_dom']}, 11:30:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:30:53 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['basic_compositor_video', 11:30:53 INFO - 'glvideo']}, 11:30:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:30:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:30:53 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:53 INFO - 'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['ts_paint_webext', 11:30:53 INFO - 'tp5o_webext']}, 11:30:53 INFO - 'other-e10s': {'tests': ['a11yr', 11:30:53 INFO - 'ts_paint', 11:30:53 INFO - 'tpaint', 11:30:53 INFO - 'sessionrestore', 11:30:53 INFO - 'sessionrestore_many_windows', 11:30:53 INFO - 'sessionrestore_no_auto_restore', 11:30:53 INFO - 'tabpaint']}, 11:30:53 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['a11yr', 11:30:53 INFO - 'ts_paint', 11:30:53 INFO - 'tpaint', 11:30:53 INFO - 'sessionrestore', 11:30:53 INFO - 'sessionrestore_many_windows', 11:30:53 INFO - 'sessionrestore_no_auto_restore', 11:30:53 INFO - 'tabpaint']}, 11:30:53 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:30:53 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:30:53 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['bloom_basic_singleton']}, 11:30:53 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['bloom_basic']}, 11:30:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:30:53 INFO - 'tsvgr_opacity', 11:30:53 INFO - 'tart', 11:30:53 INFO - 'tscrollx', 11:30:53 INFO - 'tsvg_static']}, 11:30:53 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['tsvgx', 11:30:53 INFO - 'tsvgr_opacity', 11:30:53 INFO - 'tart', 11:30:53 INFO - 'tscrollx', 11:30:53 INFO - 'tsvg_static']}, 11:30:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:30:53 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:53 INFO - 'talos_options': ['--disable-stylo'], 11:30:53 INFO - 'tests': ['tp5o']}, 11:30:53 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:53 INFO - 'talos_options': ['--mitmproxy', 11:30:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:53 INFO - '--firstNonBlankPaint'], 11:30:53 INFO - 'tests': ['tp6_google', 11:30:53 INFO - 'tp6_youtube', 11:30:53 INFO - 'tp6_amazon', 11:30:53 INFO - 'tp6_facebook']}, 11:30:53 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:53 INFO - 'talos_options': ['--disable-stylo', 11:30:53 INFO - '--mitmproxy', 11:30:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:53 INFO - '--firstNonBlankPaint'], 11:30:53 INFO - 'tests': ['tp6_google', 11:30:53 INFO - 'tp6_youtube', 11:30:53 INFO - 'tp6_amazon', 11:30:53 INFO - 'tp6_facebook']}, 11:30:53 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:30:53 INFO - 'talos_options': ['--stylo-threads=1', 11:30:53 INFO - '--mitmproxy', 11:30:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:30:53 INFO - '--firstNonBlankPaint'], 11:30:53 INFO - 'tests': ['tp6_google', 11:30:53 INFO - 'tp6_youtube', 11:30:53 INFO - 'tp6_amazon', 11:30:53 INFO - 'tp6_facebook']}, 11:30:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:30:53 INFO - 'talos_options': ['--xperf_path', 11:30:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:53 INFO - 'tests': ['tp5n']}, 11:30:53 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:30:53 INFO - 'talos_options': ['--disable-stylo', 11:30:53 INFO - '--xperf_path', 11:30:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:30:53 INFO - 'tests': ['tp5n']}}} 11:30:53 INFO - Running post-action listener: _resource_record_post_action 11:30:53 INFO - [mozharness: 2017-09-14 18:30:53.232035Z] Finished populate-webroot step (success) 11:30:53 INFO - [mozharness: 2017-09-14 18:30:53.232130Z] Running create-virtualenv step. 11:30:53 INFO - Running pre-action listener: _resource_record_pre_action 11:30:53 INFO - Running main action method: create_virtualenv 11:30:53 INFO - Creating virtualenv /builds/slave/test/build/venv 11:30:53 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:30:53 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:30:53 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:30:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:30:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:30:53 INFO - Installing distribute.............................................................................................................................................................................................done. 11:30:54 INFO - Installing pip...............done. 11:30:54 INFO - Return code: 0 11:30:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:30:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:30:54 INFO - Reading from file tmpfile_stdout 11:30:54 INFO - Output received: 11:30:54 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:30:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:30:54 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')]} 11:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:54 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')]} 11:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:54 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 0x236d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2536a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dd090>, '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 0x26ddd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d7f90>, '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': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', '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 11:30:54 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 11:30:54 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 11:30:54 INFO - Using env: {'DISPLAY': ':0', 11:30:54 INFO - 'HOME': '/home/cltbld', 11:30:54 INFO - 'LANG': 'en_US.UTF-8', 11:30:54 INFO - 'LANGUAGE': 'en_US:en', 11:30:54 INFO - 'LOGNAME': 'cltbld', 11:30:54 INFO - 'MAIL': '/var/mail/cltbld', 11:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:54 INFO - 'MOZ_NO_REMOTE': '1', 11:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:54 INFO - 'NO_EM_RESTART': '1', 11:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:54 INFO - 'PWD': '/builds/slave/test', 11:30:54 INFO - 'SHELL': '/bin/bash', 11:30:54 INFO - 'SHLVL': '1', 11:30:54 INFO - 'TERM': 'linux', 11:30:54 INFO - 'TMOUT': '86400', 11:30:54 INFO - 'USER': 'cltbld', 11:30:54 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:54 INFO - '_': '/tools/buildbot/bin/python'} 11:30:55 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:30:55 INFO - Downloading/unpacking pip>=1.5 11:30:55 INFO - Running setup.py egg_info for package pip 11:30:55 INFO - warning: no files found matching 'pip/cacert.pem' 11:30:55 INFO - warning: no files found matching '*.html' under directory 'docs' 11:30:55 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:30:55 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:30:55 INFO - Installing collected packages: pip 11:30:55 INFO - Found existing installation: pip 1.1 11:30:55 INFO - Uninstalling pip: 11:30:55 INFO - Successfully uninstalled pip 11:30:55 INFO - Running setup.py install for pip 11:30:55 INFO - warning: no files found matching 'pip/cacert.pem' 11:30:55 INFO - warning: no files found matching '*.html' under directory 'docs' 11:30:55 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:30:55 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:30:55 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:30:55 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:30:55 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:30:55 INFO - Successfully installed pip 11:30:55 INFO - Cleaning up... 11:30:55 INFO - Return code: 0 11:30:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:30: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')]} 11:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30: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')]} 11:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30: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', '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 0x236d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2536a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dd090>, '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 0x26ddd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d7f90>, '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': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', '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 11:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:30: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 psutil>=3.1.1 11:30:55 INFO - Using env: {'DISPLAY': ':0', 11:30:55 INFO - 'HOME': '/home/cltbld', 11:30:55 INFO - 'LANG': 'en_US.UTF-8', 11:30:55 INFO - 'LANGUAGE': 'en_US:en', 11:30:55 INFO - 'LOGNAME': 'cltbld', 11:30:55 INFO - 'MAIL': '/var/mail/cltbld', 11:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:55 INFO - 'MOZ_NO_REMOTE': '1', 11:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:55 INFO - 'NO_EM_RESTART': '1', 11:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:55 INFO - 'PWD': '/builds/slave/test', 11:30:55 INFO - 'SHELL': '/bin/bash', 11:30:55 INFO - 'SHLVL': '1', 11:30:55 INFO - 'TERM': 'linux', 11:30:55 INFO - 'TMOUT': '86400', 11:30:55 INFO - 'USER': 'cltbld', 11:30:55 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:55 INFO - '_': '/tools/buildbot/bin/python'} 11:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:55 INFO - Downloading/unpacking psutil>=3.1.1 11:30:55 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 11:30:55 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 11:30:55 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 11:30:55 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 11:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:30:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:30:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:30:56 INFO - Installing collected packages: psutil 11:30:56 INFO - Running setup.py install for psutil 11:30:57 INFO - building 'psutil._psutil_linux' extension 11:30:57 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 11:30:57 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 11:30:57 INFO - building 'psutil._psutil_posix' extension 11:30:57 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 11:30:57 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 11:30:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:30:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:30:57 INFO - Successfully installed psutil 11:30:57 INFO - Cleaning up... 11:30:57 INFO - Return code: 0 11:30:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:30: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')]} 11:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30: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')]} 11:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x236d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2536a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dd090>, '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 0x26ddd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d7f90>, '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': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', '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 11:30: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:30: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 mozsystemmonitor==0.3 11:30:57 INFO - Using env: {'DISPLAY': ':0', 11:30:57 INFO - 'HOME': '/home/cltbld', 11:30:57 INFO - 'LANG': 'en_US.UTF-8', 11:30:57 INFO - 'LANGUAGE': 'en_US:en', 11:30:57 INFO - 'LOGNAME': 'cltbld', 11:30:57 INFO - 'MAIL': '/var/mail/cltbld', 11:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:57 INFO - 'MOZ_NO_REMOTE': '1', 11:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:57 INFO - 'NO_EM_RESTART': '1', 11:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:57 INFO - 'PWD': '/builds/slave/test', 11:30:57 INFO - 'SHELL': '/bin/bash', 11:30:57 INFO - 'SHLVL': '1', 11:30:57 INFO - 'TERM': 'linux', 11:30:57 INFO - 'TMOUT': '86400', 11:30:57 INFO - 'USER': 'cltbld', 11:30:57 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:57 INFO - '_': '/tools/buildbot/bin/python'} 11:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:30:57 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 11:30:57 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 11:30:57 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 11:30:57 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 11:30:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:30:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:30:58 INFO - Installing collected packages: mozsystemmonitor 11:30:58 INFO - Running setup.py install for mozsystemmonitor 11:30:59 INFO - Successfully installed mozsystemmonitor 11:30:59 INFO - Cleaning up... 11:30:59 INFO - Return code: 0 11:30:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:30:59 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')]} 11:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:30:59 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')]} 11:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:30:59 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 0x236d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2536a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dd090>, '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 0x26ddd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d7f90>, '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': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', '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 11:30:59 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 11:30:59 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 11:30:59 INFO - Using env: {'DISPLAY': ':0', 11:30:59 INFO - 'HOME': '/home/cltbld', 11:30:59 INFO - 'LANG': 'en_US.UTF-8', 11:30:59 INFO - 'LANGUAGE': 'en_US:en', 11:30:59 INFO - 'LOGNAME': 'cltbld', 11:30:59 INFO - 'MAIL': '/var/mail/cltbld', 11:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:30:59 INFO - 'MOZ_NO_REMOTE': '1', 11:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:30:59 INFO - 'NO_EM_RESTART': '1', 11:30:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:30:59 INFO - 'PWD': '/builds/slave/test', 11:30:59 INFO - 'SHELL': '/bin/bash', 11:30:59 INFO - 'SHLVL': '1', 11:30:59 INFO - 'TERM': 'linux', 11:30:59 INFO - 'TMOUT': '86400', 11:30:59 INFO - 'USER': 'cltbld', 11:30:59 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:30:59 INFO - '_': '/tools/buildbot/bin/python'} 11:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:30:59 INFO - Downloading/unpacking jsonschema==2.5.1 11:30: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 11:30: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 11:30: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 11:30: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 11:31:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:31:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:31: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 11:31: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 11:31: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 11:31: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 11:31:01 INFO - Downloading functools32-3.2.3-2.tar.gz 11:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:31:01 INFO - warning: no files found matching '*.txt' 11:31:01 INFO - no previously-included directories found matching 'build' 11:31:01 INFO - no previously-included directories found matching 'dist' 11:31:01 INFO - no previously-included directories found matching '.git*' 11:31:01 INFO - Installing collected packages: jsonschema, functools32 11:31:01 INFO - Running setup.py install for functools32 11:31:01 INFO - warning: no files found matching '*.txt' 11:31:01 INFO - no previously-included directories found matching 'build' 11:31:01 INFO - no previously-included directories found matching 'dist' 11:31:01 INFO - no previously-included directories found matching '.git*' 11:31:01 INFO - Successfully installed jsonschema functools32 11:31:01 INFO - Cleaning up... 11:31:01 INFO - Return code: 0 11:31:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:01 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 0x236d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2536a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dd090>, '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 0x26ddd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d7f90>, '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': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', '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 11:31:01 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 11:31:01 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 11:31:01 INFO - Using env: {'DISPLAY': ':0', 11:31:01 INFO - 'HOME': '/home/cltbld', 11:31:01 INFO - 'LANG': 'en_US.UTF-8', 11:31:01 INFO - 'LANGUAGE': 'en_US:en', 11:31:01 INFO - 'LOGNAME': 'cltbld', 11:31:01 INFO - 'MAIL': '/var/mail/cltbld', 11:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:01 INFO - 'MOZ_NO_REMOTE': '1', 11:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:01 INFO - 'NO_EM_RESTART': '1', 11:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:01 INFO - 'PWD': '/builds/slave/test', 11:31:01 INFO - 'SHELL': '/bin/bash', 11:31:01 INFO - 'SHLVL': '1', 11:31:01 INFO - 'TERM': 'linux', 11:31:01 INFO - 'TMOUT': '86400', 11:31:01 INFO - 'USER': 'cltbld', 11:31:01 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:01 INFO - '_': '/tools/buildbot/bin/python'} 11:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:31:01 INFO - Cleaning up... 11:31:01 INFO - Return code: 0 11:31:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:01 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 0x236d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2536a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dd090>, '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 0x26ddd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d7f90>, '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': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', '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 11:31:01 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 11:31:01 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 11:31:01 INFO - Using env: {'DISPLAY': ':0', 11:31:01 INFO - 'HOME': '/home/cltbld', 11:31:01 INFO - 'LANG': 'en_US.UTF-8', 11:31:01 INFO - 'LANGUAGE': 'en_US:en', 11:31:01 INFO - 'LOGNAME': 'cltbld', 11:31:01 INFO - 'MAIL': '/var/mail/cltbld', 11:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:01 INFO - 'MOZ_NO_REMOTE': '1', 11:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:01 INFO - 'NO_EM_RESTART': '1', 11:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:01 INFO - 'PWD': '/builds/slave/test', 11:31:01 INFO - 'SHELL': '/bin/bash', 11:31:01 INFO - 'SHLVL': '1', 11:31:01 INFO - 'TERM': 'linux', 11:31:01 INFO - 'TMOUT': '86400', 11:31:01 INFO - 'USER': 'cltbld', 11:31:01 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:01 INFO - '_': '/tools/buildbot/bin/python'} 11:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:01 INFO - Downloading/unpacking blobuploader==1.2.4 11:31: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 11:31: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 11:31: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 11:31: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 11:31:02 INFO - Downloading blobuploader-1.2.4.tar.gz 11:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:31:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:31:02 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 11:31:02 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 11:31:02 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 11:31:02 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 11:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:31:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:31:03 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 11:31:03 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 11:31:03 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 11:31:03 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 11:31:03 INFO - Downloading docopt-0.6.1.tar.gz 11:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:31:03 INFO - Installing collected packages: blobuploader, requests, docopt 11:31:03 INFO - Running setup.py install for blobuploader 11:31:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:31:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:31:03 INFO - Running setup.py install for requests 11:31:04 INFO - Running setup.py install for docopt 11:31:04 INFO - Successfully installed blobuploader requests docopt 11:31:04 INFO - Cleaning up... 11:31:04 INFO - Return code: 0 11:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:04 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')]} 11:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:04 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')]} 11:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:04 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 0x236d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2536a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dd090>, '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 0x26ddd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d7f90>, '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': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', '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 11:31:04 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 11:31:04 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 11:31:04 INFO - Using env: {'DISPLAY': ':0', 11:31:04 INFO - 'HOME': '/home/cltbld', 11:31:04 INFO - 'LANG': 'en_US.UTF-8', 11:31:04 INFO - 'LANGUAGE': 'en_US:en', 11:31:04 INFO - 'LOGNAME': 'cltbld', 11:31:04 INFO - 'MAIL': '/var/mail/cltbld', 11:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:04 INFO - 'MOZ_NO_REMOTE': '1', 11:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:04 INFO - 'NO_EM_RESTART': '1', 11:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:04 INFO - 'PWD': '/builds/slave/test', 11:31:04 INFO - 'SHELL': '/bin/bash', 11:31:04 INFO - 'SHLVL': '1', 11:31:04 INFO - 'TERM': 'linux', 11:31:04 INFO - 'TMOUT': '86400', 11:31:04 INFO - 'USER': 'cltbld', 11:31:04 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:04 INFO - '_': '/tools/buildbot/bin/python'} 11:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:04 INFO - Running setup.py (path:/tmp/pip-A4PYKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:04 INFO - Running setup.py (path:/tmp/pip-1Du8Mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:04 INFO - Running setup.py (path:/tmp/pip-PyS9L_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:04 INFO - Running setup.py (path:/tmp/pip-y_MjDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:04 INFO - Running setup.py (path:/tmp/pip-qNkEVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:04 INFO - Running setup.py (path:/tmp/pip-WPapVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:04 INFO - Running setup.py (path:/tmp/pip-nlyupi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:04 INFO - Running setup.py (path:/tmp/pip-8Zllaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:04 INFO - Running setup.py (path:/tmp/pip-wlpplu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:04 INFO - Running setup.py (path:/tmp/pip-74VfBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:05 INFO - Running setup.py (path:/tmp/pip-RsdTP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:05 INFO - Running setup.py (path:/tmp/pip-jOXlw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:05 INFO - Running setup.py (path:/tmp/pip-tzxo2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:05 INFO - Running setup.py (path:/tmp/pip-r1kJEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:05 INFO - Running setup.py (path:/tmp/pip-BIDODf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:05 INFO - Running setup.py (path:/tmp/pip-hKErbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:05 INFO - Running setup.py (path:/tmp/pip-DvUiGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:31:05 INFO - Running setup.py install for manifestparser 11:31:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:31:05 INFO - Running setup.py install for mozcrash 11:31:05 INFO - Running setup.py install for mozdebug 11:31:05 INFO - Running setup.py install for mozdevice 11:31:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:31:05 INFO - Running setup.py install for mozfile 11:31:06 INFO - Running setup.py install for mozhttpd 11:31:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:31:06 INFO - Running setup.py install for mozinfo 11:31:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:31:06 INFO - Running setup.py install for mozInstall 11:31:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:31:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:31:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:31:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:31:06 INFO - Running setup.py install for mozleak 11:31:06 INFO - Running setup.py install for mozlog 11:31:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:31:06 INFO - Running setup.py install for moznetwork 11:31:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:31:06 INFO - Running setup.py install for mozprocess 11:31:06 INFO - Running setup.py install for mozprofile 11:31:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:31:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:31:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:31:06 INFO - Running setup.py install for mozrunner 11:31:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:31:07 INFO - Running setup.py install for mozscreenshot 11:31:07 INFO - Running setup.py install for moztest 11:31:07 INFO - Running setup.py install for mozversion 11:31:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:31:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:31:07 INFO - Cleaning up... 11:31:07 INFO - Return code: 0 11:31:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:07 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 0x236d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2536a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dd090>, '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 0x26ddd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d7f90>, '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': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', '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 11:31:07 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 11:31:07 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 11:31:07 INFO - Using env: {'DISPLAY': ':0', 11:31:07 INFO - 'HOME': '/home/cltbld', 11:31:07 INFO - 'LANG': 'en_US.UTF-8', 11:31:07 INFO - 'LANGUAGE': 'en_US:en', 11:31:07 INFO - 'LOGNAME': 'cltbld', 11:31:07 INFO - 'MAIL': '/var/mail/cltbld', 11:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:07 INFO - 'MOZ_NO_REMOTE': '1', 11:31:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:07 INFO - 'NO_EM_RESTART': '1', 11:31:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:07 INFO - 'PWD': '/builds/slave/test', 11:31:07 INFO - 'SHELL': '/bin/bash', 11:31:07 INFO - 'SHLVL': '1', 11:31:07 INFO - 'TERM': 'linux', 11:31:07 INFO - 'TMOUT': '86400', 11:31:07 INFO - 'USER': 'cltbld', 11:31:07 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:07 INFO - '_': '/tools/buildbot/bin/python'} 11:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:07 INFO - Running setup.py (path:/tmp/pip-E9fh_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:07 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)) 11:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:07 INFO - Running setup.py (path:/tmp/pip-xNZDrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:07 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)) 11:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:07 INFO - Running setup.py (path:/tmp/pip-SBI5Sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:07 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)) 11:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:07 INFO - Running setup.py (path:/tmp/pip-oh3S4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:07 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)) 11:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:07 INFO - Running setup.py (path:/tmp/pip-9rzEKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:07 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)) 11:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:07 INFO - Running setup.py (path:/tmp/pip-nEJX33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:07 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)) 11:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:07 INFO - Running setup.py (path:/tmp/pip-Ga7pI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:08 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)) 11:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:08 INFO - Running setup.py (path:/tmp/pip-GA3sH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:08 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)) 11:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:08 INFO - Running setup.py (path:/tmp/pip-RYImgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:08 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)) 11:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:08 INFO - Running setup.py (path:/tmp/pip-PpsQRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:08 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)) 11:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:08 INFO - Running setup.py (path:/tmp/pip-35hneu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:08 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)) 11:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:08 INFO - Running setup.py (path:/tmp/pip-6xxMhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:08 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)) 11:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:08 INFO - Running setup.py (path:/tmp/pip-XvTK2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:08 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)) 11:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:08 INFO - Running setup.py (path:/tmp/pip-aCSxJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:08 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)) 11:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:08 INFO - Running setup.py (path:/tmp/pip-GXNeAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:08 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)) 11:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:08 INFO - Running setup.py (path:/tmp/pip-ZMGnus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:08 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)) 11:31:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:08 INFO - Running setup.py (path:/tmp/pip-kjO4jS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 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)) 11:31:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:31:08 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 11:31:08 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 11:31:08 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 11:31:08 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 11:31:09 INFO - Downloading blessings-1.6.tar.gz 11:31:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:31:09 INFO - Installing collected packages: blessings 11:31:09 INFO - Running setup.py install for blessings 11:31:10 INFO - Successfully installed blessings 11:31:10 INFO - Cleaning up... 11:31:10 INFO - Return code: 0 11:31:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:10 INFO - Reading from file tmpfile_stdout 11:31:10 INFO - Current package versions: 11:31:10 INFO - argparse == 1.2.1 11:31:10 INFO - blessings == 1.6 11:31:10 INFO - blobuploader == 1.2.4 11:31:10 INFO - docopt == 0.6.1 11:31:10 INFO - functools32 == 3.2.3-2 11:31:10 INFO - jsonschema == 2.5.1 11:31:10 INFO - manifestparser == 1.1 11:31:10 INFO - mozInstall == 1.13 11:31:10 INFO - mozcrash == 1.0 11:31:10 INFO - mozdebug == 0.1 11:31:10 INFO - mozdevice == 0.51 11:31:10 INFO - mozfile == 1.2 11:31:10 INFO - mozhttpd == 0.7 11:31:10 INFO - mozinfo == 0.10 11:31:10 INFO - mozleak == 0.1 11:31:10 INFO - mozlog == 3.5 11:31:10 INFO - moznetwork == 0.27 11:31:10 INFO - mozprocess == 0.25 11:31:10 INFO - mozprofile == 0.28 11:31:10 INFO - mozrunner == 6.13 11:31:10 INFO - mozscreenshot == 0.1 11:31:10 INFO - mozsystemmonitor == 0.3 11:31:10 INFO - moztest == 0.8 11:31:10 INFO - mozversion == 1.4 11:31:10 INFO - psutil == 3.1.1 11:31:10 INFO - requests == 1.2.3 11:31:10 INFO - wsgiref == 0.1.2 11:31:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:10 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 0x236d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2536a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dd090>, '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 0x26ddd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d7f90>, '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': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', '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 11:31:10 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 11:31:10 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 11:31:10 INFO - Using env: {'DISPLAY': ':0', 11:31:10 INFO - 'HOME': '/home/cltbld', 11:31:10 INFO - 'LANG': 'en_US.UTF-8', 11:31:10 INFO - 'LANGUAGE': 'en_US:en', 11:31:10 INFO - 'LOGNAME': 'cltbld', 11:31:10 INFO - 'MAIL': '/var/mail/cltbld', 11:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:10 INFO - 'MOZ_NO_REMOTE': '1', 11:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:10 INFO - 'NO_EM_RESTART': '1', 11:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:10 INFO - 'PWD': '/builds/slave/test', 11:31:10 INFO - 'SHELL': '/bin/bash', 11:31:10 INFO - 'SHLVL': '1', 11:31:10 INFO - 'TERM': 'linux', 11:31:10 INFO - 'TMOUT': '86400', 11:31:10 INFO - 'USER': 'cltbld', 11:31:10 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:10 INFO - '_': '/tools/buildbot/bin/python'} 11:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:10 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)) 11:31:10 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)) 11:31:10 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)) 11:31:10 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)) 11:31:10 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)) 11:31:10 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)) 11:31:10 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)) 11:31:10 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)) 11:31:10 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)) 11:31:10 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:31:10 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 11:31:10 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 11:31:10 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 11:31:10 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 11:31:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:31:11 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)) 11:31:11 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)) 11:31:11 INFO - Installing collected packages: simplejson 11:31:11 INFO - Running setup.py install for simplejson 11:31:11 INFO - building 'simplejson._speedups' extension 11:31:11 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 11:31:12 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 11:31:12 INFO - Successfully installed simplejson 11:31:12 INFO - Cleaning up... 11:31:12 INFO - Return code: 0 11:31:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:31:12 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')]} 11:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:12 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')]} 11:31:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:12 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 0x236d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2536a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dd090>, '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 0x26ddd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d7f90>, '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': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', '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 11:31:12 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 11:31:12 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 11:31:12 INFO - Using env: {'DISPLAY': ':0', 11:31:12 INFO - 'HOME': '/home/cltbld', 11:31:12 INFO - 'LANG': 'en_US.UTF-8', 11:31:12 INFO - 'LANGUAGE': 'en_US:en', 11:31:12 INFO - 'LOGNAME': 'cltbld', 11:31:12 INFO - 'MAIL': '/var/mail/cltbld', 11:31:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:12 INFO - 'MOZ_NO_REMOTE': '1', 11:31:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:12 INFO - 'NO_EM_RESTART': '1', 11:31:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:12 INFO - 'PWD': '/builds/slave/test', 11:31:12 INFO - 'SHELL': '/bin/bash', 11:31:12 INFO - 'SHLVL': '1', 11:31:12 INFO - 'TERM': 'linux', 11:31:12 INFO - 'TMOUT': '86400', 11:31:12 INFO - 'USER': 'cltbld', 11:31:12 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:31:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:12 INFO - '_': '/tools/buildbot/bin/python'} 11:31:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:31:12 INFO - Cleaning up... 11:31:13 INFO - Return code: 0 11:31:13 INFO - Running post-action listener: _resource_record_post_action 11:31:13 INFO - Running post-action listener: _start_resource_monitoring 11:31:13 INFO - Starting resource monitoring. 11:31:13 INFO - [mozharness: 2017-09-14 18:31:13.032394Z] Finished create-virtualenv step (success) 11:31:13 INFO - [mozharness: 2017-09-14 18:31:13.032960Z] Running install step. 11:31:13 INFO - Running pre-action listener: _resource_record_pre_action 11:31:13 INFO - Running main action method: install 11:31:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:13 INFO - Reading from file tmpfile_stdout 11:31:13 INFO - Detecting whether we're running mozinstall >=1.0... 11:31:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:31:13 INFO - Reading from file tmpfile_stdout 11:31:13 INFO - Output received: 11:31:13 INFO - Usage: mozinstall [options] installer 11:31:13 INFO - Options: 11:31:13 INFO - -h, --help show this help message and exit 11:31:13 INFO - -d DEST, --destination=DEST 11:31:13 INFO - Directory to install application into. [default: 11:31:13 INFO - "/builds/slave/test"] 11:31:13 INFO - --app=APP Application being installed. [default: firefox] 11:31:13 INFO - mkdir: /builds/slave/test/build/application 11:31:13 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'] 11:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:31:31 INFO - Reading from file tmpfile_stdout 11:31:31 INFO - Output received: 11:31:31 INFO - /builds/slave/test/build/application/firefox/firefox 11:31:31 INFO - Running post-action listener: _resource_record_post_action 11:31:31 INFO - [mozharness: 2017-09-14 18:31:31.193582Z] Finished install step (success) 11:31:31 INFO - [mozharness: 2017-09-14 18:31:31.193832Z] Running setup-mitmproxy step. 11:31:31 INFO - Running pre-action listener: _resource_record_pre_action 11:31:31 INFO - Running main action method: setup_mitmproxy 11:31:31 INFO - Skipping: mitmproxy is not required 11:31:31 INFO - Running post-action listener: _resource_record_post_action 11:31:31 INFO - [mozharness: 2017-09-14 18:31:31.194954Z] Finished setup-mitmproxy step (success) 11:31:31 INFO - [mozharness: 2017-09-14 18:31:31.195174Z] Running run-tests step. 11:31:31 INFO - Running pre-action listener: _resource_record_pre_action 11:31:31 INFO - Running pre-action listener: _set_gcov_prefix 11:31:31 INFO - Running main action method: run_tests 11:31:31 WARNING - Try message not found. 11:31:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:31:31 INFO - Python 2.7.3 11:31:31 INFO - Return code: 0 11:31:31 INFO - grabbing minidump binary from tooltool 11:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:31 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 0x26dd090>, '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 0x26ddd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26d7f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:31:31 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 11:31:31 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 11:31:31 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 11:31:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:31:31 INFO - Return code: 0 11:31:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:31:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:31:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:31:31 INFO - ENV: RUST_BACKTRACE is now full 11:31:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:31:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:31:31 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/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 11:31:31 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/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-049 --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 11:31:31 INFO - Using env: {'DISPLAY': ':0', 11:31:31 INFO - 'HOME': '/home/cltbld', 11:31:31 INFO - 'LANG': 'en_US.UTF-8', 11:31:31 INFO - 'LANGUAGE': 'en_US:en', 11:31:31 INFO - 'LOGNAME': 'cltbld', 11:31:31 INFO - 'MAIL': '/var/mail/cltbld', 11:31:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:31 INFO - 'MOZ_NO_REMOTE': '1', 11:31:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:31 INFO - 'NO_EM_RESTART': '1', 11:31:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:31 INFO - 'PWD': '/builds/slave/test', 11:31:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:31 INFO - 'RUST_BACKTRACE': 'full', 11:31:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:31 INFO - 'SHELL': '/bin/bash', 11:31:31 INFO - 'SHLVL': '1', 11:31:31 INFO - 'TERM': 'linux', 11:31:31 INFO - 'TMOUT': '86400', 11:31:31 INFO - 'USER': 'cltbld', 11:31:31 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:31 INFO - '_': '/tools/buildbot/bin/python'} 11:31:31 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/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 11:31:31 INFO - mozversion application_buildid: 20170914174242 11:31:31 INFO - mozversion application_changeset: 593158cd491002031b4527a95d9bfac79c0cdcef 11:31:31 INFO - mozversion application_display_name: Nightly 11:31:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:31:31 INFO - mozversion application_name: Firefox 11:31:31 INFO - mozversion application_remotingname: firefox 11:31:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:31:31 INFO - mozversion application_vendor: Mozilla 11:31:31 INFO - mozversion application_version: 57.0a1 11:31:31 INFO - mozversion platform_buildid: 20170914174242 11:31:31 INFO - mozversion platform_changeset: 593158cd491002031b4527a95d9bfac79c0cdcef 11:31:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:31:31 INFO - mozversion platform_version: 57.0a1 11:31:31 INFO - using testdate: 1505413891 11:31:31 INFO - actual date: 1505413891 11:31:31 INFO - starting webserver on 'localhost:39658' 11:31:31 INFO - SUITE-START | Running 2 tests 11:31:31 INFO - TEST-START | dromaeo_css 11:31:31 INFO - Initialising browser for dromaeo_css test... 11:31:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39658/getInfo.html -profile /tmp/tmpaxMWpi/profile 11:31:31 INFO - TEST-INFO | started process 29322 (/builds/slave/test/build/application/firefox/firefox http://localhost:39658/getInfo.html) 11:31:37 INFO - TEST-INFO | 29322: exit 0 11:31:37 INFO - Browser initialized. 11:31:37 INFO - Running cycle 1/1 for dromaeo_css test... 11:31:37 INFO - Using env: {'DISPLAY': ':0', 11:31:37 INFO - 'HOME': '/home/cltbld', 11:31:37 INFO - 'JSGC_DISABLE_POISONING': '1', 11:31:37 INFO - 'LANG': 'en_US.UTF-8', 11:31:37 INFO - 'LANGUAGE': 'en_US:en', 11:31:37 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:31:37 INFO - 'LOGNAME': 'cltbld', 11:31:37 INFO - 'MAIL': '/var/mail/cltbld', 11:31:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:37 INFO - 'MOZ_CRASHREPORTER': '1', 11:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:37 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:31:37 INFO - 'MOZ_NO_REMOTE': '1', 11:31:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:37 INFO - 'NO_EM_RESTART': '1', 11:31:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:37 INFO - 'PWD': '/builds/slave/test', 11:31:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:37 INFO - 'RUST_BACKTRACE': 'full', 11:31:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:37 INFO - 'SHELL': '/bin/bash', 11:31:37 INFO - 'SHLVL': '1', 11:31:37 INFO - 'TERM': 'linux', 11:31:37 INFO - 'TMOUT': '86400', 11:31:37 INFO - 'USER': 'cltbld', 11:31:37 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:37 INFO - '_': '/tools/buildbot/bin/python'} 11:31:37 INFO - TEST-INFO | started process 29555 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaxMWpi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:38 INFO - PID 29555 | 11:31:38 INFO - PID 29555 | (/builds/slave/test/build/application/firefox/firefox:29604): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:31:38 INFO - PID 29555 | 11:31:48 INFO - PID 29555 | RSS: Main: 173043712 11:31:48 INFO - PID 29555 | 11:34:46 INFO - PID 29555 | Cycle 1(1): loaded http://localhost:39658/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39658/tests/dromaeo/cssquery-ext.html) 11:34:47 INFO - PID 29555 | RSS: Main: 175931392 11:34:47 INFO - PID 29555 | 11:37:49 INFO - PID 29555 | Cycle 1(1): loaded http://localhost:39658/tests/dromaeo/cssquery-ext.html (next: http://localhost:39658/tests/dromaeo/cssquery-jquery.html) 11:37:50 INFO - PID 29555 | RSS: Main: 172175360 11:37:50 INFO - PID 29555 | 11:40:51 INFO - PID 29555 | Cycle 1(1): loaded http://localhost:39658/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39658/tests/dromaeo/cssquery-mootools.html) 11:40:52 INFO - PID 29555 | RSS: Main: 174026752 11:40:52 INFO - PID 29555 | 11:43:54 INFO - PID 29555 | Cycle 1(1): loaded http://localhost:39658/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39658/tests/dromaeo/cssquery-prototype.html) 11:43:54 INFO - PID 29555 | RSS: Main: 172793856 11:43:54 INFO - PID 29555 | 11:46:57 INFO - PID 29555 | Cycle 1(1): loaded http://localhost:39658/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39658/tests/dromaeo/cssquery-yui.html) 11:46:58 INFO - PID 29555 | RSS: Main: 173899776 11:46:58 INFO - PID 29555 | 11:50:00 INFO - PID 29555 | Cycle 1(1): loaded http://localhost:39658/tests/dromaeo/cssquery-yui.html (next: http://localhost:39658/tests/dromaeo/cssquery-dojo.html) 11:50:00 INFO - PID 29555 | RSS: Main: 177192960 11:50:00 INFO - PID 29555 | 11:50:00 INFO - PID 29555 | __start_tp_report 11:50:00 INFO - PID 29555 | _x_x_mozilla_page_load 11:50:00 INFO - PID 29555 | _x_x_mozilla_page_load_details 11:50:00 INFO - PID 29555 | |i|pagename|runs| 11:50:00 INFO - PID 29555 | |0;dojo.html;3160.7155356017984;3165.493521036634;3193.776435649504;3201.4717571600686;3202.4715921872894;6200.34896335885;6212.44088032077;6271.717772700234;6310.4320611144985;6392.648404337765;6264.342244064376;6276.152719382893;6288.528360372968;6308.905366419507;6332.810015699536;5804.419558044204;5823.650580965155;5830.82507524775;5832.533397328225;5885.646861188321;5854.238948936632;5870.060790273557;5915.645061296314;5938.851528711795;5946.197263369433;5899.734511946951;5968.791092311748;5978.8206353809455;5980.820575382723;5992.700364981755;5687.402822703598;5781.364049954502;5901.4688678018965;5918.763249470016;5996.700164991777;5321.414644389114;5735.627184233053;5797;5843.941560584382;5889.705514724246;6326.367363263683;6333.619982801;6357.65032923189;6403.135576697133;6433.613983160979;6629.071929929813;6713.530052896299;6734.8316292092595;6749.12261406019;6892.896606550906;6712.127423434922;6715.597064176116;6716.1269035025625;6733.629650369227;6743.055972163901;6287.276963149251;6388.7125079371535;6415.582987105823;6417.037444383333;6420.229572451289;6884.931150688529;7004.579725216503;7070.080889484334;7096.929030709627;7130.286971302932;6601.042848786899;6673;6733.225679046972;6786.796396108125;6822.658867056726;6717.193936727624;6781;6873;6875;6879.58722476653;6811.761588344483;6836;6859.622720750307;6867.828304292333;6892.586444813327;6816.147981502312;6880.105586273852;6906.758263460755;6906.896396554056;6920.100386949664;5197.454267301954;5249.685018898878;5306.867328316823;5310.548603368757;5317.867053323698;4538.931916021263;4617.815287388534;4622.907541849211;4687.718736875799;4712.034033023222;5909.172715819789;5931.436513531294;5933.762649494059;5951.7619295227705;5954.434328738849;6827.146606674166;6838.042674025641;6841.726330946706;6929.792106236821;6945.2707465716385;5868.67722275279;5876.9118463223085;5938.2280303560265;5944.256967879015;5946.554008449383;5723.8569035774435;5814.622049566765;5816.47651711348;5872.559558033079;5901.852453688693;6658.766943156967;6663.600183988976;6677.098591690156;6697.966510167418;6709.563878347892;6810;6825.48808839339;6913.827154321182;6914.274001229798;6924.965375173293;6571.835704107436;6588.308227636029;6591.439727623206;6608.074869518367;6688.264290928091;6449.097126402214;6594.4724422045165;6603.240627327918;6744.055832183596;6767.120274364301;6701.396874281338;6725.9663701681175;6732.427743641845;6744.494162937701;6758.695858686469;6460.806175814733;6488.059231411513;6545.8690826184165;6566.474682025522;6567.605943643397;6456;6489.253735820441;6542.985837195242;6556.868862622626;6572.277049524644;6427.453666438405;6529.281779004401;6546.770863019771;6547.607143571401;6550.737970481127;6537.8692426152165;6610.900836487553;6624.701888414937;6765.594064356154;6770.52606317553;6373.6175829450385;6439;6518.608883467007;6524.184476940382;6541.215054193527 11:50:00 INFO - PID 29555 | |1;ext.html;859.0807835615891;887.4808237181248;889.1730690457875;890.8218356328736;891.5854127830562;38236.617633823655;41703.582964170346;41804;41869.790651046744;42062;38382.424263636065;38692.22615547687;38808.61191388085;38859.222815543675;38954.61045389545;6984.7206111755595;7120.928790712104;7577.583232922215;7718.073831140243;7752.3410510106705;7676.155622881479;7807;7817.648205830751;7827.608619569027;7830.804229894241;7782.027246594176;7794.727184548542;7802.258785415376;7824.021997250344;7825.904373387731;7746.883796743082;7768.45620806541;7797.844043119106;7798.961005194938;7821.687132514692;7646.808829779245;7696.076470823481;7700.768976930701;7703.8844417333785;7710.7686769397005;37238.62761372379;37418.81290593557;37472.81263593692;37673.62326376729;37700.43449348262;3470.1845066409373;3512.982435087834;3513.472979053137;3515.771474854126;3518.3491054155065;31090.68909310923;31254.21864453407;31275.687243127504;31302;31323.84338078318;4951.9752401237765;5155.9742201288755;5184.300119483859;5188.844334669966;5210.348706411699;181161;182886;182910;183053;183306;41795;42515.787421063316;47447.76276118666;47587.762061189474;47718.28422573664;49951.25073123906;50505.74747126241;50607.24089138666;50656;50729;16233.59416014609;16284.185790710417;16324.102174380285;16337.754933676004;16530.256138473796;13377.866221337663;13397.799033014513;13406.128601640861;13408.128471649312;13415.597532074053;14196.651318124761;14203.076800007966;14327.57017289483;14379.78430323546;14553.78169327461;5884.292895475823;5887.11693246008;5919.940800592025;5921.111833225051;5933.465988061009;1014.3305418423805;1015.5582321689941;1021.6168936648756;1032.6385764982197;1048.821700310949;21742.782572174394;21750.89124554367;21909.561808764054;22299.662020278836;22432.887835561043;1124.241137232365;1135.8864113588575;1139.2765593847967;1139.863216414019;1142.194752699345;11674;11846.466908989116;11982;12021.459034343476;12086.80003389853;206065;208161;228853;229357;229450;51615.74192129015;51834;51837.740811296455;51877.48122518802;52032.73983630058;1446.8263808343065;1471.9990406523452;1480.7852861335045;1482.3774014913547;1485.6360191752874;22612.434689132904;22628.094876204766;22681.659775103057;22871.31386058421;23000.654990175495;37757;37839.24321513499;37852;37856;37871.485140594064;191059;192136;192173;192374;192573;33436.83281583576;34336.141596460286;35658.28683426265;35685.46471802977;35698.46452303165;43942.12115757731;45178.32232516449;45285.320720189884;45347.77326113348;45373;33097.83451082729;33175.83412082924;33428.832855835564;33555.49666755049;33657;32687.836560817042;32890;32927.67072329342;33023;33407.665923341425;37837.62162378341;37913.05217369478;38085.80957095197;38209;38255.42616860805;31895.840520797246;32336.514952275265;32599;32613.673863262014;33116.668833311356;22434.77565224327;23309;23518.64722029137;23555.175568855007;23597.882010589838 11:50:00 INFO - PID 29555 | |2;jquery.html;570.7802496039027;575.3613489027182;575.9712014399279;578.5545130249706;583.1660725163016;22111.66832497512;22909.885450572765;22910.312690619267;22913.656295155546;22983.42541436465;22949.77050229497;23904.88047559764;24045.398865028383;24054.87972560139;24125.638115428283;6339.619622822634;6497.610143391388;6540.93459065408;6542.182227221598;6543.378379053982;6307.117003619497;6393.9680301598655;6431.517636177282;6502.7398904043785;6504.447121994613;6535.967320163416;6542.182227221598;6543.1821022372205;6554.8689026219445;6567.573107748005;6527.1188389567515;6536.901946470783;6539.542232043759;6542.051402546628;6558.770443034518;6065;6586.242582103072;6853.862922741567;6902.516823822284;6912.654367281666;29691.554626680616;29849.850750746107;30045.69954300451;30107.397852042835;30151.396972060655;2048.8463365247667;2063.13348393675;2067.7725450200464;2068.751749790035;2071.958560828775;19564.608707825908;20380.28668996578;20528;20634.793652063436;20818.687719684218;2466.5436894174636;2504.511620234055;2516.3960649444184;2574.5623244048556;2620.4497055618344;119159.40420297843;125133.37433312775;132282;139614.99999999898;141007;20430.795692043186;20713.378598642066;21611.783882160977;21712.34862954114;21763.12947482083;39519.40720889189;39631.60368396279;39801.40297895534;39828.601713983066;39836;11135.554577816958;11157.888421115847;11196.216264861381;11279.154063445274;11314.094872410187;9486.146246837818;9492.952535237418;9504.857427138599;9571.856422153673;9580.856287155699;11295.943520282346;11472;11522.942385288188;11540;11561.710957226136;4055.175171172984;4145.54399016108;4202.537720850704;4269.829206831754;4374.12517496502;927.3462209142539;957.4207604399378;960.1181683899496;964.5948701545368;979.8089372572423;17834.37579684705;17860.642787144185;17897.910510447364;18156.45530634083;18266.086695665163;1016.598443614768;1017.31839667423;1032.4270030133198;1032.4270030133348;1032.9741756456149;9897.158741506908;10117.44354060519;10119.404177149481;10155;10419.895801042045;428617;438765;443865;446171;446299;106446;107318;108864;109058;109632;1016.694991502561;1046.4610725476387;1134.551852018448;1150.8618965724168;1178.5462596900254;19041;19192.520186995436;19321.90339048296;19532.511687207934;19578.314758983368;53617.731911341754;53836.73081634567;53990;55524;55687;68020.3197968014;68051;68464.31535684779;68766.31233687598;68803.65598171977;18210.544736381697;18919.148638311035;18933.715994259823;18997.145128468976;19004.809951900857;21404.57190856143;21629.13483460644;21672;21856.125754969624;22048.88975555112;16922.661546768748;17053.317867285168;17187.226574803914;17413.129343533026;17762.5559361017;15761.921190393974;15967.20163991819;16210.189490525663;16510.587235319214;16627.418040368528;18014.36949706754;18276.543086422946;18286.268549257882;18296;18385.17266722974;17856.732149018037;18654.72017919705;18668.159932802784;18721.53196170107;18770.155343009323;17780;17940;17948.461546153638;17973;17976.101194940464 11:50:00 INFO - PID 29555 | |3;mootools.html;1322.1207896748663;1346.2797403389188;1390.4090761426392;1398.7272481866034;1411.5835828430618;14899.627509312259;15492.69014619707;15554.84445155548;15558.222088895567;15569.221538923051;15221.6955660887;15313.92343038286;15317.84682153178;15344.616384590363;15352.539423817303;2714.7919175966704;2737.5893615957566;2808.1715893811274;2816.3240822202624;2819.3374556979093;2696.608991696203;2725.3731641722416;2738.329109368198;2741.300968253093;2744.108164846423;2715.3075965628836;2815.81697189683;2817.1830169250993;2826.9293267668268;2828.4484525517532;2770.8891644334203;2796.972030279702;2825.307799589093;2827.7313655202724;2828.5050116229577;2605.661264035682;2785.5125353063336;2785.9860700696568;2786.4287820996647;2793.092245020356;15345.539633810891;15477.613059673486;15518.146501942389;15524.456644017519;15525.223738813014;7945.721899733481;8176.836463270761;8185.918140818575;8211.34309255258;8248.628811703487;5256.9737151314;5356.91964620531;5396.514313711785;5493.313335833021;5521.917171242435;1880.8777429467177;1886.962260754791;1894.3085773692642;1935.796741342153;1943.3878328326673;26072.217833465027;26406;26758.5986210207;26764.73235267642;27095.1871443857;6505.707243174069;6583.177102862142;6768.2216545097945;6772.932270677329;6794.048833163361;16634.417795377103;16659.500214993328;16683.416080437128;16688.165591720364;16694.91652541754;7585.355244804143;7760.611969401621;7795.181505941908;7809.531428114331;7835;6961.79114626562;7046.260142685047;7245.130584329914;7263.164736055422;7307.817304567322;5209.505097015777;5291.259223708684;5298.761555730001;5325.4940780625775;5340.30576025122;1522.7715842623695;1526.6946610677687;1536.2088524409937;1536.5620798072496;1545.2351086212398;871.1245198575391;888.1961824548794;905.6694306578183;906.9501177435304;913.4473643445752;7379.409647228209;7387.0027546281635;7411.295926886937;7430.517016393917;7478.140013898471;705.0517054561607;725.9818504537429;729.824842037907;731.0897932074495;745.1840234942729;4953.876153096201;5091.159958606789;5213.374395072616;5382.865428364322;5445.074337362658;21267.78732212658;21326.78673213248;21456.24903128383;21719.457013574473;22269.22057728004;20810.791892080884;21513.462163445725;21622.891885540786;21678.13287468515;21868.343949681534;11294.322340659586;11420.714482138013;11480.253783504046;11536.769264614828;11610.30338179695;8139.715109971123;8516.701915433177;8536.786580335542;8564.57177141128;8591.828163436821;18398.08009599488;18670;18711.345102921332;18718.906405467886;19072.809271907103;19014.334498292494;19083.90458047701;19122.713159302344;19146.7127993083;19261.22955081781;10775.191860610643;11034.117270618492;11042.558297668003;11046.392448415414;11055.557777688802;13857.168569885838;13910.72178556403;13931.373088210852;14025.5091071812;14073.788893166406;10802.621908233488;11142.442877856236;11240.494177762184;11266.380349080551;11454.541818327174;9757.414555126716;9864.16154626836;9903.207743380657;9905.653302134391;10087.646932357333;10423.061924426838;10517.631882884369;10544.89455105439;10551.525181366704;10554.947225263824;7210.603416812126;7231.168415632059;7257.310555497219;7278.781636550912;7345.77962661121;6850.246472887879;6851.109355783717;6909.2745261746795;6915.896261556181;7090.361867431956 11:50:00 INFO - PID 29555 | |4;prototype.html;502.7907881544884;504.3493892878187;520.1054186798705;527.6807531443474;528.2868128027163;6031.276246850379;6201.542637480195;6221.937780622193;6252.030935205039;6258.718357673899;6065.757369705207;6081.756729730805;6094.013933172736;6119.632822030681;6123.203983482141;4940.629452791037;4959.404871415435;4963.875903102433;4978;4990.950090499085;4807.5432833880905;4890.24201248807;4908.852734417956;4942.196098424085;4992.525710057539;4858.611311095122;4917.4836642152595;4935.358403407552;4939.555440010398;4941.308216849626;4873.561379475864;4880.487548807359;4890.242012488052;4899.510048995107;4916.754162291871;4377.715448495837;4465.575770301816;4474.060447306069;4474.955250447519;4484;5963.19496867926;6053.273607167168;6096.512279017668;6109.816705498843;6178.41305076017;5428.212909128194;5543.889122217533;5561.77752889888;5582.692951887645;5612.15817627355;6463;6503.089567460559;6503.642299673516;6510.40495078704;6540.771073012469;4823.20417131173;4826.107170173529;4840.419149702023;4841.49164337743;4863.8784030399165;6748.257691653914;6782.5252232343755;6815.727370905158;6823.454123670144;6828.726850925957;4941.209406494943;4959.156943319572;4975.626827987915;4991.600671946236;4992.8751781205765;5036.647434679538;5054.595632349477;5057.115004874132;5063.0380227756605;5069.163588008011;5599.384067752544;5600.523955463831;5609.214709940612;5612.270404847343;5613.270274864241;6187.257529096537;6193.163922870445;6374.840628984312;6537.607743535403;6576.572522786099;1680.865530757561;1711.6576684663116;1711.7603535505039;1712.1011468978636;1722.190570431895;1040.256216804988;1194.2834299420451;1195.282830301812;1201.9338936358408;1216.3735676126803;5567.498925096761;5667.603267771216;5704.3154821421695;5747.281589801275;5799.130130480462;6727.394534491919;6727.562708423937;6727.62998035103;6760.121184246115;6785;5704.22992895962;5705.600607957404;5713.628614140068;5754.683492407958;5761.135829625506;3490.47642853574;3543.061088811467;3543.964560354415;3550.5384300041346;3558.9110272242883;6425.903611445832;6433.485321174295;6458.22501299847;6501.804945851632;6512.153420055362;6803.727850885909;6808.148981377328;6821.658917054227;6837.726490940406;6861.451083913275;6517.641529715816;6521.347865213441;6529.106559097903;6530.510211734139;6552.63960482178;6398.648074355955;6463.870722585428;6536.967315163393;6613.636250006296;6613.966930165511;6463.806085817433;6495.707693153914;6567.44176744982;6658.66946568697;6680.498962577729;6408.052295943731;6459.580127291711;6473.77341793035;6493.577917435352;6523.706433210422;4916.852494425173;4928.408590969107;4939.333190019445;4943.159662857251;4962.851114466572;6644.568103073285;6646.368594983469;6667.2666006738245;6676.1988561371;6719.563228390139;6445.129907462432;6449.4840412765925;6482.967585162232;6502.187226596676;6543.378379053982;6434.356564343529;6441.516886233458;6493.74025039012;6501.284858665637;6512.804615861532;6294.590851594632;6330.164252366762;6333.588316759396;6344.809655710336;6368.203974503187;6384.201974753156;6407.551471396958;6410.935890641033;6471.676416179078;6502;5803.883922321445;6142.938570614237;6161.969190154021;6169.321374648695;6205.162303089206 11:50:00 INFO - PID 29555 | |5;yui.html;912.968046118386;914.0904799724274;938.4932136646212;941.8445956417191;947.3699989506978;19487.41537753868;20133.496662583424;20168.294109706163;20179.899100504514;20369.796302036975;18290.63418731628;18706.064696765174;18745.7188142178;18765.624687506242;18803.811961880412;948.8007518421123;967.7580604848788;973.7662960889371;974.0648976982105;975.2149519636689;967.2068903499134;967.3760424526176;967.5500892085179;968.0174622757906;970.7718686108759;923.3351986569659;963.0802583532709;1004.1665417703289;1006.4665727164614;1013.5287091502449;1007.4156988946394;1015.3501758874327;1016.3952448293254;1016.8983101689846;1022.6625213679467;723.3562129704567;739.2016622048175;742.9479936404454;744;746.1866565443638;20763.377098687062;20905.477363065893;20960.47598810027;20972.37082887516;21069;21752.673709894363;21807.563848722937;22592.887035564716;22797.43006424836;22817.42956426086;17506.12469376526;17577.384791532237;17735.911320443443;17747.378841740607;18009.09954502283;3655.4334078217917;3695.7782533047834;3705.573859006222;3706.870259540903;3709.4992176056157;112071;140933.2953335237;142321;143450.99999999895;144235;17587.384441544484;17857.196426160855;18084.276628934695;18199;18221.088945552936;31930.680693193237;32012;32051.358972820413;32051.35897282088;32090.839545802122;9294.581743821544;9396.67111651089;9430.811383772287;9455.338126331228;9489.335746497678;4393;4457.3091170868565;4462.129884672523;4477.865664030084;4490.169318676056;5378.1663842104535;5460.180972854104;5531.7234138293725;5532.446755324436;5578.748956296977;1065.2756125834508;1080.8054550180887;1093.6828319787332;1095.9726006849892;1106.8267636305382;692.3042342445809;708.6704682322642;720.909886264217;721.9279370135345;732.1799584465433;11713.707157321136;11809.763804724029;12032.639020829389;12314.076444266715;12430.564930227398;647.8769033883547;649.4252586461016;658.4238791057825;669.855980964095;694.9270326615733;5703.515201207861;5716.771329146787;5741.655500669973;5802.88394232113;5889.440503152194;125311;126734.36632816777;126919;129738;130438.34780826225;106964;111722;112800;114863.42568287272;115865;97541.5122924395;98344.50827745958;101419.4929025365;101729;105101.47449262858;10635.734106647396;10737.89262107369;10935.945320273348;11013.174011948975;11118.221724479208;43194.568054319054;43952.12095758003;44110;45270.77364613156;45803.77098114488;55174;58732.70633646804;59000;59114.40885591089;60276.698616506634;15622.375104995674;15859.52421427359;15932.283047262668;15994.520164395086;16056.43802466908;17946;18082.638347232718;18368.08159592042;18404.723929140804;18603.534911627317;16105.838941610435;16171.595710107342;16274.186290685655;16331.101789401697;16386.508404747878;16361.181940903145;16396.75404868904;16560.50318490447;16592;16833.15834208309;16019.919900400424;16269.26788294506;16347.754783678492;16453.177341133134;16478.83521164773;11870.643880683594;11884.76230475403;11929.761404772029;12132;12172.147949643795;11947.94026029899;12060.819087713517;12272.325022123869;12466.750664986468;12500.562480313145 11:50:00 INFO - PID 29555 | __end_tp_report 11:50:00 INFO - PID 29555 | __start_cc_report 11:50:00 INFO - PID 29555 | _x_x_mozilla_cycle_collect,351 11:50:00 INFO - PID 29555 | __end_cc_report 11:50:00 INFO - PID 29555 | __startTimestamp1505415000395__endTimestamp 11:50:00 INFO - PID 29555 | 11:50:00 INFO - PID 29555 | ------- Summary: start ------- 11:50:00 INFO - PID 29555 | Number of tests: 6 11:50:00 INFO - PID 29555 | 11:50:00 INFO - PID 29555 | [#0] dojo.html Cycles:165 Average:6251.37 Median:6489.25 stddev:754.44 (11.6%) stddev-sans-first:716.74 11:50:00 INFO - PID 29555 | Values: 3160.7 3165.5 3193.8 3201.5 3202.5 6200.3 6212.4 6271.7 6310.4 6392.6 6264.3 6276.2 6288.5 6308.9 6332.8 5804.4 5823.7 5830.8 5832.5 5885.6 5854.2 5870.1 5915.6 5938.9 5946.2 5899.7 5968.8 5978.8 5980.8 5992.7 5687.4 5781.4 5901.5 5918.8 5996.7 5321.4 5735.6 5797.0 5843.9 5889.7 6326.4 6333.6 6357.7 6403.1 6433.6 6629.1 6713.5 6734.8 6749.1 6892.9 6712.1 6715.6 6716.1 6733.6 6743.1 6287.3 6388.7 6415.6 6417.0 6420.2 6884.9 7004.6 7070.1 7096.9 7130.3 6601.0 6673.0 6733.2 6786.8 6822.7 6717.2 6781.0 6873.0 6875.0 6879.6 6811.8 6836.0 6859.6 6867.8 6892.6 6816.1 6880.1 6906.8 6906.9 6920.1 5197.5 5249.7 5306.9 5310.5 5317.9 4538.9 4617.8 4622.9 4687.7 4712.0 5909.2 5931.4 5933.8 5951.8 5954.4 6827.1 6838.0 6841.7 6929.8 6945.3 5868.7 5876.9 5938.2 5944.3 5946.6 5723.9 5814.6 5816.5 5872.6 5901.9 6658.8 6663.6 6677.1 6698.0 6709.6 6810.0 6825.5 6913.8 6914.3 6925.0 6571.8 6588.3 6591.4 6608.1 6688.3 6449.1 6594.5 6603.2 6744.1 6767.1 6701.4 6726.0 6732.4 6744.5 6758.7 6460.8 6488.1 6545.9 6566.5 6567.6 6456.0 6489.3 6543.0 6556.9 6572.3 6427.5 6529.3 6546.8 6547.6 6550.7 6537.9 6610.9 6624.7 6765.6 6770.5 6373.6 6439.0 6518.6 6524.2 6541.2 11:50:00 INFO - PID 29555 | 11:50:00 INFO - PID 29555 | [#1] ext.html Cycles:180 Average:36885.66 Median:3517.06 stddev:51499.65 (1464.3%) stddev-sans-first:51573.07 11:50:00 INFO - PID 29555 | Values: 859.1 887.5 889.2 890.8 891.6 38236.6 41703.6 41804.0 41869.8 42062.0 38382.4 38692.2 38808.6 38859.2 38954.6 6984.7 7120.9 7577.6 7718.1 7752.3 7676.2 7807.0 7817.6 7827.6 7830.8 7782.0 7794.7 7802.3 7824.0 7825.9 7746.9 7768.5 7797.8 7799.0 7821.7 7646.8 7696.1 7700.8 7703.9 7710.8 37238.6 37418.8 37472.8 37673.6 37700.4 3470.2 3513.0 3513.5 3515.8 3518.3 31090.7 31254.2 31275.7 31302.0 31323.8 4952.0 5156.0 5184.3 5188.8 5210.3 181161.0 182886.0 182910.0 183053.0 183306.0 41795.0 42515.8 47447.8 47587.8 47718.3 49951.3 50505.7 50607.2 50656.0 50729.0 16233.6 16284.2 16324.1 16337.8 16530.3 13377.9 13397.8 13406.1 13408.1 13415.6 14196.7 14203.1 14327.6 14379.8 14553.8 5884.3 5887.1 5919.9 5921.1 5933.5 1014.3 1015.6 1021.6 1032.6 1048.8 21742.8 21750.9 21909.6 22299.7 22432.9 1124.2 1135.9 1139.3 1139.9 1142.2 11674.0 11846.5 11982.0 12021.5 12086.8 206065.0 208161.0 228853.0 229357.0 229450.0 51615.7 51834.0 51837.7 51877.5 52032.7 1446.8 1472.0 1480.8 1482.4 1485.6 22612.4 22628.1 22681.7 22871.3 23000.7 37757.0 37839.2 37852.0 37856.0 37871.5 191059.0 192136.0 192173.0 192374.0 192573.0 33436.8 34336.1 35658.3 35685.5 35698.5 43942.1 45178.3 45285.3 45347.8 45373.0 33097.8 33175.8 33428.8 33555.5 33657.0 32687.8 32890.0 32927.7 33023.0 33407.7 37837.6 37913.1 38085.8 38209.0 38255.4 31895.8 32336.5 32599.0 32613.7 33116.7 22434.8 23309.0 23518.6 23555.2 23597.9 11:50:00 INFO - PID 29555 | 11:50:00 INFO - PID 29555 | [#2] jquery.html Cycles:180 Average:33746.77 Median:21737.74 stddev:74412.91 (342.3%) stddev-sans-first:74579.97 11:50:00 INFO - PID 29555 | Values: 570.8 575.4 576.0 578.6 583.2 22111.7 22909.9 22910.3 22913.7 22983.4 22949.8 23904.9 24045.4 24054.9 24125.6 6339.6 6497.6 6540.9 6542.2 6543.4 6307.1 6394.0 6431.5 6502.7 6504.4 6536.0 6542.2 6543.2 6554.9 6567.6 6527.1 6536.9 6539.5 6542.1 6558.8 6065.0 6586.2 6853.9 6902.5 6912.7 29691.6 29849.9 30045.7 30107.4 30151.4 2048.8 2063.1 2067.8 2068.8 2072.0 19564.6 20380.3 20528.0 20634.8 20818.7 2466.5 2504.5 2516.4 2574.6 2620.4 119159.4 125133.4 132282.0 139615.0 141007.0 20430.8 20713.4 21611.8 21712.3 21763.1 39519.4 39631.6 39801.4 39828.6 39836.0 11135.6 11157.9 11196.2 11279.2 11314.1 9486.1 9493.0 9504.9 9571.9 9580.9 11295.9 11472.0 11522.9 11540.0 11561.7 4055.2 4145.5 4202.5 4269.8 4374.1 927.3 957.4 960.1 964.6 979.8 17834.4 17860.6 17897.9 18156.5 18266.1 1016.6 1017.3 1032.4 1032.4 1033.0 9897.2 10117.4 10119.4 10155.0 10419.9 428617.0 438765.0 443865.0 446171.0 446299.0 106446.0 107318.0 108864.0 109058.0 109632.0 1016.7 1046.5 1134.6 1150.9 1178.5 19041.0 19192.5 19321.9 19532.5 19578.3 53617.7 53836.7 53990.0 55524.0 55687.0 68020.3 68051.0 68464.3 68766.3 68803.7 18210.5 18919.1 18933.7 18997.1 19004.8 21404.6 21629.1 21672.0 21856.1 22048.9 16922.7 17053.3 17187.2 17413.1 17762.6 15761.9 15967.2 16210.2 16510.6 16627.4 18014.4 18276.5 18286.3 18296.0 18385.2 17856.7 18654.7 18668.2 18721.5 18770.2 17780.0 17940.0 17948.5 17973.0 17976.1 11:50:00 INFO - PID 29555 | 11:50:00 INFO - PID 29555 | [#3] mootools.html Cycles:180 Average:9292.37 Median:2731.48 stddev:6682.55 (244.6%) stddev-sans-first:6674.47 11:50:00 INFO - PID 29555 | Values: 1322.1 1346.3 1390.4 1398.7 1411.6 14899.6 15492.7 15554.8 15558.2 15569.2 15221.7 15313.9 15317.8 15344.6 15352.5 2714.8 2737.6 2808.2 2816.3 2819.3 2696.6 2725.4 2738.3 2741.3 2744.1 2715.3 2815.8 2817.2 2826.9 2828.4 2770.9 2797.0 2825.3 2827.7 2828.5 2605.7 2785.5 2786.0 2786.4 2793.1 15345.5 15477.6 15518.1 15524.5 15525.2 7945.7 8176.8 8185.9 8211.3 8248.6 5257.0 5356.9 5396.5 5493.3 5521.9 1880.9 1887.0 1894.3 1935.8 1943.4 26072.2 26406.0 26758.6 26764.7 27095.2 6505.7 6583.2 6768.2 6772.9 6794.0 16634.4 16659.5 16683.4 16688.2 16694.9 7585.4 7760.6 7795.2 7809.5 7835.0 6961.8 7046.3 7245.1 7263.2 7307.8 5209.5 5291.3 5298.8 5325.5 5340.3 1522.8 1526.7 1536.2 1536.6 1545.2 871.1 888.2 905.7 907.0 913.4 7379.4 7387.0 7411.3 7430.5 7478.1 705.1 726.0 729.8 731.1 745.2 4953.9 5091.2 5213.4 5382.9 5445.1 21267.8 21326.8 21456.2 21719.5 22269.2 20810.8 21513.5 21622.9 21678.1 21868.3 11294.3 11420.7 11480.3 11536.8 11610.3 8139.7 8516.7 8536.8 8564.6 8591.8 18398.1 18670.0 18711.3 18718.9 19072.8 19014.3 19083.9 19122.7 19146.7 19261.2 10775.2 11034.1 11042.6 11046.4 11055.6 13857.2 13910.7 13931.4 14025.5 14073.8 10802.6 11142.4 11240.5 11266.4 11454.5 9757.4 9864.2 9903.2 9905.7 10087.6 10423.1 10517.6 10544.9 10551.5 10554.9 7210.6 7231.2 7257.3 7278.8 7345.8 6850.2 6851.1 6909.3 6915.9 7090.4 11:50:00 INFO - PID 29555 | 11:50:00 INFO - PID 29555 | [#4] prototype.html Cycles:180 Average:5448.35 Median:6087.89 stddev:1530.96 (25.1%) stddev-sans-first:1489.58 11:50:00 INFO - PID 29555 | Values: 502.8 504.3 520.1 527.7 528.3 6031.3 6201.5 6221.9 6252.0 6258.7 6065.8 6081.8 6094.0 6119.6 6123.2 4940.6 4959.4 4963.9 4978.0 4991.0 4807.5 4890.2 4908.9 4942.2 4992.5 4858.6 4917.5 4935.4 4939.6 4941.3 4873.6 4880.5 4890.2 4899.5 4916.8 4377.7 4465.6 4474.1 4475.0 4484.0 5963.2 6053.3 6096.5 6109.8 6178.4 5428.2 5543.9 5561.8 5582.7 5612.2 6463.0 6503.1 6503.6 6510.4 6540.8 4823.2 4826.1 4840.4 4841.5 4863.9 6748.3 6782.5 6815.7 6823.5 6828.7 4941.2 4959.2 4975.6 4991.6 4992.9 5036.6 5054.6 5057.1 5063.0 5069.2 5599.4 5600.5 5609.2 5612.3 5613.3 6187.3 6193.2 6374.8 6537.6 6576.6 1680.9 1711.7 1711.8 1712.1 1722.2 1040.3 1194.3 1195.3 1201.9 1216.4 5567.5 5667.6 5704.3 5747.3 5799.1 6727.4 6727.6 6727.6 6760.1 6785.0 5704.2 5705.6 5713.6 5754.7 5761.1 3490.5 3543.1 3544.0 3550.5 3558.9 6425.9 6433.5 6458.2 6501.8 6512.2 6803.7 6808.1 6821.7 6837.7 6861.5 6517.6 6521.3 6529.1 6530.5 6552.6 6398.6 6463.9 6537.0 6613.6 6614.0 6463.8 6495.7 6567.4 6658.7 6680.5 6408.1 6459.6 6473.8 6493.6 6523.7 4916.9 4928.4 4939.3 4943.2 4962.9 6644.6 6646.4 6667.3 6676.2 6719.6 6445.1 6449.5 6483.0 6502.2 6543.4 6434.4 6441.5 6493.7 6501.3 6512.8 6294.6 6330.2 6333.6 6344.8 6368.2 6384.2 6407.6 6410.9 6471.7 6502.0 5803.9 6142.9 6162.0 6169.3 6205.2 11:50:00 INFO - PID 29555 | 11:50:00 INFO - PID 29555 | [#5] yui.html Cycles:180 Average:24937.94 Median:19145.61 stddev:36035.08 (188.2%) stddev-sans-first:36091.01 11:50:00 INFO - PID 29555 | Values: 913.0 914.1 938.5 941.8 947.4 19487.4 20133.5 20168.3 20179.9 20369.8 18290.6 18706.1 18745.7 18765.6 18803.8 948.8 967.8 973.8 974.1 975.2 967.2 967.4 967.6 968.0 970.8 923.3 963.1 1004.2 1006.5 1013.5 1007.4 1015.4 1016.4 1016.9 1022.7 723.4 739.2 742.9 744.0 746.2 20763.4 20905.5 20960.5 20972.4 21069.0 21752.7 21807.6 22592.9 22797.4 22817.4 17506.1 17577.4 17735.9 17747.4 18009.1 3655.4 3695.8 3705.6 3706.9 3709.5 112071.0 140933.3 142321.0 143451.0 144235.0 17587.4 17857.2 18084.3 18199.0 18221.1 31930.7 32012.0 32051.4 32051.4 32090.8 9294.6 9396.7 9430.8 9455.3 9489.3 4393.0 4457.3 4462.1 4477.9 4490.2 5378.2 5460.2 5531.7 5532.4 5578.7 1065.3 1080.8 1093.7 1096.0 1106.8 692.3 708.7 720.9 721.9 732.2 11713.7 11809.8 12032.6 12314.1 12430.6 647.9 649.4 658.4 669.9 694.9 5703.5 5716.8 5741.7 5802.9 5889.4 125311.0 126734.4 126919.0 129738.0 130438.3 106964.0 111722.0 112800.0 114863.4 115865.0 97541.5 98344.5 101419.5 101729.0 105101.5 10635.7 10737.9 10935.9 11013.2 11118.2 43194.6 43952.1 44110.0 45270.8 45803.8 55174.0 58732.7 59000.0 59114.4 60276.7 15622.4 15859.5 15932.3 15994.5 16056.4 17946.0 18082.6 18368.1 18404.7 18603.5 16105.8 16171.6 16274.2 16331.1 16386.5 16361.2 16396.8 16560.5 16592.0 16833.2 16019.9 16269.3 16347.8 16453.2 16478.8 11870.6 11884.8 11929.8 12132.0 12172.1 11947.9 12060.8 12272.3 12466.8 12500.6 11:50:00 INFO - PID 29555 | -------- Summary: end -------- 11:50:00 INFO - PID 29555 | 11:50:01 INFO - TEST-INFO | 29555: exit 0 11:50:01 INFO - TEST-OK | dromaeo_css | took 1109603ms 11:50:01 INFO - TEST-START | kraken 11:50:01 INFO - Initialising browser for kraken test... 11:50:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39658/getInfo.html -profile /tmp/tmp4VQ7LS/profile 11:50:01 INFO - TEST-INFO | started process 29927 (/builds/slave/test/build/application/firefox/firefox http://localhost:39658/getInfo.html) 11:50:06 INFO - TEST-INFO | 29927: exit 0 11:50:06 INFO - Browser initialized. 11:50:06 INFO - Running cycle 1/1 for kraken test... 11:50:06 INFO - Using env: {'DISPLAY': ':0', 11:50:06 INFO - 'HOME': '/home/cltbld', 11:50:06 INFO - 'JSGC_DISABLE_POISONING': '1', 11:50:06 INFO - 'LANG': 'en_US.UTF-8', 11:50:06 INFO - 'LANGUAGE': 'en_US:en', 11:50:06 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:50:06 INFO - 'LOGNAME': 'cltbld', 11:50:06 INFO - 'MAIL': '/var/mail/cltbld', 11:50:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:50:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:50:06 INFO - 'MOZ_CRASHREPORTER': '1', 11:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:06 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:50:06 INFO - 'MOZ_NO_REMOTE': '1', 11:50:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:50:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:06 INFO - 'NO_EM_RESTART': '1', 11:50:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:06 INFO - 'PWD': '/builds/slave/test', 11:50:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:50:06 INFO - 'RUST_BACKTRACE': 'full', 11:50:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:50:06 INFO - 'SHELL': '/bin/bash', 11:50:06 INFO - 'SHLVL': '1', 11:50:06 INFO - 'TERM': 'linux', 11:50:06 INFO - 'TMOUT': '86400', 11:50:06 INFO - 'USER': 'cltbld', 11:50:06 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484', 11:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:06 INFO - '_': '/tools/buildbot/bin/python'} 11:50:06 INFO - TEST-INFO | started process 30157 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4VQ7LS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:50:07 INFO - PID 30157 | 11:50:07 INFO - PID 30157 | (/builds/slave/test/build/application/firefox/firefox:30206): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:50:07 INFO - PID 30157 | 11:50:17 INFO - PID 30157 | RSS: Main: 172326912 11:50:17 INFO - PID 30157 | 11:50:20 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39658/tests/kraken/driver.html?testName=audio-beat-detection) 11:50:21 INFO - PID 30157 | RSS: Main: 177090560 11:50:21 INFO - PID 30157 | 11:50:23 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39658/tests/kraken/driver.html?testName=audio-dft) 11:50:24 INFO - PID 30157 | RSS: Main: 178626560 11:50:24 INFO - PID 30157 | 11:50:27 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39658/tests/kraken/driver.html?testName=audio-fft) 11:50:28 INFO - PID 30157 | RSS: Main: 173801472 11:50:28 INFO - PID 30157 | 11:50:30 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39658/tests/kraken/driver.html?testName=audio-oscillator) 11:50:30 INFO - PID 30157 | RSS: Main: 174194688 11:50:30 INFO - PID 30157 | 11:50:33 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39658/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:50:33 INFO - PID 30157 | RSS: Main: 174616576 11:50:33 INFO - PID 30157 | 11:50:36 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39658/tests/kraken/driver.html?testName=imaging-darkroom) 11:50:37 INFO - PID 30157 | RSS: Main: 177111040 11:50:37 INFO - PID 30157 | 11:50:41 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39658/tests/kraken/driver.html?testName=imaging-desaturate) 11:50:41 INFO - PID 30157 | RSS: Main: 176877568 11:50:41 INFO - PID 30157 | 11:50:44 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39658/tests/kraken/driver.html?testName=json-parse-financial) 11:50:45 INFO - PID 30157 | RSS: Main: 176619520 11:50:45 INFO - PID 30157 | 11:50:47 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39658/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:50:47 INFO - PID 30157 | RSS: Main: 177414144 11:50:47 INFO - PID 30157 | 11:50:49 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39658/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:50:49 INFO - PID 30157 | RSS: Main: 177373184 11:50:49 INFO - PID 30157 | 11:50:52 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39658/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:50:52 INFO - PID 30157 | RSS: Main: 174247936 11:50:52 INFO - PID 30157 | 11:50:55 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:50:56 INFO - PID 30157 | RSS: Main: 174579712 11:50:56 INFO - PID 30157 | 11:50:58 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:50:59 INFO - PID 30157 | RSS: Main: 174440448 11:50:59 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | Cycle 1(1): loaded http://localhost:39658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39658/tests/kraken/driver.html?testName=ai-astar) 11:51:01 INFO - PID 30157 | RSS: Main: 176340992 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | __start_tp_report 11:51:01 INFO - PID 30157 | _x_x_mozilla_page_load 11:51:01 INFO - PID 30157 | _x_x_mozilla_page_load_details 11:51:01 INFO - PID 30157 | |i|pagename|runs| 11:51:01 INFO - PID 30157 | |0;ai-astar;106;107;107;106;107;109;110;109;107;106 11:51:01 INFO - PID 30157 | |1;audio-beat-detection;118;117;115;118;118;117;116;115;116;119 11:51:01 INFO - PID 30157 | |2;audio-dft;162;162;162;162;162;163;166;169;167;164 11:51:01 INFO - PID 30157 | |3;audio-fft;74;74;74;72;74;74;74;76;76;79 11:51:01 INFO - PID 30157 | |4;audio-oscillator;80;80;79;84;79;79;131;86;83;79 11:51:01 INFO - PID 30157 | |5;imaging-gaussian-blur;113;113;114;114;113;113;114;115;114;118 11:51:01 INFO - PID 30157 | |6;imaging-darkroom;147;147;148;147;148;151;147;148;152;150 11:51:01 INFO - PID 30157 | |7;imaging-desaturate;96;94;95;94;94;93;94;94;94;94 11:51:01 INFO - PID 30157 | |8;json-parse-financial;54;53;53;53;53;54;56;54;54;53 11:51:01 INFO - PID 30157 | |9;json-stringify-tinderbox;38;39;38;39;38;39;38;38;39;39 11:51:01 INFO - PID 30157 | |10;stanford-crypto-aes;74;74;74;75;79;75;74;74;77;75 11:51:01 INFO - PID 30157 | |11;stanford-crypto-ccm;113;112;119;117;118;118;113;115;112;113 11:51:01 INFO - PID 30157 | |12;stanford-crypto-pbkdf2;169;172;172;170;166;166;166;167;175;173 11:51:01 INFO - PID 30157 | |13;stanford-crypto-sha256-iterative;70;66;69;70;66;67;66;66;72;67 11:51:01 INFO - PID 30157 | __end_tp_report 11:51:01 INFO - PID 30157 | __start_cc_report 11:51:01 INFO - PID 30157 | _x_x_mozilla_cycle_collect,968 11:51:01 INFO - PID 30157 | __end_cc_report 11:51:01 INFO - PID 30157 | __startTimestamp1505415061691__endTimestamp 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | ------- Summary: start ------- 11:51:01 INFO - PID 30157 | Number of tests: 14 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#0] ai-astar Cycles:10 Average:107.40 Median:107.00 stddev:1.43 (1.3%) stddev-sans-first:1.42 11:51:01 INFO - PID 30157 | Values: 106.0 107.0 107.0 106.0 107.0 109.0 110.0 109.0 107.0 106.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#1] audio-beat-detection Cycles:10 Average:116.90 Median:117.50 stddev:1.37 (1.2%) stddev-sans-first:1.39 11:51:01 INFO - PID 30157 | Values: 118.0 117.0 115.0 118.0 118.0 117.0 116.0 115.0 116.0 119.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#2] audio-dft Cycles:10 Average:163.90 Median:163.50 stddev:2.56 (1.6%) stddev-sans-first:2.62 11:51:01 INFO - PID 30157 | Values: 162.0 162.0 162.0 162.0 162.0 163.0 166.0 169.0 167.0 164.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#3] audio-fft Cycles:10 Average:74.70 Median:74.00 stddev:1.89 (2.6%) stddev-sans-first:1.99 11:51:01 INFO - PID 30157 | Values: 74.0 74.0 74.0 72.0 74.0 74.0 74.0 76.0 76.0 79.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:80.00 stddev:16.01 (20.0%) stddev-sans-first:16.83 11:51:01 INFO - PID 30157 | Values: 80.0 80.0 79.0 84.0 79.0 79.0 131.0 86.0 83.0 79.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:1.52 (1.3%) stddev-sans-first:1.56 11:51:01 INFO - PID 30157 | Values: 113.0 113.0 114.0 114.0 113.0 113.0 114.0 115.0 114.0 118.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:148.00 stddev:1.84 (1.2%) stddev-sans-first:1.87 11:51:01 INFO - PID 30157 | Values: 147.0 147.0 148.0 147.0 148.0 151.0 147.0 148.0 152.0 150.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.50 11:51:01 INFO - PID 30157 | Values: 96.0 94.0 95.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.95 (1.8%) stddev-sans-first:1.00 11:51:01 INFO - PID 30157 | Values: 54.0 53.0 53.0 53.0 53.0 54.0 56.0 54.0 54.0 53.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#9] json-stringify-tinderbox Cycles:10 Average:38.50 Median:39.00 stddev:0.53 (1.4%) stddev-sans-first:0.53 11:51:01 INFO - PID 30157 | Values: 38.0 39.0 38.0 39.0 38.0 39.0 38.0 38.0 39.0 39.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:75.00 stddev:1.66 (2.2%) stddev-sans-first:1.72 11:51:01 INFO - PID 30157 | Values: 74.0 74.0 74.0 75.0 79.0 75.0 74.0 74.0 77.0 75.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#11] stanford-crypto-ccm Cycles:10 Average:115.00 Median:116.00 stddev:2.75 (2.4%) stddev-sans-first:2.82 11:51:01 INFO - PID 30157 | Values: 113.0 112.0 119.0 117.0 118.0 118.0 113.0 115.0 112.0 113.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:171.00 stddev:3.31 (1.9%) stddev-sans-first:3.50 11:51:01 INFO - PID 30157 | Values: 169.0 172.0 172.0 170.0 166.0 166.0 166.0 167.0 175.0 173.0 11:51:01 INFO - PID 30157 | 11:51:01 INFO - PID 30157 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:2.18 (3.2%) stddev-sans-first:2.18 11:51:01 INFO - PID 30157 | Values: 70.0 66.0 69.0 70.0 66.0 67.0 66.0 66.0 72.0 67.0 11:51:01 INFO - PID 30157 | -------- Summary: end -------- 11:51:01 INFO - PID 30157 | 11:51:02 INFO - PID 30157 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:51:02 INFO - TEST-INFO | 30157: exit 0 11:51:02 INFO - TEST-OK | kraken | took 61254ms 11:51:02 INFO - SUITE-END | took 1170s 11:51:02 INFO - Completed test suite (00:19:31) 11:51:02 INFO - javascript benchmark 11:51:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8586.670072599713, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3160.7155356017984, 3165.493521036634, 3193.776435649504, 3201.4717571600686, 3202.4715921872894, 6200.34896335885, 6212.44088032077, 6271.717772700234, 6310.4320611144985, 6392.648404337765, 6264.342244064376, 6276.152719382893, 6288.528360372968, 6308.905366419507, 6332.810015699536, 5804.419558044204, 5823.650580965155, 5830.82507524775, 5832.533397328225, 5885.646861188321, 5854.238948936632, 5870.060790273557, 5915.645061296314, 5938.851528711795, 5946.197263369433, 5899.734511946951, 5968.791092311748, 5978.8206353809455, 5980.820575382723, 5992.700364981755, 5687.402822703598, 5781.364049954502, 5901.4688678018965, 5918.763249470016, 5996.700164991777, 5321.414644389114, 5735.627184233053, 5797.0, 5843.941560584382, 5889.705514724246, 6326.367363263683, 6333.619982801, 6357.65032923189, 6403.135576697133, 6433.613983160979, 6629.071929929813, 6713.530052896299, 6734.8316292092595, 6749.12261406019, 6892.896606550906, 6712.127423434922, 6715.597064176116, 6716.1269035025625, 6733.629650369227, 6743.055972163901, 6287.276963149251, 6388.7125079371535, 6415.582987105823, 6417.037444383333, 6420.229572451289, 6884.931150688529, 7004.579725216503, 7070.080889484334, 7096.929030709627, 7130.286971302932, 6601.042848786899, 6673.0, 6733.225679046972, 6786.796396108125, 6822.658867056726, 6717.193936727624, 6781.0, 6873.0, 6875.0, 6879.58722476653, 6811.761588344483, 6836.0, 6859.622720750307, 6867.828304292333, 6892.586444813327, 6816.147981502312, 6880.105586273852, 6906.758263460755, 6906.896396554056, 6920.100386949664, 5197.454267301954, 5249.685018898878, 5306.867328316823, 5310.548603368757, 5317.867053323698, 4538.931916021263, 4617.815287388534, 4622.907541849211, 4687.718736875799, 4712.034033023222, 5909.172715819789, 5931.436513531294, 5933.762649494059, 5951.7619295227705, 5954.434328738849, 6827.146606674166, 6838.042674025641, 6841.726330946706, 6929.792106236821, 6945.2707465716385, 5868.67722275279, 5876.9118463223085, 5938.2280303560265, 5944.256967879015, 5946.554008449383, 5723.8569035774435, 5814.622049566765, 5816.47651711348, 5872.559558033079, 5901.852453688693, 6658.766943156967, 6663.600183988976, 6677.098591690156, 6697.966510167418, 6709.563878347892, 6810.0, 6825.48808839339, 6913.827154321182, 6914.274001229798, 6924.965375173293, 6571.835704107436, 6588.308227636029, 6591.439727623206, 6608.074869518367, 6688.264290928091, 6449.097126402214, 6594.4724422045165, 6603.240627327918, 6744.055832183596, 6767.120274364301, 6701.396874281338, 6725.9663701681175, 6732.427743641845, 6744.494162937701, 6758.695858686469, 6460.806175814733, 6488.059231411513, 6545.8690826184165, 6566.474682025522, 6567.605943643397, 6456.0, 6489.253735820441, 6542.985837195242, 6556.868862622626, 6572.277049524644, 6427.453666438405, 6529.281779004401, 6546.770863019771, 6547.607143571401, 6550.737970481127, 6537.8692426152165, 6610.900836487553, 6624.701888414937, 6765.594064356154, 6770.52606317553, 6373.6175829450385, 6439.0, 6518.608883467007, 6524.184476940382, 6541.215054193527], "value": 6193.532431217922, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [859.0807835615891, 887.4808237181248, 889.1730690457875, 890.8218356328736, 891.5854127830562, 38236.617633823655, 41703.582964170346, 41804.0, 41869.790651046744, 42062.0, 38382.424263636065, 38692.22615547687, 38808.61191388085, 38859.222815543675, 38954.61045389545, 6984.7206111755595, 7120.928790712104, 7577.583232922215, 7718.073831140243, 7752.3410510106705, 7676.155622881479, 7807.0, 7817.648205830751, 7827.608619569027, 7830.804229894241, 7782.027246594176, 7794.727184548542, 7802.258785415376, 7824.021997250344, 7825.904373387731, 7746.883796743082, 7768.45620806541, 7797.844043119106, 7798.961005194938, 7821.687132514692, 7646.808829779245, 7696.076470823481, 7700.768976930701, 7703.8844417333785, 7710.7686769397005, 37238.62761372379, 37418.81290593557, 37472.81263593692, 37673.62326376729, 37700.43449348262, 3470.1845066409373, 3512.982435087834, 3513.472979053137, 3515.771474854126, 3518.3491054155065, 31090.68909310923, 31254.21864453407, 31275.687243127504, 31302.0, 31323.84338078318, 4951.9752401237765, 5155.9742201288755, 5184.300119483859, 5188.844334669966, 5210.348706411699, 181161.0, 182886.0, 182910.0, 183053.0, 183306.0, 41795.0, 42515.787421063316, 47447.76276118666, 47587.762061189474, 47718.28422573664, 49951.25073123906, 50505.74747126241, 50607.24089138666, 50656.0, 50729.0, 16233.59416014609, 16284.185790710417, 16324.102174380285, 16337.754933676004, 16530.256138473796, 13377.866221337663, 13397.799033014513, 13406.128601640861, 13408.128471649312, 13415.597532074053, 14196.651318124761, 14203.076800007966, 14327.57017289483, 14379.78430323546, 14553.78169327461, 5884.292895475823, 5887.11693246008, 5919.940800592025, 5921.111833225051, 5933.465988061009, 1014.3305418423805, 1015.5582321689941, 1021.6168936648756, 1032.6385764982197, 1048.821700310949, 21742.782572174394, 21750.89124554367, 21909.561808764054, 22299.662020278836, 22432.887835561043, 1124.241137232365, 1135.8864113588575, 1139.2765593847967, 1139.863216414019, 1142.194752699345, 11674.0, 11846.466908989116, 11982.0, 12021.459034343476, 12086.80003389853, 206065.0, 208161.0, 228853.0, 229357.0, 229450.0, 51615.74192129015, 51834.0, 51837.740811296455, 51877.48122518802, 52032.73983630058, 1446.8263808343065, 1471.9990406523452, 1480.7852861335045, 1482.3774014913547, 1485.6360191752874, 22612.434689132904, 22628.094876204766, 22681.659775103057, 22871.31386058421, 23000.654990175495, 37757.0, 37839.24321513499, 37852.0, 37856.0, 37871.485140594064, 191059.0, 192136.0, 192173.0, 192374.0, 192573.0, 33436.83281583576, 34336.141596460286, 35658.28683426265, 35685.46471802977, 35698.46452303165, 43942.12115757731, 45178.32232516449, 45285.320720189884, 45347.77326113348, 45373.0, 33097.83451082729, 33175.83412082924, 33428.832855835564, 33555.49666755049, 33657.0, 32687.836560817042, 32890.0, 32927.67072329342, 33023.0, 33407.665923341425, 37837.62162378341, 37913.05217369478, 38085.80957095197, 38209.0, 38255.42616860805, 31895.840520797246, 32336.514952275265, 32599.0, 32613.673863262014, 33116.668833311356, 22434.77565224327, 23309.0, 23518.64722029137, 23555.175568855007, 23597.882010589838], "value": 17142.769406751944, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.7802496039027, 575.3613489027182, 575.9712014399279, 578.5545130249706, 583.1660725163016, 22111.66832497512, 22909.885450572765, 22910.312690619267, 22913.656295155546, 22983.42541436465, 22949.77050229497, 23904.88047559764, 24045.398865028383, 24054.87972560139, 24125.638115428283, 6339.619622822634, 6497.610143391388, 6540.93459065408, 6542.182227221598, 6543.378379053982, 6307.117003619497, 6393.9680301598655, 6431.517636177282, 6502.7398904043785, 6504.447121994613, 6535.967320163416, 6542.182227221598, 6543.1821022372205, 6554.8689026219445, 6567.573107748005, 6527.1188389567515, 6536.901946470783, 6539.542232043759, 6542.051402546628, 6558.770443034518, 6065.0, 6586.242582103072, 6853.862922741567, 6902.516823822284, 6912.654367281666, 29691.554626680616, 29849.850750746107, 30045.69954300451, 30107.397852042835, 30151.396972060655, 2048.8463365247667, 2063.13348393675, 2067.7725450200464, 2068.751749790035, 2071.958560828775, 19564.608707825908, 20380.28668996578, 20528.0, 20634.793652063436, 20818.687719684218, 2466.5436894174636, 2504.511620234055, 2516.3960649444184, 2574.5623244048556, 2620.4497055618344, 119159.40420297843, 125133.37433312775, 132282.0, 139614.99999999898, 141007.0, 20430.795692043186, 20713.378598642066, 21611.783882160977, 21712.34862954114, 21763.12947482083, 39519.40720889189, 39631.60368396279, 39801.40297895534, 39828.601713983066, 39836.0, 11135.554577816958, 11157.888421115847, 11196.216264861381, 11279.154063445274, 11314.094872410187, 9486.146246837818, 9492.952535237418, 9504.857427138599, 9571.856422153673, 9580.856287155699, 11295.943520282346, 11472.0, 11522.942385288188, 11540.0, 11561.710957226136, 4055.175171172984, 4145.54399016108, 4202.537720850704, 4269.829206831754, 4374.12517496502, 927.3462209142539, 957.4207604399378, 960.1181683899496, 964.5948701545368, 979.8089372572423, 17834.37579684705, 17860.642787144185, 17897.910510447364, 18156.45530634083, 18266.086695665163, 1016.598443614768, 1017.31839667423, 1032.4270030133198, 1032.4270030133348, 1032.9741756456149, 9897.158741506908, 10117.44354060519, 10119.404177149481, 10155.0, 10419.895801042045, 428617.0, 438765.0, 443865.0, 446171.0, 446299.0, 106446.0, 107318.0, 108864.0, 109058.0, 109632.0, 1016.694991502561, 1046.4610725476387, 1134.551852018448, 1150.8618965724168, 1178.5462596900254, 19041.0, 19192.520186995436, 19321.90339048296, 19532.511687207934, 19578.314758983368, 53617.731911341754, 53836.73081634567, 53990.0, 55524.0, 55687.0, 68020.3197968014, 68051.0, 68464.31535684779, 68766.31233687598, 68803.65598171977, 18210.544736381697, 18919.148638311035, 18933.715994259823, 18997.145128468976, 19004.809951900857, 21404.57190856143, 21629.13483460644, 21672.0, 21856.125754969624, 22048.88975555112, 16922.661546768748, 17053.317867285168, 17187.226574803914, 17413.129343533026, 17762.5559361017, 15761.921190393974, 15967.20163991819, 16210.189490525663, 16510.587235319214, 16627.418040368528, 18014.36949706754, 18276.543086422946, 18286.268549257882, 18296.0, 18385.17266722974, 17856.732149018037, 18654.72017919705, 18668.159932802784, 18721.53196170107, 18770.155343009323, 17780.0, 17940.0, 17948.461546153638, 17973.0, 17976.101194940464], "value": 12518.479613765696, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.1207896748663, 1346.2797403389188, 1390.4090761426392, 1398.7272481866034, 1411.5835828430618, 14899.627509312259, 15492.69014619707, 15554.84445155548, 15558.222088895567, 15569.221538923051, 15221.6955660887, 15313.92343038286, 15317.84682153178, 15344.616384590363, 15352.539423817303, 2714.7919175966704, 2737.5893615957566, 2808.1715893811274, 2816.3240822202624, 2819.3374556979093, 2696.608991696203, 2725.3731641722416, 2738.329109368198, 2741.300968253093, 2744.108164846423, 2715.3075965628836, 2815.81697189683, 2817.1830169250993, 2826.9293267668268, 2828.4484525517532, 2770.8891644334203, 2796.972030279702, 2825.307799589093, 2827.7313655202724, 2828.5050116229577, 2605.661264035682, 2785.5125353063336, 2785.9860700696568, 2786.4287820996647, 2793.092245020356, 15345.539633810891, 15477.613059673486, 15518.146501942389, 15524.456644017519, 15525.223738813014, 7945.721899733481, 8176.836463270761, 8185.918140818575, 8211.34309255258, 8248.628811703487, 5256.9737151314, 5356.91964620531, 5396.514313711785, 5493.313335833021, 5521.917171242435, 1880.8777429467177, 1886.962260754791, 1894.3085773692642, 1935.796741342153, 1943.3878328326673, 26072.217833465027, 26406.0, 26758.5986210207, 26764.73235267642, 27095.1871443857, 6505.707243174069, 6583.177102862142, 6768.2216545097945, 6772.932270677329, 6794.048833163361, 16634.417795377103, 16659.500214993328, 16683.416080437128, 16688.165591720364, 16694.91652541754, 7585.355244804143, 7760.611969401621, 7795.181505941908, 7809.531428114331, 7835.0, 6961.79114626562, 7046.260142685047, 7245.130584329914, 7263.164736055422, 7307.817304567322, 5209.505097015777, 5291.259223708684, 5298.761555730001, 5325.4940780625775, 5340.30576025122, 1522.7715842623695, 1526.6946610677687, 1536.2088524409937, 1536.5620798072496, 1545.2351086212398, 871.1245198575391, 888.1961824548794, 905.6694306578183, 906.9501177435304, 913.4473643445752, 7379.409647228209, 7387.0027546281635, 7411.295926886937, 7430.517016393917, 7478.140013898471, 705.0517054561607, 725.9818504537429, 729.824842037907, 731.0897932074495, 745.1840234942729, 4953.876153096201, 5091.159958606789, 5213.374395072616, 5382.865428364322, 5445.074337362658, 21267.78732212658, 21326.78673213248, 21456.24903128383, 21719.457013574473, 22269.22057728004, 20810.791892080884, 21513.462163445725, 21622.891885540786, 21678.13287468515, 21868.343949681534, 11294.322340659586, 11420.714482138013, 11480.253783504046, 11536.769264614828, 11610.30338179695, 8139.715109971123, 8516.701915433177, 8536.786580335542, 8564.57177141128, 8591.828163436821, 18398.08009599488, 18670.0, 18711.345102921332, 18718.906405467886, 19072.809271907103, 19014.334498292494, 19083.90458047701, 19122.713159302344, 19146.7127993083, 19261.22955081781, 10775.191860610643, 11034.117270618492, 11042.558297668003, 11046.392448415414, 11055.557777688802, 13857.168569885838, 13910.72178556403, 13931.373088210852, 14025.5091071812, 14073.788893166406, 10802.621908233488, 11142.442877856236, 11240.494177762184, 11266.380349080551, 11454.541818327174, 9757.414555126716, 9864.16154626836, 9903.207743380657, 9905.653302134391, 10087.646932357333, 10423.061924426838, 10517.631882884369, 10544.89455105439, 10551.525181366704, 10554.947225263824, 7210.603416812126, 7231.168415632059, 7257.310555497219, 7278.781636550912, 7345.77962661121, 6850.246472887879, 6851.109355783717, 6909.2745261746795, 6915.896261556181, 7090.361867431956], "value": 6612.460440055272, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.7907881544884, 504.3493892878187, 520.1054186798705, 527.6807531443474, 528.2868128027163, 6031.276246850379, 6201.542637480195, 6221.937780622193, 6252.030935205039, 6258.718357673899, 6065.757369705207, 6081.756729730805, 6094.013933172736, 6119.632822030681, 6123.203983482141, 4940.629452791037, 4959.404871415435, 4963.875903102433, 4978.0, 4990.950090499085, 4807.5432833880905, 4890.24201248807, 4908.852734417956, 4942.196098424085, 4992.525710057539, 4858.611311095122, 4917.4836642152595, 4935.358403407552, 4939.555440010398, 4941.308216849626, 4873.561379475864, 4880.487548807359, 4890.242012488052, 4899.510048995107, 4916.754162291871, 4377.715448495837, 4465.575770301816, 4474.060447306069, 4474.955250447519, 4484.0, 5963.19496867926, 6053.273607167168, 6096.512279017668, 6109.816705498843, 6178.41305076017, 5428.212909128194, 5543.889122217533, 5561.77752889888, 5582.692951887645, 5612.15817627355, 6463.0, 6503.089567460559, 6503.642299673516, 6510.40495078704, 6540.771073012469, 4823.20417131173, 4826.107170173529, 4840.419149702023, 4841.49164337743, 4863.8784030399165, 6748.257691653914, 6782.5252232343755, 6815.727370905158, 6823.454123670144, 6828.726850925957, 4941.209406494943, 4959.156943319572, 4975.626827987915, 4991.600671946236, 4992.8751781205765, 5036.647434679538, 5054.595632349477, 5057.115004874132, 5063.0380227756605, 5069.163588008011, 5599.384067752544, 5600.523955463831, 5609.214709940612, 5612.270404847343, 5613.270274864241, 6187.257529096537, 6193.163922870445, 6374.840628984312, 6537.607743535403, 6576.572522786099, 1680.865530757561, 1711.6576684663116, 1711.7603535505039, 1712.1011468978636, 1722.190570431895, 1040.256216804988, 1194.2834299420451, 1195.282830301812, 1201.9338936358408, 1216.3735676126803, 5567.498925096761, 5667.603267771216, 5704.3154821421695, 5747.281589801275, 5799.130130480462, 6727.394534491919, 6727.562708423937, 6727.62998035103, 6760.121184246115, 6785.0, 5704.22992895962, 5705.600607957404, 5713.628614140068, 5754.683492407958, 5761.135829625506, 3490.47642853574, 3543.061088811467, 3543.964560354415, 3550.5384300041346, 3558.9110272242883, 6425.903611445832, 6433.485321174295, 6458.22501299847, 6501.804945851632, 6512.153420055362, 6803.727850885909, 6808.148981377328, 6821.658917054227, 6837.726490940406, 6861.451083913275, 6517.641529715816, 6521.347865213441, 6529.106559097903, 6530.510211734139, 6552.63960482178, 6398.648074355955, 6463.870722585428, 6536.967315163393, 6613.636250006296, 6613.966930165511, 6463.806085817433, 6495.707693153914, 6567.44176744982, 6658.66946568697, 6680.498962577729, 6408.052295943731, 6459.580127291711, 6473.77341793035, 6493.577917435352, 6523.706433210422, 4916.852494425173, 4928.408590969107, 4939.333190019445, 4943.159662857251, 4962.851114466572, 6644.568103073285, 6646.368594983469, 6667.2666006738245, 6676.1988561371, 6719.563228390139, 6445.129907462432, 6449.4840412765925, 6482.967585162232, 6502.187226596676, 6543.378379053982, 6434.356564343529, 6441.516886233458, 6493.74025039012, 6501.284858665637, 6512.804615861532, 6294.590851594632, 6330.164252366762, 6333.588316759396, 6344.809655710336, 6368.203974503187, 6384.201974753156, 6407.551471396958, 6410.935890641033, 6471.676416179078, 6502.0, 5803.883922321445, 6142.938570614237, 6161.969190154021, 6169.321374648695, 6205.162303089206], "value": 4996.314184544212, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [912.968046118386, 914.0904799724274, 938.4932136646212, 941.8445956417191, 947.3699989506978, 19487.41537753868, 20133.496662583424, 20168.294109706163, 20179.899100504514, 20369.796302036975, 18290.63418731628, 18706.064696765174, 18745.7188142178, 18765.624687506242, 18803.811961880412, 948.8007518421123, 967.7580604848788, 973.7662960889371, 974.0648976982105, 975.2149519636689, 967.2068903499134, 967.3760424526176, 967.5500892085179, 968.0174622757906, 970.7718686108759, 923.3351986569659, 963.0802583532709, 1004.1665417703289, 1006.4665727164614, 1013.5287091502449, 1007.4156988946394, 1015.3501758874327, 1016.3952448293254, 1016.8983101689846, 1022.6625213679467, 723.3562129704567, 739.2016622048175, 742.9479936404454, 744.0, 746.1866565443638, 20763.377098687062, 20905.477363065893, 20960.47598810027, 20972.37082887516, 21069.0, 21752.673709894363, 21807.563848722937, 22592.887035564716, 22797.43006424836, 22817.42956426086, 17506.12469376526, 17577.384791532237, 17735.911320443443, 17747.378841740607, 18009.09954502283, 3655.4334078217917, 3695.7782533047834, 3705.573859006222, 3706.870259540903, 3709.4992176056157, 112071.0, 140933.2953335237, 142321.0, 143450.99999999895, 144235.0, 17587.384441544484, 17857.196426160855, 18084.276628934695, 18199.0, 18221.088945552936, 31930.680693193237, 32012.0, 32051.358972820413, 32051.35897282088, 32090.839545802122, 9294.581743821544, 9396.67111651089, 9430.811383772287, 9455.338126331228, 9489.335746497678, 4393.0, 4457.3091170868565, 4462.129884672523, 4477.865664030084, 4490.169318676056, 5378.1663842104535, 5460.180972854104, 5531.7234138293725, 5532.446755324436, 5578.748956296977, 1065.2756125834508, 1080.8054550180887, 1093.6828319787332, 1095.9726006849892, 1106.8267636305382, 692.3042342445809, 708.6704682322642, 720.909886264217, 721.9279370135345, 732.1799584465433, 11713.707157321136, 11809.763804724029, 12032.639020829389, 12314.076444266715, 12430.564930227398, 647.8769033883547, 649.4252586461016, 658.4238791057825, 669.855980964095, 694.9270326615733, 5703.515201207861, 5716.771329146787, 5741.655500669973, 5802.88394232113, 5889.440503152194, 125311.0, 126734.36632816777, 126919.0, 129738.0, 130438.34780826225, 106964.0, 111722.0, 112800.0, 114863.42568287272, 115865.0, 97541.5122924395, 98344.50827745958, 101419.4929025365, 101729.0, 105101.47449262858, 10635.734106647396, 10737.89262107369, 10935.945320273348, 11013.174011948975, 11118.221724479208, 43194.568054319054, 43952.12095758003, 44110.0, 45270.77364613156, 45803.77098114488, 55174.0, 58732.70633646804, 59000.0, 59114.40885591089, 60276.698616506634, 15622.375104995674, 15859.52421427359, 15932.283047262668, 15994.520164395086, 16056.43802466908, 17946.0, 18082.638347232718, 18368.08159592042, 18404.723929140804, 18603.534911627317, 16105.838941610435, 16171.595710107342, 16274.186290685655, 16331.101789401697, 16386.508404747878, 16361.181940903145, 16396.75404868904, 16560.50318490447, 16592.0, 16833.15834208309, 16019.919900400424, 16269.26788294506, 16347.754783678492, 16453.177341133134, 16478.83521164773, 11870.643880683594, 11884.76230475403, 11929.761404772029, 12132.0, 12172.147949643795, 11947.94026029899, 12060.819087713517, 12272.325022123869, 12466.750664986468, 12500.562480313145], "value": 9127.208820167409, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1425.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 107.0, 106.0, 107.0, 109.0, 110.0, 109.0, 107.0, 106.0], "value": 107.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 115.0, 118.0, 118.0, 117.0, 116.0, 115.0, 116.0, 119.0], "value": 116.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 162.0, 163.0, 166.0, 169.0, 167.0, 164.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 72.0, 74.0, 74.0, 74.0, 76.0, 76.0, 79.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 84.0, 79.0, 79.0, 131.0, 86.0, 83.0, 79.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 114.0, 113.0, 113.0, 114.0, 115.0, 114.0, 118.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 147.0, 148.0, 151.0, 147.0, 148.0, 152.0, 150.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 53.0, 53.0, 54.0, 56.0, 54.0, 54.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 39.0, 38.0, 39.0, 38.0, 38.0, 39.0, 39.0], "value": 38.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 75.0, 79.0, 75.0, 74.0, 74.0, 77.0, 75.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 119.0, 117.0, 118.0, 118.0, 113.0, 115.0, 112.0, 113.0], "value": 115.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 172.0, 172.0, 170.0, 166.0, 166.0, 166.0, 167.0, 175.0, 173.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 69.0, 70.0, 66.0, 67.0, 66.0, 66.0, 72.0, 67.0], "value": 67.9, "unit": "score"}]}]} 11:51:02 INFO - Return code: 0 11:51:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:51:02 INFO - # TBPL SUCCESS # 11:51:02 INFO - Running post-action listener: _package_coverage_data 11:51:02 INFO - Running post-action listener: _resource_record_post_action 11:51:02 INFO - [mozharness: 2017-09-14 18:51:02.977392Z] Finished run-tests step (success) 11:51:02 INFO - Running post-run listener: _resource_record_post_run 11:51:03 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 338411520; Read time: 4; Write time: 221056 11:51:03 INFO - TinderboxPrint: CPU usage
13.1% 11:51:03 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 11:51:03 INFO - TinderboxPrint: I/O write bytes / time
338,411,520 / 221,056 11:51:03 INFO - TinderboxPrint: CPU idle
8,236.4 (86.9%) 11:51:03 INFO - TinderboxPrint: CPU user
1,195.0 (12.6%) 11:51:03 INFO - TinderboxPrint: Swap in / out
0 / 0 11:51:03 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13053952; Read time: 0; Write time: 2248 11:51:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:51:03 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 324939776; Read time: 0; Write time: 218784 11:51:03 INFO - Running post-run listener: _upload_blobber_files 11:51:03 INFO - Blob upload gear active. 11:51:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:51:03 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 11:51:03 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'] 11:51:03 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 11:51:28 INFO - (blobuploader) - INFO - Open directory for files ... 11:51:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:51:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:28 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber! 11:52:28 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 11:52:28 INFO - (blobuploader) - INFO - Done attempting. 11:52:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:52:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:53:28 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber! 11:53:28 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 11:53:28 INFO - (blobuploader) - INFO - Done attempting. 11:53:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:53:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:53:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:28 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber! 11:54:28 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 11:54:28 INFO - (blobuploader) - INFO - Done attempting. 11:54:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:54:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:54:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:57 INFO - (blobuploader) - WARNING - File uploaded to blobserver but failed uploading to Amazon S3. 11:54:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:54:57 INFO - (blobuploader) - INFO - Done attempting. 11:54:57 INFO - (blobuploader) - INFO - Iteration through files over. 11:54:57 INFO - Return code: 0 11:54:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:54:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:54:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": null, "perfherder-data.json": null, "dromaeojs-e10s_raw.log": null, "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b7845d8853d2cfd5e324b43882225625ab54f1236d670580036d29b7b67c1d8615148e356821354f93db71013562db5f9bb3455121d46020dfb5d8ae7437a298"} 11:54:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:54:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:54:57 INFO - Contents: 11:54:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": null, "perfherder-data.json": null, "dromaeojs-e10s_raw.log": null, "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b7845d8853d2cfd5e324b43882225625ab54f1236d670580036d29b7b67c1d8615148e356821354f93db71013562db5f9bb3455121d46020dfb5d8ae7437a298"} 11:54:57 INFO - Running post-run listener: copy_logs_to_upload_dir 11:54:57 INFO - Copying logs to upload dir... 11:54:57 INFO - mkdir: /builds/slave/test/build/upload/logs 11:54:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1465.823742 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 25 secs) (at 2017-09-14 11:54:57.644593) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 11:54:57.647606) ========= 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=db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": null, "perfherder-data.json": null, "dromaeojs-e10s_raw.log": null, "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b7845d8853d2cfd5e324b43882225625ab54f1236d670580036d29b7b67c1d8615148e356821354f93db71013562db5f9bb3455121d46020dfb5d8ae7437a298"} build_url:https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011519 build_url: 'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": null, "perfherder-data.json": null, "dromaeojs-e10s_raw.log": null, "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b7845d8853d2cfd5e324b43882225625ab54f1236d670580036d29b7b67c1d8615148e356821354f93db71013562db5f9bb3455121d46020dfb5d8ae7437a298"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 11:54:57.676917) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:54:57.677235) ========= 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=db7e7c15f3ca076d53731445000001ba-1505407670.167844-1073574484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004570 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:54:57.759160) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 11:54:57.759486) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 11:54:57.759793) ========= ========= Total master_lag: 0.24 =========