builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-036 starttime: 1506423925.32 results: success (0) revision: 6fb1f5fae37168c824b988733aac0d46499b6c11 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:25.323412) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:25.323768) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:25.324043) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:25.466198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:25.466451) ========= 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=14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-26 04:05:26-- 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% 31.6M=0s 2017-09-26 04:05:26 (31.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148173 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:25.636171) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:25.636516) ========= 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=14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021636 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:25.685600) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:25.685901) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6fb1f5fae37168c824b988733aac0d46499b6c11 --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 6fb1f5fae37168c824b988733aac0d46499b6c11 --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=14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-26 04:05:26,556 truncating revision to first 12 chars 2017-09-26 04:05:26,556 Setting DEBUG logging. 2017-09-26 04:05:26,556 attempt 1/10 2017-09-26 04:05:26,556 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6fb1f5fae371?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-26 04:05:26,822 unpacking tar archive at: mozilla-central-6fb1f5fae371/testing/mozharness/ program finished with exit code 0 elapsedTime=0.537047 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:26.241817) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:26.245118) ========= script_repo_revision: 6fb1f5fae37168c824b988733aac0d46499b6c11 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:26.245486) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:26.245749) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 04:05:26.260510) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-26 04:05:26.260776) ========= /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=14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:05:27 INFO - MultiFileLogger online at 20170926 04:05:27 in /builds/slave/test 04:05:27 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 04:05:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:05:27 INFO - {'append_to_log': False, 04:05:27 INFO - 'base_work_dir': '/builds/slave/test', 04:05:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:05:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:05:27 INFO - 'branch': 'Firefox-Non-PGO', 04:05:27 INFO - 'buildbot_json_path': 'buildprops.json', 04:05:27 INFO - 'code_coverage': False, 04:05:27 INFO - 'config_files': ('talos/linux_config.py',), 04:05:27 INFO - 'default_actions': ('clobber', 04:05:27 INFO - 'read-buildbot-config', 04:05:27 INFO - 'download-and-extract', 04:05:27 INFO - 'populate-webroot', 04:05:27 INFO - 'create-virtualenv', 04:05:27 INFO - 'install', 04:05:27 INFO - 'setup-mitmproxy', 04:05:27 INFO - 'run-tests'), 04:05:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:05:27 INFO - 'disable_ccov_upload': False, 04:05:27 INFO - 'disable_stylo': False, 04:05:27 INFO - 'download_minidump_stackwalk': True, 04:05:27 INFO - 'download_symbols': 'ondemand', 04:05:27 INFO - 'enable_stylo': False, 04:05:27 INFO - 'enable_webrender': False, 04:05:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:05:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:05:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:05:27 INFO - 'gecko_profile': False, 04:05:27 INFO - 'gecko_profile_interval': 0, 04:05:27 INFO - 'installer_path': 'installer.exe', 04:05:27 INFO - 'log_level': 'info', 04:05:27 INFO - 'log_name': 'talos', 04:05:27 INFO - 'log_to_console': True, 04:05:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:05:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:05:27 INFO - 'opt_config_files': (), 04:05:27 INFO - 'pip_index': False, 04:05:27 INFO - 'suite': 'dromaeojs-e10s', 04:05:27 INFO - 'system_bits': '32', 04:05:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:05:27 INFO - 'title': 'talos-linux64-ix-036', 04:05:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:05:27 INFO - 'use_talos_json': True, 04:05:27 INFO - 'verify': 'False', 04:05:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:05:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:05:27 INFO - 'work_dir': 'build'} 04:05:27 INFO - [mozharness: 2017-09-26 11:05:27.278701Z] Running clobber step. 04:05:27 INFO - Running pre-action listener: _resource_record_pre_action 04:05:27 INFO - Running main action method: clobber 04:05:27 INFO - rmtree: /builds/slave/test/build 04:05:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:05:28 INFO - Running post-action listener: _resource_record_post_action 04:05:28 INFO - [mozharness: 2017-09-26 11:05:28.267474Z] Finished clobber step (success) 04:05:28 INFO - [mozharness: 2017-09-26 11:05:28.267571Z] Running read-buildbot-config step. 04:05:28 INFO - Running pre-action listener: _resource_record_pre_action 04:05:28 INFO - Running main action method: read_buildbot_config 04:05:28 INFO - Using buildbot properties: 04:05:28 INFO - { 04:05:28 INFO - "project": "", 04:05:28 INFO - "product": "firefox", 04:05:28 INFO - "who": "archaeopteryx@coole-files.de", 04:05:28 INFO - "installer_path": "public/build/target.tar.bz2", 04:05:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:05:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:05:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:05:28 INFO - "stage_platform": "linux64", 04:05:28 INFO - "basedir": "/builds/slave/test", 04:05:28 INFO - "buildnumber": 867, 04:05:28 INFO - "platform": "ubuntu64_hw", 04:05:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:05:28 INFO - "slavebuilddir": "test", 04:05:28 INFO - "taskId": "A6ca_NJURIy4uFyqxsroJg", 04:05:28 INFO - "branch": "mozilla-central", 04:05:28 INFO - "script_repo_revision": "production", 04:05:28 INFO - "revision": "6fb1f5fae37168c824b988733aac0d46499b6c11", 04:05:28 INFO - "slavename": "talos-linux64-ix-036", 04:05:28 INFO - "repo_path": "mozilla-central" 04:05:28 INFO - } 04:05:28 INFO - Finding installer, test and symbols from parent task. 04:05:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A6ca_NJURIy4uFyqxsroJg'}, attempt #1 04:05:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A6ca_NJURIy4uFyqxsroJg'}, attempt #1 04:05:29 INFO - Task dependencies: NZTur4OUTTCzffNcI7ihNw 04:05:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw'}, attempt #1 04:05:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.tar.bz2 04:05:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.test_packages.json 04:05:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.crashreporter-symbols.zip 04:05:29 INFO - Running post-action listener: _resource_record_post_action 04:05:29 INFO - [mozharness: 2017-09-26 11:05:29.723354Z] Finished read-buildbot-config step (success) 04:05:29 INFO - [mozharness: 2017-09-26 11:05:29.723572Z] Running download-and-extract step. 04:05:29 INFO - Running pre-action listener: _resource_record_pre_action 04:05:29 INFO - Running main action method: download_and_extract 04:05:29 INFO - mkdir: /builds/slave/test/build/tests 04:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:29 INFO - trying https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.test_packages.json 04:05:29 INFO - Downloading https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:05:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:05:30 INFO - Downloaded 989 bytes. 04:05:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:05:30 INFO - Using the following test package requirements: 04:05:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:05:30 INFO - u'common': [u'target.common.tests.zip'], 04:05:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:05:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:05:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:05:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:05:30 INFO - u'mozbase': [u'target.common.tests.zip'], 04:05:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:05:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:05:30 INFO - u'web-platform': [u'target.common.tests.zip', 04:05:30 INFO - u'target.web-platform.tests.tar.gz'], 04:05:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:05:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:05:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.common.tests.zip 04:05:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:05:30 INFO - Fetch https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.common.tests.zip into memory 04:05:32 INFO - Content-Length response header: 54943909 04:05:32 INFO - Bytes received: 54943909 04:05:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.talos.tests.zip 04:05:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:05:43 INFO - Fetch https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.talos.tests.zip into memory 04:05:45 INFO - Content-Length response header: 14513823 04:05:45 INFO - Bytes received: 14513823 04:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:45 INFO - trying https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.tar.bz2 04:05:45 INFO - Downloading https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:05:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:05:49 INFO - Downloaded 61657450 bytes. 04:05:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.tar.bz2 04:05:49 INFO - mkdir: /builds/slave/test/properties 04:05:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:05:49 INFO - Writing to file /builds/slave/test/properties/build_url 04:05:49 INFO - Contents: 04:05:49 INFO - build_url:https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.tar.bz2 04:05:49 INFO - Running post-action listener: _resource_record_post_action 04:05:49 INFO - Running post-action listener: find_tests_for_verification 04:05:49 INFO - Running post-action listener: set_extra_try_arguments 04:05:49 INFO - [mozharness: 2017-09-26 11:05:49.845431Z] Finished download-and-extract step (success) 04:05:49 INFO - [mozharness: 2017-09-26 11:05:49.845659Z] Running populate-webroot step. 04:05:49 INFO - Running pre-action listener: _resource_record_pre_action 04:05:49 INFO - Running main action method: populate_webroot 04:05:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:05:49 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['tresize', 04:05:49 INFO - 'tcanvasmark']}, 04:05:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:05:49 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['dromaeo_css', 04:05:49 INFO - 'kraken']}, 04:05:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:05:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:05:49 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:05:49 INFO - 'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:05:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:05:49 INFO - 'tests': ['damp', 'tps']}, 04:05:49 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:05:49 INFO - 'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['damp', 'tps']}, 04:05:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:05:49 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['dromaeo_dom']}, 04:05:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:05:49 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['basic_compositor_video', 04:05:49 INFO - 'glvideo']}, 04:05:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:05:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:05:49 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:05:49 INFO - 'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['ts_paint_webext', 04:05:49 INFO - 'tp5o_webext']}, 04:05:49 INFO - 'other-e10s': {'tests': ['a11yr', 04:05:49 INFO - 'ts_paint', 04:05:49 INFO - 'tpaint', 04:05:49 INFO - 'sessionrestore', 04:05:49 INFO - 'sessionrestore_many_windows', 04:05:49 INFO - 'sessionrestore_no_auto_restore', 04:05:49 INFO - 'tabpaint']}, 04:05:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['a11yr', 04:05:49 INFO - 'ts_paint', 04:05:49 INFO - 'tpaint', 04:05:49 INFO - 'sessionrestore', 04:05:49 INFO - 'sessionrestore_many_windows', 04:05:49 INFO - 'sessionrestore_no_auto_restore', 04:05:49 INFO - 'tabpaint']}, 04:05:49 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 04:05:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:05:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['perf_reftest_singletons']}, 04:05:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['perf_reftest']}, 04:05:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:05:49 INFO - 'tsvgr_opacity', 04:05:49 INFO - 'tart', 04:05:49 INFO - 'tscrollx', 04:05:49 INFO - 'tsvg_static']}, 04:05:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['tsvgx', 04:05:49 INFO - 'tsvgr_opacity', 04:05:49 INFO - 'tart', 04:05:49 INFO - 'tscrollx', 04:05:49 INFO - 'tsvg_static']}, 04:05:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:05:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:05:49 INFO - 'talos_options': ['--disable-stylo'], 04:05:49 INFO - 'tests': ['tp5o']}, 04:05:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:05:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:05:49 INFO - 'talos_options': ['--mitmproxy', 04:05:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:05:49 INFO - '--firstNonBlankPaint'], 04:05:49 INFO - 'tests': ['tp6_google', 04:05:49 INFO - 'tp6_youtube', 04:05:49 INFO - 'tp6_amazon', 04:05:49 INFO - 'tp6_facebook']}, 04:05:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:05:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:05:49 INFO - 'talos_options': ['--disable-stylo', 04:05:49 INFO - '--mitmproxy', 04:05:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:05:49 INFO - '--firstNonBlankPaint'], 04:05:49 INFO - 'tests': ['tp6_google', 04:05:49 INFO - 'tp6_youtube', 04:05:49 INFO - 'tp6_amazon', 04:05:49 INFO - 'tp6_facebook']}, 04:05:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:05:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:05:49 INFO - 'talos_options': ['--stylo-threads=1', 04:05:49 INFO - '--mitmproxy', 04:05:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:05:49 INFO - '--firstNonBlankPaint'], 04:05:49 INFO - 'tests': ['tp6_google', 04:05:49 INFO - 'tp6_youtube', 04:05:49 INFO - 'tp6_amazon', 04:05:49 INFO - 'tp6_facebook']}, 04:05:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:05:49 INFO - 'talos_options': ['--xperf_path', 04:05:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:05:49 INFO - 'tests': ['tp5n']}, 04:05:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:05:49 INFO - 'talos_options': ['--disable-stylo', 04:05:49 INFO - '--xperf_path', 04:05:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:05:49 INFO - 'tests': ['tp5n']}}} 04:05:49 INFO - Running post-action listener: _resource_record_post_action 04:05:49 INFO - [mozharness: 2017-09-26 11:05:49.870542Z] Finished populate-webroot step (success) 04:05:49 INFO - [mozharness: 2017-09-26 11:05:49.870631Z] Running create-virtualenv step. 04:05:49 INFO - Running pre-action listener: _resource_record_pre_action 04:05:49 INFO - Running main action method: create_virtualenv 04:05:49 INFO - Creating virtualenv /builds/slave/test/build/venv 04:05:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:05:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:05:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:05:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:05:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:05:50 INFO - Installing distribute.............................................................................................................................................................................................done. 04:05:50 INFO - Installing pip...............done. 04:05:50 INFO - Return code: 0 04:05:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:05:50 INFO - Reading from file tmpfile_stdout 04:05:50 INFO - Output received: 04:05:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:05:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:05: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')]} 04:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05: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')]} 04:05:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:50 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 0x1f6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2136b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22db9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d8d60>, '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 0x22d9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d9ef0>, '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': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', '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 04:05:50 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 04:05:50 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 04:05:50 INFO - Using env: {'DISPLAY': ':0', 04:05:50 INFO - 'HOME': '/home/cltbld', 04:05:50 INFO - 'LANG': 'en_US.UTF-8', 04:05:50 INFO - 'LANGUAGE': 'en_US:en', 04:05:50 INFO - 'LOGNAME': 'cltbld', 04:05:50 INFO - 'MAIL': '/var/mail/cltbld', 04:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:50 INFO - 'MOZ_NO_REMOTE': '1', 04:05:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:50 INFO - 'NO_EM_RESTART': '1', 04:05:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:50 INFO - 'PWD': '/builds/slave/test', 04:05:50 INFO - 'SHELL': '/bin/bash', 04:05:50 INFO - 'SHLVL': '1', 04:05:50 INFO - 'TERM': 'linux', 04:05:50 INFO - 'TMOUT': '86400', 04:05:50 INFO - 'USER': 'cltbld', 04:05:50 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:50 INFO - '_': '/tools/buildbot/bin/python'} 04:05:52 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:05:52 INFO - Downloading/unpacking pip>=1.5 04:05:52 INFO - Running setup.py egg_info for package pip 04:05:52 INFO - warning: no files found matching 'pip/cacert.pem' 04:05:52 INFO - warning: no files found matching '*.html' under directory 'docs' 04:05:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:05:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:05:52 INFO - Installing collected packages: pip 04:05:52 INFO - Found existing installation: pip 1.1 04:05:52 INFO - Uninstalling pip: 04:05:52 INFO - Successfully uninstalled pip 04:05:52 INFO - Running setup.py install for pip 04:05:52 INFO - warning: no files found matching 'pip/cacert.pem' 04:05:52 INFO - warning: no files found matching '*.html' under directory 'docs' 04:05:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:05:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:05:52 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:05:52 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:05:52 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:05:52 INFO - Successfully installed pip 04:05:52 INFO - Cleaning up... 04:05:52 INFO - Return code: 0 04:05:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2136b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22db9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d8d60>, '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 0x22d9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d9ef0>, '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': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', '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 04:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:05:52 INFO - Using env: {'DISPLAY': ':0', 04:05:52 INFO - 'HOME': '/home/cltbld', 04:05:52 INFO - 'LANG': 'en_US.UTF-8', 04:05:52 INFO - 'LANGUAGE': 'en_US:en', 04:05:52 INFO - 'LOGNAME': 'cltbld', 04:05:52 INFO - 'MAIL': '/var/mail/cltbld', 04:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:52 INFO - 'MOZ_NO_REMOTE': '1', 04:05:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:52 INFO - 'NO_EM_RESTART': '1', 04:05:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:52 INFO - 'PWD': '/builds/slave/test', 04:05:52 INFO - 'SHELL': '/bin/bash', 04:05:52 INFO - 'SHLVL': '1', 04:05:52 INFO - 'TERM': 'linux', 04:05:52 INFO - 'TMOUT': '86400', 04:05:52 INFO - 'USER': 'cltbld', 04:05:52 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:52 INFO - '_': '/tools/buildbot/bin/python'} 04:05:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:52 INFO - Downloading/unpacking psutil>=3.1.1 04:05:52 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 04:05:52 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 04:05:52 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 04:05:52 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 04:05:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:05:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:05:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:05:53 INFO - Installing collected packages: psutil 04:05:53 INFO - Running setup.py install for psutil 04:05:53 INFO - building 'psutil._psutil_linux' extension 04:05:53 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 04:05:53 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 04:05:53 INFO - building 'psutil._psutil_posix' extension 04:05:53 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 04:05:53 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 04:05:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:05:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:05:54 INFO - Successfully installed psutil 04:05:54 INFO - Cleaning up... 04:05:54 INFO - Return code: 0 04:05:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:05: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')]} 04:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05: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')]} 04:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2136b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22db9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d8d60>, '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 0x22d9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d9ef0>, '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': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', '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 04:05: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:05: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 mozsystemmonitor==0.3 04:05:54 INFO - Using env: {'DISPLAY': ':0', 04:05:54 INFO - 'HOME': '/home/cltbld', 04:05:54 INFO - 'LANG': 'en_US.UTF-8', 04:05:54 INFO - 'LANGUAGE': 'en_US:en', 04:05:54 INFO - 'LOGNAME': 'cltbld', 04:05:54 INFO - 'MAIL': '/var/mail/cltbld', 04:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:54 INFO - 'MOZ_NO_REMOTE': '1', 04:05:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:54 INFO - 'NO_EM_RESTART': '1', 04:05:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:54 INFO - 'PWD': '/builds/slave/test', 04:05:54 INFO - 'SHELL': '/bin/bash', 04:05:54 INFO - 'SHLVL': '1', 04:05:54 INFO - 'TERM': 'linux', 04:05:54 INFO - 'TMOUT': '86400', 04:05:54 INFO - 'USER': 'cltbld', 04:05:54 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:54 INFO - '_': '/tools/buildbot/bin/python'} 04:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:05:55 INFO - Installing collected packages: mozsystemmonitor 04:05:55 INFO - Running setup.py install for mozsystemmonitor 04:05:55 INFO - Successfully installed mozsystemmonitor 04:05:55 INFO - Cleaning up... 04:05:56 INFO - Return code: 0 04:05:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:56 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 0x1f6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2136b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22db9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d8d60>, '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 0x22d9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d9ef0>, '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': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', '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 04:05:56 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 04:05:56 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 04:05:56 INFO - Using env: {'DISPLAY': ':0', 04:05:56 INFO - 'HOME': '/home/cltbld', 04:05:56 INFO - 'LANG': 'en_US.UTF-8', 04:05:56 INFO - 'LANGUAGE': 'en_US:en', 04:05:56 INFO - 'LOGNAME': 'cltbld', 04:05:56 INFO - 'MAIL': '/var/mail/cltbld', 04:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:56 INFO - 'MOZ_NO_REMOTE': '1', 04:05:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:56 INFO - 'NO_EM_RESTART': '1', 04:05:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:56 INFO - 'PWD': '/builds/slave/test', 04:05:56 INFO - 'SHELL': '/bin/bash', 04:05:56 INFO - 'SHLVL': '1', 04:05:56 INFO - 'TERM': 'linux', 04:05:56 INFO - 'TMOUT': '86400', 04:05:56 INFO - 'USER': 'cltbld', 04:05:56 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:56 INFO - '_': '/tools/buildbot/bin/python'} 04:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:56 INFO - Downloading/unpacking jsonschema==2.5.1 04:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:05:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:05:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:05: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 04:05: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 04:05: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 04:05: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 04:05:57 INFO - Downloading functools32-3.2.3-2.tar.gz 04:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:05:57 INFO - warning: no files found matching '*.txt' 04:05:57 INFO - no previously-included directories found matching 'build' 04:05:57 INFO - no previously-included directories found matching 'dist' 04:05:57 INFO - no previously-included directories found matching '.git*' 04:05:57 INFO - Installing collected packages: jsonschema, functools32 04:05:57 INFO - Running setup.py install for functools32 04:05:57 INFO - warning: no files found matching '*.txt' 04:05:57 INFO - no previously-included directories found matching 'build' 04:05:57 INFO - no previously-included directories found matching 'dist' 04:05:57 INFO - no previously-included directories found matching '.git*' 04:05:58 INFO - Successfully installed jsonschema functools32 04:05:58 INFO - Cleaning up... 04:05:58 INFO - Return code: 0 04:05:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2136b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22db9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d8d60>, '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 0x22d9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d9ef0>, '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': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', '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 04:05:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:05:58 INFO - Using env: {'DISPLAY': ':0', 04:05:58 INFO - 'HOME': '/home/cltbld', 04:05:58 INFO - 'LANG': 'en_US.UTF-8', 04:05:58 INFO - 'LANGUAGE': 'en_US:en', 04:05:58 INFO - 'LOGNAME': 'cltbld', 04:05:58 INFO - 'MAIL': '/var/mail/cltbld', 04:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:58 INFO - 'MOZ_NO_REMOTE': '1', 04:05:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:58 INFO - 'NO_EM_RESTART': '1', 04:05:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:58 INFO - 'PWD': '/builds/slave/test', 04:05:58 INFO - 'SHELL': '/bin/bash', 04:05:58 INFO - 'SHLVL': '1', 04:05:58 INFO - 'TERM': 'linux', 04:05:58 INFO - 'TMOUT': '86400', 04:05:58 INFO - 'USER': 'cltbld', 04:05:58 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:58 INFO - '_': '/tools/buildbot/bin/python'} 04:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:05:58 INFO - Cleaning up... 04:05:58 INFO - Return code: 0 04:05:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:05:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2136b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22db9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d8d60>, '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 0x22d9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d9ef0>, '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': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', '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 04:05:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:05:58 INFO - Using env: {'DISPLAY': ':0', 04:05:58 INFO - 'HOME': '/home/cltbld', 04:05:58 INFO - 'LANG': 'en_US.UTF-8', 04:05:58 INFO - 'LANGUAGE': 'en_US:en', 04:05:58 INFO - 'LOGNAME': 'cltbld', 04:05:58 INFO - 'MAIL': '/var/mail/cltbld', 04:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:58 INFO - 'MOZ_NO_REMOTE': '1', 04:05:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:58 INFO - 'NO_EM_RESTART': '1', 04:05:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:58 INFO - 'PWD': '/builds/slave/test', 04:05:58 INFO - 'SHELL': '/bin/bash', 04:05:58 INFO - 'SHLVL': '1', 04:05:58 INFO - 'TERM': 'linux', 04:05:58 INFO - 'TMOUT': '86400', 04:05:58 INFO - 'USER': 'cltbld', 04:05:58 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:58 INFO - '_': '/tools/buildbot/bin/python'} 04:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:05:59 INFO - Downloading/unpacking blobuploader==1.2.4 04:05: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 04:05: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 04:05: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 04:05: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 04:06:00 INFO - Downloading blobuploader-1.2.4.tar.gz 04:06:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:06:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:06:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:06:00 INFO - Downloading docopt-0.6.1.tar.gz 04:06:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:06:01 INFO - Installing collected packages: blobuploader, requests, docopt 04:06:01 INFO - Running setup.py install for blobuploader 04:06:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:06:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:06:01 INFO - Running setup.py install for requests 04:06:01 INFO - Running setup.py install for docopt 04:06:01 INFO - Successfully installed blobuploader requests docopt 04:06:01 INFO - Cleaning up... 04:06:01 INFO - Return code: 0 04:06:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:06: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')]} 04:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06: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')]} 04:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:01 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 0x1f6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2136b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22db9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d8d60>, '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 0x22d9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d9ef0>, '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': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', '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 04:06:01 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 04:06:01 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 04:06:01 INFO - Using env: {'DISPLAY': ':0', 04:06:01 INFO - 'HOME': '/home/cltbld', 04:06:01 INFO - 'LANG': 'en_US.UTF-8', 04:06:01 INFO - 'LANGUAGE': 'en_US:en', 04:06:01 INFO - 'LOGNAME': 'cltbld', 04:06:01 INFO - 'MAIL': '/var/mail/cltbld', 04:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:01 INFO - 'MOZ_NO_REMOTE': '1', 04:06:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:01 INFO - 'NO_EM_RESTART': '1', 04:06:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:01 INFO - 'PWD': '/builds/slave/test', 04:06:01 INFO - 'SHELL': '/bin/bash', 04:06:01 INFO - 'SHLVL': '1', 04:06:01 INFO - 'TERM': 'linux', 04:06:01 INFO - 'TMOUT': '86400', 04:06:01 INFO - 'USER': 'cltbld', 04:06:01 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:01 INFO - '_': '/tools/buildbot/bin/python'} 04:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:06:01 INFO - Running setup.py (path:/tmp/pip-qTp66N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:06:01 INFO - Running setup.py (path:/tmp/pip-tsnv2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:06:01 INFO - Running setup.py (path:/tmp/pip-2h69js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:06:02 INFO - Running setup.py (path:/tmp/pip-JNwJfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:06:02 INFO - Running setup.py (path:/tmp/pip-hEGGNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:06:02 INFO - Running setup.py (path:/tmp/pip-rwCqU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:06:02 INFO - Running setup.py (path:/tmp/pip-yCf8xy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:06:02 INFO - Running setup.py (path:/tmp/pip-ptU87z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:06:02 INFO - Running setup.py (path:/tmp/pip-QQ0eVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:06:02 INFO - Running setup.py (path:/tmp/pip-nFp_k_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:06:02 INFO - Running setup.py (path:/tmp/pip-lEU8Ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:06:02 INFO - Running setup.py (path:/tmp/pip-sr89cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:06:02 INFO - Running setup.py (path:/tmp/pip-GLA85n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:06:02 INFO - Running setup.py (path:/tmp/pip-3bYBzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:06:02 INFO - Running setup.py (path:/tmp/pip-DgXrPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:06:02 INFO - Running setup.py (path:/tmp/pip-7I0g6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:06:03 INFO - Running setup.py (path:/tmp/pip-QH3XtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:06:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:06:03 INFO - Running setup.py install for manifestparser 04:06:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:06:03 INFO - Running setup.py install for mozcrash 04:06:03 INFO - Running setup.py install for mozdebug 04:06:03 INFO - Running setup.py install for mozdevice 04:06:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:06:03 INFO - Running setup.py install for mozfile 04:06:03 INFO - Running setup.py install for mozhttpd 04:06:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:06:03 INFO - Running setup.py install for mozinfo 04:06:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:06:03 INFO - Running setup.py install for mozInstall 04:06:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:06:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:06:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:06:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:06:03 INFO - Running setup.py install for mozleak 04:06:03 INFO - Running setup.py install for mozlog 04:06:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:06:04 INFO - Running setup.py install for moznetwork 04:06:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:06:04 INFO - Running setup.py install for mozprocess 04:06:04 INFO - Running setup.py install for mozprofile 04:06:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:06:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:06:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:06:04 INFO - Running setup.py install for mozrunner 04:06:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:06:04 INFO - Running setup.py install for mozscreenshot 04:06:04 INFO - Running setup.py install for moztest 04:06:04 INFO - Running setup.py install for mozversion 04:06:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:06:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:06:04 INFO - Cleaning up... 04:06:04 INFO - Return code: 0 04:06:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:06: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')]} 04:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06: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')]} 04:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:04 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 0x1f6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2136b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22db9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d8d60>, '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 0x22d9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d9ef0>, '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': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', '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 04:06:04 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 04:06:04 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 04:06:04 INFO - Using env: {'DISPLAY': ':0', 04:06:04 INFO - 'HOME': '/home/cltbld', 04:06:04 INFO - 'LANG': 'en_US.UTF-8', 04:06:04 INFO - 'LANGUAGE': 'en_US:en', 04:06:04 INFO - 'LOGNAME': 'cltbld', 04:06:04 INFO - 'MAIL': '/var/mail/cltbld', 04:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:04 INFO - 'MOZ_NO_REMOTE': '1', 04:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:04 INFO - 'NO_EM_RESTART': '1', 04:06:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:04 INFO - 'PWD': '/builds/slave/test', 04:06:04 INFO - 'SHELL': '/bin/bash', 04:06:04 INFO - 'SHLVL': '1', 04:06:04 INFO - 'TERM': 'linux', 04:06:04 INFO - 'TMOUT': '86400', 04:06:04 INFO - 'USER': 'cltbld', 04:06:04 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:04 INFO - '_': '/tools/buildbot/bin/python'} 04:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:06:05 INFO - Running setup.py (path:/tmp/pip-6BgP5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:06:05 INFO - Running setup.py (path:/tmp/pip-0I0ZRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:06:05 INFO - Running setup.py (path:/tmp/pip-7wm9lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:06:05 INFO - Running setup.py (path:/tmp/pip-T3EyJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:06:05 INFO - Running setup.py (path:/tmp/pip-tEGJrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:06:05 INFO - Running setup.py (path:/tmp/pip-aAzdT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:06:05 INFO - Running setup.py (path:/tmp/pip-w8kENN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:06:05 INFO - Running setup.py (path:/tmp/pip-IVu5Eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:06:05 INFO - Running setup.py (path:/tmp/pip-4oouIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:06:05 INFO - Running setup.py (path:/tmp/pip-hDTqet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:06:05 INFO - Running setup.py (path:/tmp/pip-JnzcTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:06:05 INFO - Running setup.py (path:/tmp/pip-U67D6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:06:05 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)) 04:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:06:05 INFO - Running setup.py (path:/tmp/pip-veSaCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:06:06 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)) 04:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:06:06 INFO - Running setup.py (path:/tmp/pip-T3mzBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:06:06 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)) 04:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:06:06 INFO - Running setup.py (path:/tmp/pip-Uea0Sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:06:06 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)) 04:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:06:06 INFO - Running setup.py (path:/tmp/pip-Lvaesz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:06:06 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)) 04:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:06:06 INFO - Running setup.py (path:/tmp/pip-0dQWre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:06:06 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)) 04:06:06 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)) 04:06:06 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)) 04:06:06 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)) 04:06:06 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)) 04:06:06 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)) 04:06:06 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)) 04:06:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:06:06 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 04:06:06 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 04:06:06 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 04:06:06 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 04:06:07 INFO - Downloading blessings-1.6.tar.gz 04:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:06:07 INFO - Installing collected packages: blessings 04:06:07 INFO - Running setup.py install for blessings 04:06:07 INFO - Successfully installed blessings 04:06:07 INFO - Cleaning up... 04:06:07 INFO - Return code: 0 04:06:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:06:07 INFO - Reading from file tmpfile_stdout 04:06:07 INFO - Current package versions: 04:06:07 INFO - argparse == 1.2.1 04:06:07 INFO - blessings == 1.6 04:06:07 INFO - blobuploader == 1.2.4 04:06:07 INFO - docopt == 0.6.1 04:06:07 INFO - functools32 == 3.2.3-2 04:06:07 INFO - jsonschema == 2.5.1 04:06:07 INFO - manifestparser == 1.1 04:06:07 INFO - mozInstall == 1.13 04:06:07 INFO - mozcrash == 1.0 04:06:07 INFO - mozdebug == 0.1 04:06:07 INFO - mozdevice == 0.51 04:06:07 INFO - mozfile == 1.2 04:06:07 INFO - mozhttpd == 0.7 04:06:07 INFO - mozinfo == 0.10 04:06:07 INFO - mozleak == 0.1 04:06:07 INFO - mozlog == 3.5 04:06:07 INFO - moznetwork == 0.27 04:06:07 INFO - mozprocess == 0.25 04:06:07 INFO - mozprofile == 0.28 04:06:07 INFO - mozrunner == 6.13 04:06:07 INFO - mozscreenshot == 0.1 04:06:07 INFO - mozsystemmonitor == 0.3 04:06:07 INFO - moztest == 0.8 04:06:07 INFO - mozversion == 1.4 04:06:07 INFO - psutil == 3.1.1 04:06:07 INFO - requests == 1.2.3 04:06:07 INFO - wsgiref == 0.1.2 04:06:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:06: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')]} 04:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06: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')]} 04:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:07 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 0x1f6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2136b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22db9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d8d60>, '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 0x22d9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d9ef0>, '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': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', '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 04:06:07 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 04:06:07 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 04:06:07 INFO - Using env: {'DISPLAY': ':0', 04:06:07 INFO - 'HOME': '/home/cltbld', 04:06:07 INFO - 'LANG': 'en_US.UTF-8', 04:06:07 INFO - 'LANGUAGE': 'en_US:en', 04:06:07 INFO - 'LOGNAME': 'cltbld', 04:06:07 INFO - 'MAIL': '/var/mail/cltbld', 04:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:07 INFO - 'MOZ_NO_REMOTE': '1', 04:06:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:07 INFO - 'NO_EM_RESTART': '1', 04:06:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:07 INFO - 'PWD': '/builds/slave/test', 04:06:07 INFO - 'SHELL': '/bin/bash', 04:06:07 INFO - 'SHLVL': '1', 04:06:07 INFO - 'TERM': 'linux', 04:06:07 INFO - 'TMOUT': '86400', 04:06:07 INFO - 'USER': 'cltbld', 04:06:07 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:07 INFO - '_': '/tools/buildbot/bin/python'} 04:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:08 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)) 04:06:08 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)) 04:06:08 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)) 04:06:08 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)) 04:06:08 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)) 04:06:08 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)) 04:06:08 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)) 04:06:08 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)) 04:06:08 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)) 04:06:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:06: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 04:06: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 04:06: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 04:06: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 04:06:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:06:09 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)) 04:06:09 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)) 04:06:09 INFO - Installing collected packages: simplejson 04:06:09 INFO - Running setup.py install for simplejson 04:06:09 INFO - building 'simplejson._speedups' extension 04:06:09 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 04:06:10 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 04:06:10 INFO - Successfully installed simplejson 04:06:10 INFO - Cleaning up... 04:06:10 INFO - Return code: 0 04:06:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:06: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')]} 04:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:06: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')]} 04:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:06:10 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 0x1f6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2136b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22db9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d8d60>, '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 0x22d9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d9ef0>, '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': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', '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 04:06:10 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 04:06:10 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 04:06:10 INFO - Using env: {'DISPLAY': ':0', 04:06:10 INFO - 'HOME': '/home/cltbld', 04:06:10 INFO - 'LANG': 'en_US.UTF-8', 04:06:10 INFO - 'LANGUAGE': 'en_US:en', 04:06:10 INFO - 'LOGNAME': 'cltbld', 04:06:10 INFO - 'MAIL': '/var/mail/cltbld', 04:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:10 INFO - 'MOZ_NO_REMOTE': '1', 04:06:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:10 INFO - 'NO_EM_RESTART': '1', 04:06:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:10 INFO - 'PWD': '/builds/slave/test', 04:06:10 INFO - 'SHELL': '/bin/bash', 04:06:10 INFO - 'SHLVL': '1', 04:06:10 INFO - 'TERM': 'linux', 04:06:10 INFO - 'TMOUT': '86400', 04:06:10 INFO - 'USER': 'cltbld', 04:06:10 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:10 INFO - '_': '/tools/buildbot/bin/python'} 04:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:06:10 INFO - Cleaning up... 04:06:10 INFO - Return code: 0 04:06:10 INFO - Running post-action listener: _resource_record_post_action 04:06:10 INFO - Running post-action listener: _start_resource_monitoring 04:06:10 INFO - Starting resource monitoring. 04:06:10 INFO - [mozharness: 2017-09-26 11:06:10.600368Z] Finished create-virtualenv step (success) 04:06:10 INFO - [mozharness: 2017-09-26 11:06:10.600979Z] Running install step. 04:06:10 INFO - Running pre-action listener: _resource_record_pre_action 04:06:10 INFO - Running main action method: install 04:06:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:06:10 INFO - Reading from file tmpfile_stdout 04:06:10 INFO - Detecting whether we're running mozinstall >=1.0... 04:06:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:06:10 INFO - Reading from file tmpfile_stdout 04:06:10 INFO - Output received: 04:06:10 INFO - Usage: mozinstall [options] installer 04:06:10 INFO - Options: 04:06:10 INFO - -h, --help show this help message and exit 04:06:10 INFO - -d DEST, --destination=DEST 04:06:10 INFO - Directory to install application into. [default: 04:06:10 INFO - "/builds/slave/test"] 04:06:10 INFO - --app=APP Application being installed. [default: firefox] 04:06:10 INFO - mkdir: /builds/slave/test/build/application 04:06:10 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'] 04:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:06:28 INFO - Reading from file tmpfile_stdout 04:06:28 INFO - Output received: 04:06:28 INFO - /builds/slave/test/build/application/firefox/firefox 04:06:28 INFO - Running post-action listener: _resource_record_post_action 04:06:28 INFO - [mozharness: 2017-09-26 11:06:28.714269Z] Finished install step (success) 04:06:28 INFO - [mozharness: 2017-09-26 11:06:28.714523Z] Running setup-mitmproxy step. 04:06:28 INFO - Running pre-action listener: _resource_record_pre_action 04:06:28 INFO - Running main action method: setup_mitmproxy 04:06:28 INFO - Skipping: mitmproxy is not required 04:06:28 INFO - Running post-action listener: _resource_record_post_action 04:06:28 INFO - [mozharness: 2017-09-26 11:06:28.715652Z] Finished setup-mitmproxy step (success) 04:06:28 INFO - [mozharness: 2017-09-26 11:06:28.715872Z] Running run-tests step. 04:06:28 INFO - Running pre-action listener: _resource_record_pre_action 04:06:28 INFO - Running pre-action listener: _set_gcov_prefix 04:06:28 INFO - Running main action method: run_tests 04:06:28 WARNING - Try message not found. 04:06:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:06:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:06:28 INFO - Python 2.7.3 04:06:28 INFO - Return code: 0 04:06:28 INFO - grabbing minidump binary from tooltool 04:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:06:28 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 0x22d8d60>, '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 0x22d9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d9ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:06:28 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 04:06:28 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 04:06:28 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 04:06:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:06:28 INFO - Return code: 0 04:06:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:06:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:06:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:06:28 INFO - ENV: RUST_BACKTRACE is now full 04:06:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:06:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:06:28 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/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 04:06:28 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/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 04:06:28 INFO - Using env: {'DISPLAY': ':0', 04:06:28 INFO - 'HOME': '/home/cltbld', 04:06:28 INFO - 'LANG': 'en_US.UTF-8', 04:06:28 INFO - 'LANGUAGE': 'en_US:en', 04:06:28 INFO - 'LOGNAME': 'cltbld', 04:06:28 INFO - 'MAIL': '/var/mail/cltbld', 04:06:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:06:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:28 INFO - 'MOZ_NO_REMOTE': '1', 04:06:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:06:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:28 INFO - 'NO_EM_RESTART': '1', 04:06:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:28 INFO - 'PWD': '/builds/slave/test', 04:06:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:06:28 INFO - 'RUST_BACKTRACE': 'full', 04:06:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:06:28 INFO - 'SHELL': '/bin/bash', 04:06:28 INFO - 'SHLVL': '1', 04:06:28 INFO - 'TERM': 'linux', 04:06:28 INFO - 'TMOUT': '86400', 04:06:28 INFO - 'USER': 'cltbld', 04:06:28 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:28 INFO - '_': '/tools/buildbot/bin/python'} 04:06:28 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/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 04:06:29 INFO - mozversion application_buildid: 20170926095221 04:06:29 INFO - mozversion application_changeset: 6fb1f5fae37168c824b988733aac0d46499b6c11 04:06:29 INFO - mozversion application_display_name: Nightly 04:06:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:06:29 INFO - mozversion application_name: Firefox 04:06:29 INFO - mozversion application_remotingname: firefox 04:06:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:06:29 INFO - mozversion application_vendor: Mozilla 04:06:29 INFO - mozversion application_version: 58.0a1 04:06:29 INFO - mozversion platform_buildid: 20170926095221 04:06:29 INFO - mozversion platform_changeset: 6fb1f5fae37168c824b988733aac0d46499b6c11 04:06:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:06:29 INFO - mozversion platform_version: 58.0a1 04:06:29 INFO - using testdate: 1506423989 04:06:29 INFO - actual date: 1506423989 04:06:29 INFO - starting webserver on 'localhost:44457' 04:06:29 INFO - SUITE-START | Running 2 tests 04:06:29 INFO - TEST-START | dromaeo_css 04:06:29 INFO - Initialising browser for dromaeo_css test... 04:06:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44457/getInfo.html -profile /tmp/tmpEka294/profile 04:06:29 INFO - TEST-INFO | started process 21128 (/builds/slave/test/build/application/firefox/firefox http://localhost:44457/getInfo.html) 04:06:35 INFO - TEST-INFO | 21128: exit 0 04:06:35 INFO - Browser initialized. 04:06:35 INFO - Running cycle 1/1 for dromaeo_css test... 04:06:35 INFO - Using env: {'DISPLAY': ':0', 04:06:35 INFO - 'HOME': '/home/cltbld', 04:06:35 INFO - 'JSGC_DISABLE_POISONING': '1', 04:06:35 INFO - 'LANG': 'en_US.UTF-8', 04:06:35 INFO - 'LANGUAGE': 'en_US:en', 04:06:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:06:35 INFO - 'LOGNAME': 'cltbld', 04:06:35 INFO - 'MAIL': '/var/mail/cltbld', 04:06:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:06:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:06:35 INFO - 'MOZ_CRASHREPORTER': '1', 04:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:06:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:06:35 INFO - 'MOZ_NO_REMOTE': '1', 04:06:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:06:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:06:35 INFO - 'NO_EM_RESTART': '1', 04:06:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:06:35 INFO - 'PWD': '/builds/slave/test', 04:06:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:06:35 INFO - 'RUST_BACKTRACE': 'full', 04:06:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:06:35 INFO - 'SHELL': '/bin/bash', 04:06:35 INFO - 'SHLVL': '1', 04:06:35 INFO - 'TERM': 'linux', 04:06:35 INFO - 'TMOUT': '86400', 04:06:35 INFO - 'USER': 'cltbld', 04:06:35 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:06:35 INFO - '_': '/tools/buildbot/bin/python'} 04:06:35 INFO - TEST-INFO | started process 21365 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEka294/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:06:36 INFO - PID 21365 | 04:06:36 INFO - PID 21365 | (/builds/slave/test/build/application/firefox/firefox:21414): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:06:36 INFO - PID 21365 | 04:06:46 INFO - PID 21365 | RSS: Main: 151609344 04:06:46 INFO - PID 21365 | 04:09:44 INFO - PID 21365 | Cycle 1(1): loaded http://localhost:44457/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44457/tests/dromaeo/cssquery-ext.html) 04:09:45 INFO - PID 21365 | RSS: Main: 152707072 04:09:45 INFO - PID 21365 | 04:12:46 INFO - PID 21365 | Cycle 1(1): loaded http://localhost:44457/tests/dromaeo/cssquery-ext.html (next: http://localhost:44457/tests/dromaeo/cssquery-jquery.html) 04:12:47 INFO - PID 21365 | RSS: Main: 153505792 04:12:47 INFO - PID 21365 | 04:15:49 INFO - PID 21365 | Cycle 1(1): loaded http://localhost:44457/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44457/tests/dromaeo/cssquery-mootools.html) 04:15:49 INFO - PID 21365 | RSS: Main: 155017216 04:15:49 INFO - PID 21365 | 04:18:51 INFO - PID 21365 | Cycle 1(1): loaded http://localhost:44457/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44457/tests/dromaeo/cssquery-prototype.html) 04:18:51 INFO - PID 21365 | RSS: Main: 153481216 04:18:51 INFO - PID 21365 | 04:21:55 INFO - PID 21365 | Cycle 1(1): loaded http://localhost:44457/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44457/tests/dromaeo/cssquery-yui.html) 04:21:55 INFO - PID 21365 | RSS: Main: 154918912 04:21:55 INFO - PID 21365 | 04:24:57 INFO - PID 21365 | Cycle 1(1): loaded http://localhost:44457/tests/dromaeo/cssquery-yui.html (next: http://localhost:44457/tests/dromaeo/cssquery-dojo.html) 04:24:57 INFO - PID 21365 | RSS: Main: 156852224 04:24:57 INFO - PID 21365 | 04:24:57 INFO - PID 21365 | __start_tp_report 04:24:57 INFO - PID 21365 | _x_x_mozilla_page_load 04:24:57 INFO - PID 21365 | _x_x_mozilla_page_load_details 04:24:57 INFO - PID 21365 | |i|pagename|runs| 04:24:57 INFO - PID 21365 | |0;dojo.html;3156.810591364518;3175.9523607145884;3188.250761071149;3196.408664397086;3218.372417378611;6217;6283.905741413871;6386.329435409279;6434;6453.612783233003;6288.811335659926;6304.1489398931135;6313.621182729028;6318.684065796703;6347.079673447347;5822.854428639275;5838.766449342022;5878.5003274721485;5920.437558431942;5987.401259873999;5903.203067585885;5927.970360148193;5963.075723262901;5971.014782560872;6005.969970150143;806.8767908309449;5770.2498675171955;5912.7930522431725;5984.640921544699;5985.401459854023;5431.348238211401;5801.3038435388025;5802.7388767505145;5890.705464726747;5932.79235226765;5796.8840623187725;5868.706564671749;5913.497352725023;5939.643621382731;5966.940330596682;6327.778527751507;6420.646864422455;6426.967865160691;6475.643839588821;6503.447206987365;6852.862942741117;6859.142607174103;6945.756898508528;6962.443004559623;6975.895361569581;6736.05695202672;6816.7614133505085;6909.412699920513;6924.23833378328;7010.228874823765;6278.748850045993;6321.873562528724;6330.87338253237;6341.58779679324;6372.044193370985;7073.540219885691;7155.17715462718;7166;7174.92825071753;7181.856362872713;6726.899096513556;6733.730650774012;6739.831504212434;6819.113515243086;6832.145981752281;6663.433608143362;6751.797446076625;6752.291009443938;6849.589024658437;6931.341522555349;6764.492663050192;6767.2894346093935;6770.796876093626;6778.76274330406;6780.491463140284;6646.767363142267;6810.523263371616;6869.828254293583;6894.069300644349;6905.620190889449;5343.625946183807;5399.676019438846;5407.621466497308;5408.783648654088;5411.2965314508865;4621.722696638212;4670.602998745145;4674.6026587740425;4686.671932964659;4689.390379250675;5705.400932902068;5807.738651760681;5808.186853840466;5812.128180772918;5821.883562328814;6684.231313398924;6831.9658401707675;6850.760223392257;6921.861562768817;6922.238553759083;5871.32479764824;5875.50058245054;5889.646621202742;5889.764409423575;5902.2327097477055;5833.38749431312;5858.979737900789;5906.675132867647;5911.4975227105315;5912.438318359749;6441.066045423388;6520.380563846427;6540.574862633914;6545.116409284781;6626.072349871022;6798.490113241428;6819.897701534382;6820.761273355409;6824.829379265359;6828.112345395067;6463.741450342121;6541.967290163519;6566.770163044271;6569.770058047945;6575.605463672195;6116;6670.599764014175;6713.060171575886;6715.899261511179;6748.426383757435;6839.487038472234;6860.965695171692;6968.581885086911;6972.6513674315065;7008.299170082951;6678.899816502853;6750.86498270022;6759.188897332351;6765.492588055817;6768.96615516939;6662.533622646368;6663.46692264608;6670.266270710315;6755.493337999768;6761.560498567577;6423.710933007932;6532.771353002622;6532.934670653232;6536.379043990813;6684.83287917806;6638.800835974928;6681;6692.230393504809;6715.529912906245;6731.259561448334;6318.336574659595;6437.452816510649;6489.870202595827;6566.310537393505;6570.507211959218 04:24:57 INFO - PID 21365 | |1;ext.html;860.9397342186046;885.6014793342997;886.1581497577299;887.3389324952911;887.6937456577483;39028.41457378138;42870.571294287045;42891.78554107225;43160.784196079054;43166.92082697934;38239.80880095596;38406.23187536248;38569;38639.22721545568;38640.227195456144;7296.197418283985;7923.7226697065325;8096.271335579797;8166.1833816618455;8167.95916020422;7956.085050219241;8071.556064416454;8099.635516401776;8135;8135.2678258956685;8090;8102.5138491690395;8135.389845761562;8157.673693052271;8177.959110204441;8105.27052565269;8108.472949258279;8152.796180095485;8153.714619988302;8154.8776768348225;7952.045754509496;8036.879446808302;8036.919630803675;8045.115037345888;8054.355651547862;37270.44094338587;37272.25455490902;37494.25011499782;37504.81247593772;37562.81218593917;3586.318599466093;3631.4371272452813;3631.9818400907825;3633.5458067741533;3640.908977275563;30823.845880770677;30859.22851928697;31012;31035.22411939697;31232.21919452009;5028.321176641143;5058.949410505885;5120.257562653394;5141.871453213662;5191.792328306863;187059;187628;187930;188277;189104.99999999863;41811.372829407585;42530.72407827708;47352;48064.5193548067;48070.5192948066;50548;50636;50681.493185068415;50686.746566266935;50700.49299506958;15579.922100389425;15666.843331566539;15698.843011569967;15709.764353534707;15743.606409839846;12655.746885062246;12672.80990785139;12697.428615712315;12746.80879786804;12782.488700452064;13835.30823458823;14000.579982600539;14006.159630422208;14133.71732565343;14176.929115354356;5762.308522977186;5780.797672081457;5793.710314484259;5802.970985145132;5951.613145145551;1005.1456262177092;1006.4263369879245;1017.862588550551;1031.3863251365426;1032.7211652853687;23388.415289617555;23506.177283794987;23526.764732352458;23547.411314716926;23626;1133.863936327646;1134.4384529657873;1141.7431078007382;1148.1446322489633;1156.3408856951626;12251.632451026484;12438.129330946927;12556.309402982924;12562.497500100077;12576.937115314548;230233;232084;232145;232237;232291;53577.464225358024;53819.19271210935;53930.460695393325;53989.73005134949;54040.72979635155;1439.2228196773835;1468.5007097586872;1470.0150898897764;1477.9926100369644;1480.6224412774889;22566.661500077185;22675.433114172276;22722.318330450114;22815.832495912862;23305.650415244123;37830;38093;38102.2379552413;38104;38147.61852381441;142861;143830;143910;144482;145118;32188.356232875678;32976.34047319088;33211;33348.499772503914;33462.330753385286;41281.58718412898;41316.586834131274;41892.79053604713;41894.58105419029;42233.57766422419;31134.53298200574;31333.686663133078;31337.686623133475;31595.84202078975;31597.526037109;31263.84368078145;31406.842965785025;31671;31752.523712144797;32000.67999319977;35481.29037419289;35961.640383595826;35997.64002359943;36067.27865442624;36176.09559761027;30225.395492090476;30359;30587.2353191172;30648.387032258783;30670.386592268474;22671;22735.51675035586;22831.543369132192;22860;23033.65449518225 04:24:57 INFO - PID 21365 | |2;jquery.html;581.6393835821791;590.769599856056;593.6230493636542;611.5446482373271;611.9020956646938;21391.786082139173;22091.889540552314;22147.0033848477;22163.224287149933;22184.88907555464;22634.092944571606;23677.171299004538;23753.40616484589;23762.405939851513;23907.76092239077;6439.195100612424;6483.189601299838;6544.345565443466;6578.046183303418;6590.110335104748;6368.713407896656;6442.48460123189;6469.4824414047;6473.8705225895455;6699.598024118569;6671.766488172915;6675.132232809728;6682.498812589075;6682.532222744409;6687.465002799764;6649.0691303217345;6653.135092438;6654.733810647568;6657.667116644148;6683.6992335344785;6702.396784289389;6957.686904089278;6974.163100427931;6982.1970473395195;6983.89524157138;29247.2688182795;29746.40507189844;29805.40389192204;29879.253018674488;29892.402151956838;2118.9046492907855;2139.465133716571;2141.5716856628737;2144.57108578285;2149.0651566568595;19427.514312142168;19937.700934485994;20310;20624.69062964057;20728.58542829135;2555.7060937992183;2570.820042597019;2615.8168928175037;2632.8551929643863;2637.024301008639;121651.39174304072;126754;126811;133690;141099.29450352682;19908;20387.898060509604;21166.259180928595;21520.246791362228;21677.78322216789;41235;41242.38136427956;41422.58577414187;41557.376639350434;41562.79218603948;11212.775744485065;11301.71745706364;11431.657050288504;11496.252743571642;11532.538698451968;9426.670066547638;9452.149306562442;9463.479508626953;9527.857082143773;9616.807663846685;10552.683419497427;10637.627683031056;10897.400642964554;10990.670279891616;11061;3811.3520701480816;3840.731148819556;4015.819288132041;4072.450219220367;4255.957440425556;939.7979434421633;942.330945029037;970.7718686108759;985.2807450560987;986.4919566423298;18719.157637906326;18961.81038189628;19134.71297930532;19261.807381926283;19309.22763089489;1033.0857191385678;1044.8955104489642;1045.3309881675734;1051.5530899367739;1058.5289546152042;10545.921195204335;10606.045455909005;10693.83959240612;10699.358038517714;10714.946425267979;453387;455549;475963;479910;483852;107791.46104269428;109601;109698;109990;110244;1139.629620373382;1139.6979800352913;1170.6546568762064;1170.9004734597654;1173.284296579086;16885.32458701638;16891.831081689026;17011.574710631838;17337.479875603753;17431.47705568835;50727;51055.744721276154;51906.22140667969;52228;52298.477015230885;65835.67082164559;66521.66739166467;66569;66585.66707166433;66851;19161.808381916002;19316.130774115478;19349;19477.220911163397;19497.415077547696;21439.46401339979;21811.890940545196;21821.23625673094;21942.341729748132;21992.120315187854;17306.394276200273;17649.735253970943;17743.20155593027;17777.46667599974;17782;15507.147106909228;15586.454474093352;15884;16058.197090145679;16233;17814.46556603304;18085.728714069035;18249.726254106463;18276.45170644883;18295.26818927228;18886.244550217838;18892.716609251147;18988.525286867385;19075.618487630447;19222.8077719221;17529.211185496937;17801.020943848212;17812.732809008136;17833.73249401234;17852.285908563514 04:24:57 INFO - PID 21365 | |3;mootools.html;1349.116328804633;1360.4082224232454;1388.2295326094027;1395.246566853899;1407.8885662685461;14970.850291497081;15690.137042462675;15698.37206511741;15703.293351799168;15705.136217508032;15066.548003559883;16183.919080404581;16186.109763963006;16225.756613650805;16228.83771162288;2769.155407600686;2838.3755573773738;2845.6869744328105;2847.2312475631566;2849.9002534911283;2715.7148499407576;2727.618133461317;2805.102367242483;2805.382815780535;2826.067397758735;2797.049003338864;2860.5709143628414;2862.0269108503103;2863.4988876946554;2864.1980245531286;2672.973270267292;2769.2938300733385;2773.265084752532;2789.707080756532;2790.9302267443272;2700.6894207166033;2704;2728.0997270900557;2731.5356389413846;2732.1393760965234;14685.55943321702;14959.551213463614;15023.924880375638;15038.32327545263;15056;7679.424043196726;7974.122846486882;7975.322097621709;7977.920220797776;8000.8799868002025;5081.085404627165;5235.764390602394;5374.220737992969;5407.648502847303;5413.485718856702;1913.569446874456;1959.0792327606002;1990.790966948464;2006.244536030973;2022.8078332558384;26730.5990410144;26881.327966800793;26901.192964211106;26908;26951.865240673866;6679.632620205935;6721.66391680414;6752.088468056848;6785.219699734594;6902.447804175654;16951.745723814154;17041.573960650836;17041.82958170427;17049.14754262282;17072;7675.270849269311;8318.875216871751;8548;8599.312055035582;8616.396852220285;6453.322401147906;6475.870482590416;6687.398134167851;6702.061711360413;7679.539227646247;5183.559397451183;5302.1781623848365;5330.946690533045;5334.59990500714;5388.326459192601;1564.7496400575853;1567.6472793621572;1585.2232406120918;1596.2098761113323;1615.0874755762957;915.5879854065697;927.5779520318239;932.7528205025674;940.2853831088286;941.6139382852997;7642.847143057108;7764.495307805088;7780.066392032993;7862.488938218998;7891.408144389194;683.2108914204102;686.2348481443255;694.9478789090839;702.8769965256059;706.2725392845377;4092.9590704093175;4098.077932465231;4123.587641235852;4126.277901367249;4166.395872598457;21293;21479.355619331443;22439.10243590238;22440.887795561244;22454.438639034153;20843.687344689843;21383.57232855365;21788.782112178993;22070.779292207193;22100.2264920727;11165.609203677832;11384.886151138382;11500.539978400937;11597.420128993517;11618.186726929034;8193.262606365455;8325.375596830141;8602.698905538276;8692.348073894356;8855;18588.721169182743;18724.625507490047;18903.90548047251;18962.24151033943;19024.809751902303;19142.904285478482;19258.61482770365;19311.613767724284;19339.4198174055;19344.32294869673;11087.55649774;11333.886661133283;11335;11355.659330220107;11365.147613928822;14154.929225353808;14155.858441415454;14312.57062288133;14384.06503577264;14463.13221206731;11693.298402095901;11820.290782553075;11870;11941.880581194077;12142;11179.496922638664;11302.378369189444;11453.942730286295;11610.303381797119;11643.068554515448;10040.196784257389;10121.684181056415;10214.233932454947;10233.641822536176;10283.640072597424;7225.1329840419485;7284.708611655687;7331.3768329692575;7332.23011583776;7356.742514011984;6510.381513756186;6547.377999090078;6568.7372505099265;6639.601623902581;6723.562968407038 04:24:57 INFO - PID 21365 | |4;prototype.html;516.7777855522125;519.1537793396762;542.9755660995255;550.9118541033434;561.5563704673308;6342.509510265084;6398.3601639836;6434.227892652876;6440.5491615586925;6481.0926470294135;6165.907511387321;6238.376162383759;6271.843203919904;6291.842703932404;6302.968485157579;5147.665401748884;5169.121249387613;5190.71451070191;5194.844154675366;5197.766100525467;5114.948850511503;5118.769655365518;5120.487951204868;5133.383993920735;5183.196604526304;4862.34358361622;5056.924146137811;5074.238864170386;5081.415637201713;5122.564582010534;5019.874503137433;5061.5950723942;5103.209002604603;5117.948820511784;5129.410117836422;4564.246899261618;4573.702709323883;4586.289125185602;4597.885052873671;4612.915964748281;6062.696865156725;6162.198914141133;6165.198524191826;6197.721102550351;6224.657643829588;5858.47273745365;5884.293884733859;5893.322267939199;5900.232969713953;5932.9703351483395;6478.190226221723;6506.93493065068;6538.869222615569;6556.475481961432;6559.245686746038;4988.950110498885;4989.426215985171;4991.176455884811;4995.225740010304;5006.098902197603;6939.618320992344;7079.221285658574;7108.537945033608;7114.14630244374;7178.5692858428665;5035.521625445577;5098.745062746848;5100.719460429637;5136.563392111712;5171.793488037366;5203.869903252374;5214.400343960493;5227.634065615371;5256.632035757537;5259.868503287448;5751.8562035949435;5863.120531920246;5870.119482077637;5883.794067207627;5891.4403131703275;6729.899051514231;6812;6848;6853.211880633691;6871.5189936703955;1682.966340673204;1732.159902447312;1798.1998010885031;1801.4505575799508;1806.5844855683267;1149.4597539156582;1235.3020543393134;1260.3887114749339;1263.1347526944073;1267.752788206291;5544.722763861791;5626;5647.644565304297;5678.432156784289;5755.165501002415;6626.635535045619;6638.900416493757;6641.402273795382;6668.499862510331;6771.8984215236815;5602.915956260659;5620.57845661577;5674.971625141848;5687.60186786921;5790.36306006339;3509.7367697422796;3516.837145155711;3533.982330088333;3575.9284814304087;3581.8746343877838;6366.713497892606;6406.8718625627225;6418.26189988148;6493.603875166862;6500.902486462707;6767.240517465494;6854.760083397057;6867.141607299088;6871.415929646036;6894.551854129566;6314.810555683337;6357.682115894187;6365.745370185141;6413.35866413355;6447.548671592944;6570.277269500256;6610.4050635443045;6622.867542649024;6628.038934354588;6645.169353830771;6423.96788016057;6510.023496475566;6567.408933196035;6569.37590928861;6623.966880165568;6384.968075159594;6420.26166990802;6422.39440139965;6489.9026514603265;6490.253620833664;5016.523430274118;5089.9745501272255;5091.338126043591;5100.336956195671;5112.795488180431;6633.6351500666;6667.766628167991;6689.2976237496305;6694.732210711712;6695.397414232742;6240.313565507881;6284.528660350401;6376.266729326187;6495.772647957298;6528.575642583407;6404.295527492065;6414.5189110815945;6419.45434638061;6446.387593178641;6481.773137940149;6061.0604606525485;6250.9062364065485;6327;6339;6419.197600299963;6385.297617262005;6442.130312407765;6443.71003304843;6460.321666225167;6544.149260596092;6220.112429731418;6237.5321850860455;6263.592866463665;6291.245050593775;6292.402221788922 04:24:57 INFO - PID 21365 | |5;yui.html;916.7478943290595;919.7792529792849;922.8569571716378;923.6536298887919;932.0260327957284;19007;21846.781532184672;23455.1790687326;23510;23581.938812753426;19636.803631963678;21363.359099227007;21697.3490795276;21815.781842181572;21845.453863653416;973.6056896956726;990.747359423346;990.8513722941544;993.0813997052697;997.2470784557648;984.2077127912028;985.8816941966974;986.6793292180034;987;990.1633120013577;1051.3061379489538;1053.6680945502142;1055.8152323343422;1058.201058201061;1060.083028180622;1041.281515754131;1043.9269251152423;1049.4647729657854;1052.6526246338767;1055.076807793181;777.6073083093058;778.5484419037001;778.8987431633908;781.144646611959;782.6360742254878;19724;20060.598788024305;20533.4866628334;20578.176872925065;20594.485137871525;21390;21997.340079797632;22237.332880013626;22307.776922230732;22398.888005559867;17631.206595703225;17863;17863.553411164696;17913.462596122135;18191.636167276713;3738.8317525711136;3808.352580061396;3808.352580061396;3811.6378944000553;3813.180166264239;129829;131794;133493;133918;135559.32220338934;14116.85883141176;14179.432822687184;14494.637634059232;14543.20012399307;14589.635259118395;30765.538516922265;31690.207744806565;31694.207644809063;31697.20756981094;31762;9000.954995225113;9077.137671921157;9134.132257435533;9156.954215228881;9200.125988031126;4047.5547689754103;4130.690198235145;4131.173765246963;4135.6898232632675;4136.669066474675;5669.858253543694;5691.203231547586;5719.170720245625;5772.6536407815665;5776.884462310731;996.4419924842111;1014.1177175857051;1023.902729240721;1031.7369070886875;1034.813733527972;665.6671664167916;668.3083009085573;676.1075380497795;680.1803826389189;693.7537174303151;11639.06887449002;12191.573294934635;12404.62786116418;12755.68110797219;13199.868001320056;687.1685260834347;692.8752824491539;693.5873155472486;703.8873780195145;704.3168126916883;5668.149777533403;5770;5793.971030144822;5803.419658034163;5886.3230728465915;114728;117734.41132794453;118167;118684.40657796655;123914;105261;106668.46665766621;106969.46515267374;109132.45433772939;109966.45016775026;92703.07296926944;95070.52464737633;96919;97408;98299.50850245703;10713.625023124154;10719.24965252456;10823.458827058774;10859.733024491097;10939.726506837074;43311.783441083855;43329.783351083046;44049;44603.363529065886;45468.31797522974;55324.170137448775;55660.16509752276;55941;57364.42635573591;58387.416125839896;13997;14203.28983550839;14275.357608907416;14389.352479138674;14426.42294308216;14865.256737162883;14975.176365300013;15070.472533461276;15183.24083795828;15200.771988420403;16116.919415402848;16338.183090845172;16388.75416868772;16592.751108733137;16604.335826566803;15785.131817749674;15800.683986320439;15874;16128.435504757746;16142.596435089217;16339.836601633831;16420.671586568442;16514.091724955244;16614.501564953072;16701.415450459175;11650.70873228176;11687.590934317259;11687.941560292484;11750.705789214506;12198.390080496118;12052.216605920587;12244.265344079384;12284.692882678004;12627.74744505123;12651.683707907376 04:24:57 INFO - PID 21365 | __end_tp_report 04:24:57 INFO - PID 21365 | __start_cc_report 04:24:57 INFO - PID 21365 | _x_x_mozilla_cycle_collect,277 04:24:57 INFO - PID 21365 | __end_cc_report 04:24:57 INFO - PID 21365 | __startTimestamp1506425097790__endTimestamp 04:24:57 INFO - PID 21365 | 04:24:57 INFO - PID 21365 | ------- Summary: start ------- 04:24:57 INFO - PID 21365 | Number of tests: 6 04:24:57 INFO - PID 21365 | 04:24:57 INFO - PID 21365 | [#0] dojo.html Cycles:165 Average:6247.62 Median:6532.77 stddev:872.09 (13.3%) stddev-sans-first:840.39 04:24:57 INFO - PID 21365 | Values: 3156.8 3176.0 3188.3 3196.4 3218.4 6217.0 6283.9 6386.3 6434.0 6453.6 6288.8 6304.1 6313.6 6318.7 6347.1 5822.9 5838.8 5878.5 5920.4 5987.4 5903.2 5928.0 5963.1 5971.0 6006.0 806.9 5770.2 5912.8 5984.6 5985.4 5431.3 5801.3 5802.7 5890.7 5932.8 5796.9 5868.7 5913.5 5939.6 5966.9 6327.8 6420.6 6427.0 6475.6 6503.4 6852.9 6859.1 6945.8 6962.4 6975.9 6736.1 6816.8 6909.4 6924.2 7010.2 6278.7 6321.9 6330.9 6341.6 6372.0 7073.5 7155.2 7166.0 7174.9 7181.9 6726.9 6733.7 6739.8 6819.1 6832.1 6663.4 6751.8 6752.3 6849.6 6931.3 6764.5 6767.3 6770.8 6778.8 6780.5 6646.8 6810.5 6869.8 6894.1 6905.6 5343.6 5399.7 5407.6 5408.8 5411.3 4621.7 4670.6 4674.6 4686.7 4689.4 5705.4 5807.7 5808.2 5812.1 5821.9 6684.2 6832.0 6850.8 6921.9 6922.2 5871.3 5875.5 5889.6 5889.8 5902.2 5833.4 5859.0 5906.7 5911.5 5912.4 6441.1 6520.4 6540.6 6545.1 6626.1 6798.5 6819.9 6820.8 6824.8 6828.1 6463.7 6542.0 6566.8 6569.8 6575.6 6116.0 6670.6 6713.1 6715.9 6748.4 6839.5 6861.0 6968.6 6972.7 7008.3 6678.9 6750.9 6759.2 6765.5 6769.0 6662.5 6663.5 6670.3 6755.5 6761.6 6423.7 6532.8 6532.9 6536.4 6684.8 6638.8 6681.0 6692.2 6715.5 6731.3 6318.3 6437.5 6489.9 6566.3 6570.5 04:24:57 INFO - PID 21365 | 04:24:57 INFO - PID 21365 | [#1] ext.html Cycles:180 Average:35798.81 Median:19533.80 stddev:49611.43 (254.0%) stddev-sans-first:49681.24 04:24:57 INFO - PID 21365 | Values: 860.9 885.6 886.2 887.3 887.7 39028.4 42870.6 42891.8 43160.8 43166.9 38239.8 38406.2 38569.0 38639.2 38640.2 7296.2 7923.7 8096.3 8166.2 8168.0 7956.1 8071.6 8099.6 8135.0 8135.3 8090.0 8102.5 8135.4 8157.7 8178.0 8105.3 8108.5 8152.8 8153.7 8154.9 7952.0 8036.9 8036.9 8045.1 8054.4 37270.4 37272.3 37494.3 37504.8 37562.8 3586.3 3631.4 3632.0 3633.5 3640.9 30823.8 30859.2 31012.0 31035.2 31232.2 5028.3 5058.9 5120.3 5141.9 5191.8 187059.0 187628.0 187930.0 188277.0 189105.0 41811.4 42530.7 47352.0 48064.5 48070.5 50548.0 50636.0 50681.5 50686.7 50700.5 15579.9 15666.8 15698.8 15709.8 15743.6 12655.7 12672.8 12697.4 12746.8 12782.5 13835.3 14000.6 14006.2 14133.7 14176.9 5762.3 5780.8 5793.7 5803.0 5951.6 1005.1 1006.4 1017.9 1031.4 1032.7 23388.4 23506.2 23526.8 23547.4 23626.0 1133.9 1134.4 1141.7 1148.1 1156.3 12251.6 12438.1 12556.3 12562.5 12576.9 230233.0 232084.0 232145.0 232237.0 232291.0 53577.5 53819.2 53930.5 53989.7 54040.7 1439.2 1468.5 1470.0 1478.0 1480.6 22566.7 22675.4 22722.3 22815.8 23305.7 37830.0 38093.0 38102.2 38104.0 38147.6 142861.0 143830.0 143910.0 144482.0 145118.0 32188.4 32976.3 33211.0 33348.5 33462.3 41281.6 41316.6 41892.8 41894.6 42233.6 31134.5 31333.7 31337.7 31595.8 31597.5 31263.8 31406.8 31671.0 31752.5 32000.7 35481.3 35961.6 35997.6 36067.3 36176.1 30225.4 30359.0 30587.2 30648.4 30670.4 22671.0 22735.5 22831.5 22860.0 23033.7 04:24:57 INFO - PID 21365 | 04:24:57 INFO - PID 21365 | [#2] jquery.html Cycles:180 Average:34418.07 Median:21599.02 stddev:78825.67 (365.0%) stddev-sans-first:79005.86 04:24:57 INFO - PID 21365 | Values: 581.6 590.8 593.6 611.5 611.9 21391.8 22091.9 22147.0 22163.2 22184.9 22634.1 23677.2 23753.4 23762.4 23907.8 6439.2 6483.2 6544.3 6578.0 6590.1 6368.7 6442.5 6469.5 6473.9 6699.6 6671.8 6675.1 6682.5 6682.5 6687.5 6649.1 6653.1 6654.7 6657.7 6683.7 6702.4 6957.7 6974.2 6982.2 6983.9 29247.3 29746.4 29805.4 29879.3 29892.4 2118.9 2139.5 2141.6 2144.6 2149.1 19427.5 19937.7 20310.0 20624.7 20728.6 2555.7 2570.8 2615.8 2632.9 2637.0 121651.4 126754.0 126811.0 133690.0 141099.3 19908.0 20387.9 21166.3 21520.2 21677.8 41235.0 41242.4 41422.6 41557.4 41562.8 11212.8 11301.7 11431.7 11496.3 11532.5 9426.7 9452.1 9463.5 9527.9 9616.8 10552.7 10637.6 10897.4 10990.7 11061.0 3811.4 3840.7 4015.8 4072.5 4256.0 939.8 942.3 970.8 985.3 986.5 18719.2 18961.8 19134.7 19261.8 19309.2 1033.1 1044.9 1045.3 1051.6 1058.5 10545.9 10606.0 10693.8 10699.4 10714.9 453387.0 455549.0 475963.0 479910.0 483852.0 107791.5 109601.0 109698.0 109990.0 110244.0 1139.6 1139.7 1170.7 1170.9 1173.3 16885.3 16891.8 17011.6 17337.5 17431.5 50727.0 51055.7 51906.2 52228.0 52298.5 65835.7 66521.7 66569.0 66585.7 66851.0 19161.8 19316.1 19349.0 19477.2 19497.4 21439.5 21811.9 21821.2 21942.3 21992.1 17306.4 17649.7 17743.2 17777.5 17782.0 15507.1 15586.5 15884.0 16058.2 16233.0 17814.5 18085.7 18249.7 18276.5 18295.3 18886.2 18892.7 18988.5 19075.6 19222.8 17529.2 17801.0 17812.7 17833.7 17852.3 04:24:57 INFO - PID 21365 | 04:24:57 INFO - PID 21365 | [#3] mootools.html Cycles:180 Average:9389.60 Median:14826.28 stddev:6801.45 (45.9%) stddev-sans-first:6793.70 04:24:57 INFO - PID 21365 | Values: 1349.1 1360.4 1388.2 1395.2 1407.9 14970.9 15690.1 15698.4 15703.3 15705.1 15066.5 16183.9 16186.1 16225.8 16228.8 2769.2 2838.4 2845.7 2847.2 2849.9 2715.7 2727.6 2805.1 2805.4 2826.1 2797.0 2860.6 2862.0 2863.5 2864.2 2673.0 2769.3 2773.3 2789.7 2790.9 2700.7 2704.0 2728.1 2731.5 2732.1 14685.6 14959.6 15023.9 15038.3 15056.0 7679.4 7974.1 7975.3 7977.9 8000.9 5081.1 5235.8 5374.2 5407.6 5413.5 1913.6 1959.1 1990.8 2006.2 2022.8 26730.6 26881.3 26901.2 26908.0 26951.9 6679.6 6721.7 6752.1 6785.2 6902.4 16951.7 17041.6 17041.8 17049.1 17072.0 7675.3 8318.9 8548.0 8599.3 8616.4 6453.3 6475.9 6687.4 6702.1 7679.5 5183.6 5302.2 5330.9 5334.6 5388.3 1564.7 1567.6 1585.2 1596.2 1615.1 915.6 927.6 932.8 940.3 941.6 7642.8 7764.5 7780.1 7862.5 7891.4 683.2 686.2 694.9 702.9 706.3 4093.0 4098.1 4123.6 4126.3 4166.4 21293.0 21479.4 22439.1 22440.9 22454.4 20843.7 21383.6 21788.8 22070.8 22100.2 11165.6 11384.9 11500.5 11597.4 11618.2 8193.3 8325.4 8602.7 8692.3 8855.0 18588.7 18724.6 18903.9 18962.2 19024.8 19142.9 19258.6 19311.6 19339.4 19344.3 11087.6 11333.9 11335.0 11355.7 11365.1 14154.9 14155.9 14312.6 14384.1 14463.1 11693.3 11820.3 11870.0 11941.9 12142.0 11179.5 11302.4 11453.9 11610.3 11643.1 10040.2 10121.7 10214.2 10233.6 10283.6 7225.1 7284.7 7331.4 7332.2 7356.7 6510.4 6547.4 6568.7 6639.6 6723.6 04:24:57 INFO - PID 21365 | 04:24:57 INFO - PID 21365 | [#4] prototype.html Cycles:180 Average:5535.89 Median:6208.92 stddev:1526.71 (24.6%) stddev-sans-first:1483.79 04:24:57 INFO - PID 21365 | Values: 516.8 519.2 543.0 550.9 561.6 6342.5 6398.4 6434.2 6440.5 6481.1 6165.9 6238.4 6271.8 6291.8 6303.0 5147.7 5169.1 5190.7 5194.8 5197.8 5114.9 5118.8 5120.5 5133.4 5183.2 4862.3 5056.9 5074.2 5081.4 5122.6 5019.9 5061.6 5103.2 5117.9 5129.4 4564.2 4573.7 4586.3 4597.9 4612.9 6062.7 6162.2 6165.2 6197.7 6224.7 5858.5 5884.3 5893.3 5900.2 5933.0 6478.2 6506.9 6538.9 6556.5 6559.2 4989.0 4989.4 4991.2 4995.2 5006.1 6939.6 7079.2 7108.5 7114.1 7178.6 5035.5 5098.7 5100.7 5136.6 5171.8 5203.9 5214.4 5227.6 5256.6 5259.9 5751.9 5863.1 5870.1 5883.8 5891.4 6729.9 6812.0 6848.0 6853.2 6871.5 1683.0 1732.2 1798.2 1801.5 1806.6 1149.5 1235.3 1260.4 1263.1 1267.8 5544.7 5626.0 5647.6 5678.4 5755.2 6626.6 6638.9 6641.4 6668.5 6771.9 5602.9 5620.6 5675.0 5687.6 5790.4 3509.7 3516.8 3534.0 3575.9 3581.9 6366.7 6406.9 6418.3 6493.6 6500.9 6767.2 6854.8 6867.1 6871.4 6894.6 6314.8 6357.7 6365.7 6413.4 6447.5 6570.3 6610.4 6622.9 6628.0 6645.2 6424.0 6510.0 6567.4 6569.4 6624.0 6385.0 6420.3 6422.4 6489.9 6490.3 5016.5 5090.0 5091.3 5100.3 5112.8 6633.6 6667.8 6689.3 6694.7 6695.4 6240.3 6284.5 6376.3 6495.8 6528.6 6404.3 6414.5 6419.5 6446.4 6481.8 6061.1 6250.9 6327.0 6339.0 6419.2 6385.3 6442.1 6443.7 6460.3 6544.1 6220.1 6237.5 6263.6 6291.2 6292.4 04:24:57 INFO - PID 21365 | 04:24:57 INFO - PID 21365 | [#5] yui.html Cycles:180 Average:24110.18 Median:20978.92 stddev:34311.01 (163.5%) stddev-sans-first:34363.06 04:24:57 INFO - PID 21365 | Values: 916.7 919.8 922.9 923.7 932.0 19007.0 21846.8 23455.2 23510.0 23581.9 19636.8 21363.4 21697.3 21815.8 21845.5 973.6 990.7 990.9 993.1 997.2 984.2 985.9 986.7 987.0 990.2 1051.3 1053.7 1055.8 1058.2 1060.1 1041.3 1043.9 1049.5 1052.7 1055.1 777.6 778.5 778.9 781.1 782.6 19724.0 20060.6 20533.5 20578.2 20594.5 21390.0 21997.3 22237.3 22307.8 22398.9 17631.2 17863.0 17863.6 17913.5 18191.6 3738.8 3808.4 3808.4 3811.6 3813.2 129829.0 131794.0 133493.0 133918.0 135559.3 14116.9 14179.4 14494.6 14543.2 14589.6 30765.5 31690.2 31694.2 31697.2 31762.0 9001.0 9077.1 9134.1 9157.0 9200.1 4047.6 4130.7 4131.2 4135.7 4136.7 5669.9 5691.2 5719.2 5772.7 5776.9 996.4 1014.1 1023.9 1031.7 1034.8 665.7 668.3 676.1 680.2 693.8 11639.1 12191.6 12404.6 12755.7 13199.9 687.2 692.9 693.6 703.9 704.3 5668.1 5770.0 5794.0 5803.4 5886.3 114728.0 117734.4 118167.0 118684.4 123914.0 105261.0 106668.5 106969.5 109132.5 109966.5 92703.1 95070.5 96919.0 97408.0 98299.5 10713.6 10719.2 10823.5 10859.7 10939.7 43311.8 43329.8 44049.0 44603.4 45468.3 55324.2 55660.2 55941.0 57364.4 58387.4 13997.0 14203.3 14275.4 14389.4 14426.4 14865.3 14975.2 15070.5 15183.2 15200.8 16116.9 16338.2 16388.8 16592.8 16604.3 15785.1 15800.7 15874.0 16128.4 16142.6 16339.8 16420.7 16514.1 16614.5 16701.4 11650.7 11687.6 11687.9 11750.7 12198.4 12052.2 12244.3 12284.7 12627.7 12651.7 04:24:57 INFO - PID 21365 | -------- Summary: end -------- 04:24:57 INFO - PID 21365 | 04:24:58 INFO - TEST-INFO | 21365: exit 0 04:24:58 INFO - TEST-OK | dromaeo_css | took 1109441ms 04:24:58 INFO - TEST-START | kraken 04:24:58 INFO - Initialising browser for kraken test... 04:24:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44457/getInfo.html -profile /tmp/tmphozBjT/profile 04:24:58 INFO - TEST-INFO | started process 21744 (/builds/slave/test/build/application/firefox/firefox http://localhost:44457/getInfo.html) 04:25:03 INFO - TEST-INFO | 21744: exit 0 04:25:04 INFO - Browser initialized. 04:25:04 INFO - Running cycle 1/1 for kraken test... 04:25:04 INFO - Using env: {'DISPLAY': ':0', 04:25:04 INFO - 'HOME': '/home/cltbld', 04:25:04 INFO - 'JSGC_DISABLE_POISONING': '1', 04:25:04 INFO - 'LANG': 'en_US.UTF-8', 04:25:04 INFO - 'LANGUAGE': 'en_US:en', 04:25:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:25:04 INFO - 'LOGNAME': 'cltbld', 04:25:04 INFO - 'MAIL': '/var/mail/cltbld', 04:25:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:25:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:25:04 INFO - 'MOZ_CRASHREPORTER': '1', 04:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:25:04 INFO - 'MOZ_NO_REMOTE': '1', 04:25:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:25:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:04 INFO - 'NO_EM_RESTART': '1', 04:25:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:04 INFO - 'PWD': '/builds/slave/test', 04:25:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:25:04 INFO - 'RUST_BACKTRACE': 'full', 04:25:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:25:04 INFO - 'SHELL': '/bin/bash', 04:25:04 INFO - 'SHLVL': '1', 04:25:04 INFO - 'TERM': 'linux', 04:25:04 INFO - 'TMOUT': '86400', 04:25:04 INFO - 'USER': 'cltbld', 04:25:04 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971', 04:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:04 INFO - '_': '/tools/buildbot/bin/python'} 04:25:04 INFO - TEST-INFO | started process 21980 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphozBjT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:25:04 INFO - PID 21980 | 04:25:04 INFO - PID 21980 | (/builds/slave/test/build/application/firefox/firefox:22029): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:25:04 INFO - PID 21980 | 04:25:15 INFO - PID 21980 | RSS: Main: 151523328 04:25:15 INFO - PID 21980 | 04:25:17 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44457/tests/kraken/driver.html?testName=audio-beat-detection) 04:25:18 INFO - PID 21980 | RSS: Main: 157229056 04:25:18 INFO - PID 21980 | 04:25:21 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44457/tests/kraken/driver.html?testName=audio-dft) 04:25:22 INFO - PID 21980 | RSS: Main: 157900800 04:25:22 INFO - PID 21980 | 04:25:25 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44457/tests/kraken/driver.html?testName=audio-fft) 04:25:25 INFO - PID 21980 | RSS: Main: 154685440 04:25:25 INFO - PID 21980 | 04:25:27 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44457/tests/kraken/driver.html?testName=audio-oscillator) 04:25:28 INFO - PID 21980 | RSS: Main: 155435008 04:25:28 INFO - PID 21980 | 04:25:30 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44457/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:25:31 INFO - PID 21980 | RSS: Main: 155537408 04:25:31 INFO - PID 21980 | 04:25:34 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44457/tests/kraken/driver.html?testName=imaging-darkroom) 04:25:35 INFO - PID 21980 | RSS: Main: 157687808 04:25:35 INFO - PID 21980 | 04:25:38 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44457/tests/kraken/driver.html?testName=imaging-desaturate) 04:25:39 INFO - PID 21980 | RSS: Main: 157917184 04:25:39 INFO - PID 21980 | 04:25:42 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44457/tests/kraken/driver.html?testName=json-parse-financial) 04:25:42 INFO - PID 21980 | RSS: Main: 157753344 04:25:42 INFO - PID 21980 | 04:25:44 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44457/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:25:45 INFO - PID 21980 | RSS: Main: 157782016 04:25:45 INFO - PID 21980 | 04:25:46 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44457/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:25:47 INFO - PID 21980 | RSS: Main: 155140096 04:25:47 INFO - PID 21980 | 04:25:49 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44457/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:25:50 INFO - PID 21980 | RSS: Main: 154365952 04:25:50 INFO - PID 21980 | 04:25:52 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44457/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:25:53 INFO - PID 21980 | RSS: Main: 154824704 04:25:53 INFO - PID 21980 | 04:25:56 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44457/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:25:57 INFO - PID 21980 | RSS: Main: 154873856 04:25:57 INFO - PID 21980 | 04:25:58 INFO - PID 21980 | Cycle 1(1): loaded http://localhost:44457/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44457/tests/kraken/driver.html?testName=ai-astar) 04:25:59 INFO - PID 21980 | RSS: Main: 156155904 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | __start_tp_report 04:25:59 INFO - PID 21980 | _x_x_mozilla_page_load 04:25:59 INFO - PID 21980 | _x_x_mozilla_page_load_details 04:25:59 INFO - PID 21980 | |i|pagename|runs| 04:25:59 INFO - PID 21980 | |0;ai-astar;107;109;112;107;107;99;109;108;108;107 04:25:59 INFO - PID 21980 | |1;audio-beat-detection;116;115;118;116;115;121;118;116;115;120 04:25:59 INFO - PID 21980 | |2;audio-dft;166;162;163;164;162;164;162;176;170;165 04:25:59 INFO - PID 21980 | |3;audio-fft;74;74;74;77;78;75;81;79;75;75 04:25:59 INFO - PID 21980 | |4;audio-oscillator;80;81;80;79;79;132;80;81;80;79 04:25:59 INFO - PID 21980 | |5;imaging-gaussian-blur;115;115;114;115;114;114;115;115;114;115 04:25:59 INFO - PID 21980 | |6;imaging-darkroom;154;148;147;148;149;150;147;149;149;151 04:25:59 INFO - PID 21980 | |7;imaging-desaturate;94;94;94;94;94;93;94;96;94;94 04:25:59 INFO - PID 21980 | |8;json-parse-financial;54;54;54;56;56;56;55;53;53;54 04:25:59 INFO - PID 21980 | |9;json-stringify-tinderbox;38;38;38;38;39;39;39;38;39;38 04:25:59 INFO - PID 21980 | |10;stanford-crypto-aes;75;71;74;73;76;73;73;75;75;73 04:25:59 INFO - PID 21980 | |11;stanford-crypto-ccm;118;117;114;111;112;114;114;114;115;114 04:25:59 INFO - PID 21980 | |12;stanford-crypto-pbkdf2;177;166;166;164;168;165;183;168;176;168 04:25:59 INFO - PID 21980 | |13;stanford-crypto-sha256-iterative;66;65;64;63;67;67;65;68;65;70 04:25:59 INFO - PID 21980 | __end_tp_report 04:25:59 INFO - PID 21980 | __start_cc_report 04:25:59 INFO - PID 21980 | _x_x_mozilla_cycle_collect,775 04:25:59 INFO - PID 21980 | __end_cc_report 04:25:59 INFO - PID 21980 | __startTimestamp1506425159058__endTimestamp 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | ------- Summary: start ------- 04:25:59 INFO - PID 21980 | Number of tests: 14 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#0] ai-astar Cycles:10 Average:107.30 Median:108.50 stddev:3.30 (3.0%) stddev-sans-first:3.50 04:25:59 INFO - PID 21980 | Values: 107.0 109.0 112.0 107.0 107.0 99.0 109.0 108.0 108.0 107.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#1] audio-beat-detection Cycles:10 Average:117.00 Median:117.00 stddev:2.16 (1.8%) stddev-sans-first:2.26 04:25:59 INFO - PID 21980 | Values: 116.0 115.0 118.0 116.0 115.0 121.0 118.0 116.0 115.0 120.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#2] audio-dft Cycles:10 Average:165.40 Median:164.50 stddev:4.45 (2.7%) stddev-sans-first:4.72 04:25:59 INFO - PID 21980 | Values: 166.0 162.0 163.0 164.0 162.0 164.0 162.0 176.0 170.0 165.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#3] audio-fft Cycles:10 Average:76.20 Median:76.00 stddev:2.44 (3.2%) stddev-sans-first:2.46 04:25:59 INFO - PID 21980 | Values: 74.0 74.0 74.0 77.0 78.0 75.0 81.0 79.0 75.0 75.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:80.00 stddev:16.50 (20.6%) stddev-sans-first:17.39 04:25:59 INFO - PID 21980 | Values: 80.0 81.0 80.0 79.0 79.0 132.0 80.0 81.0 80.0 79.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:115.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 04:25:59 INFO - PID 21980 | Values: 115.0 115.0 114.0 115.0 114.0 114.0 115.0 115.0 114.0 115.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:2.10 (1.4%) stddev-sans-first:1.32 04:25:59 INFO - PID 21980 | Values: 154.0 148.0 147.0 148.0 149.0 150.0 147.0 149.0 149.0 151.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 04:25:59 INFO - PID 21980 | Values: 94.0 94.0 94.0 94.0 94.0 93.0 94.0 96.0 94.0 94.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.50 stddev:1.18 (2.2%) stddev-sans-first:1.24 04:25:59 INFO - PID 21980 | Values: 54.0 54.0 54.0 56.0 56.0 56.0 55.0 53.0 53.0 54.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.50 stddev:0.52 (1.3%) stddev-sans-first:0.53 04:25:59 INFO - PID 21980 | Values: 38.0 38.0 38.0 38.0 39.0 39.0 39.0 38.0 39.0 38.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#10] stanford-crypto-aes Cycles:10 Average:73.80 Median:74.50 stddev:1.48 (2.0%) stddev-sans-first:1.50 04:25:59 INFO - PID 21980 | Values: 75.0 71.0 74.0 73.0 76.0 73.0 73.0 75.0 75.0 73.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#11] stanford-crypto-ccm Cycles:10 Average:114.30 Median:114.00 stddev:2.06 (1.8%) stddev-sans-first:1.69 04:25:59 INFO - PID 21980 | Values: 118.0 117.0 114.0 111.0 112.0 114.0 114.0 114.0 115.0 114.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.10 Median:168.00 stddev:6.31 (3.8%) stddev-sans-first:6.18 04:25:59 INFO - PID 21980 | Values: 177.0 166.0 166.0 164.0 168.0 165.0 183.0 168.0 176.0 168.0 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:2.05 (3.1%) stddev-sans-first:2.18 04:25:59 INFO - PID 21980 | Values: 66.0 65.0 64.0 63.0 67.0 67.0 65.0 68.0 65.0 70.0 04:25:59 INFO - PID 21980 | -------- Summary: end -------- 04:25:59 INFO - PID 21980 | 04:25:59 INFO - PID 21980 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:25:59 INFO - TEST-INFO | 21980: exit 0 04:25:59 INFO - TEST-OK | kraken | took 61227ms 04:25:59 INFO - SUITE-END | took 1170s 04:26:00 INFO - Completed test suite (00:19:31) 04:26:00 INFO - javascript benchmark 04:26:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8591.768505927064, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3156.810591364518, 3175.9523607145884, 3188.250761071149, 3196.408664397086, 3218.372417378611, 6217.0, 6283.905741413871, 6386.329435409279, 6434.0, 6453.612783233003, 6288.811335659926, 6304.1489398931135, 6313.621182729028, 6318.684065796703, 6347.079673447347, 5822.854428639275, 5838.766449342022, 5878.5003274721485, 5920.437558431942, 5987.401259873999, 5903.203067585885, 5927.970360148193, 5963.075723262901, 5971.014782560872, 6005.969970150143, 806.8767908309449, 5770.2498675171955, 5912.7930522431725, 5984.640921544699, 5985.401459854023, 5431.348238211401, 5801.3038435388025, 5802.7388767505145, 5890.705464726747, 5932.79235226765, 5796.8840623187725, 5868.706564671749, 5913.497352725023, 5939.643621382731, 5966.940330596682, 6327.778527751507, 6420.646864422455, 6426.967865160691, 6475.643839588821, 6503.447206987365, 6852.862942741117, 6859.142607174103, 6945.756898508528, 6962.443004559623, 6975.895361569581, 6736.05695202672, 6816.7614133505085, 6909.412699920513, 6924.23833378328, 7010.228874823765, 6278.748850045993, 6321.873562528724, 6330.87338253237, 6341.58779679324, 6372.044193370985, 7073.540219885691, 7155.17715462718, 7166.0, 7174.92825071753, 7181.856362872713, 6726.899096513556, 6733.730650774012, 6739.831504212434, 6819.113515243086, 6832.145981752281, 6663.433608143362, 6751.797446076625, 6752.291009443938, 6849.589024658437, 6931.341522555349, 6764.492663050192, 6767.2894346093935, 6770.796876093626, 6778.76274330406, 6780.491463140284, 6646.767363142267, 6810.523263371616, 6869.828254293583, 6894.069300644349, 6905.620190889449, 5343.625946183807, 5399.676019438846, 5407.621466497308, 5408.783648654088, 5411.2965314508865, 4621.722696638212, 4670.602998745145, 4674.6026587740425, 4686.671932964659, 4689.390379250675, 5705.400932902068, 5807.738651760681, 5808.186853840466, 5812.128180772918, 5821.883562328814, 6684.231313398924, 6831.9658401707675, 6850.760223392257, 6921.861562768817, 6922.238553759083, 5871.32479764824, 5875.50058245054, 5889.646621202742, 5889.764409423575, 5902.2327097477055, 5833.38749431312, 5858.979737900789, 5906.675132867647, 5911.4975227105315, 5912.438318359749, 6441.066045423388, 6520.380563846427, 6540.574862633914, 6545.116409284781, 6626.072349871022, 6798.490113241428, 6819.897701534382, 6820.761273355409, 6824.829379265359, 6828.112345395067, 6463.741450342121, 6541.967290163519, 6566.770163044271, 6569.770058047945, 6575.605463672195, 6116.0, 6670.599764014175, 6713.060171575886, 6715.899261511179, 6748.426383757435, 6839.487038472234, 6860.965695171692, 6968.581885086911, 6972.6513674315065, 7008.299170082951, 6678.899816502853, 6750.86498270022, 6759.188897332351, 6765.492588055817, 6768.96615516939, 6662.533622646368, 6663.46692264608, 6670.266270710315, 6755.493337999768, 6761.560498567577, 6423.710933007932, 6532.771353002622, 6532.934670653232, 6536.379043990813, 6684.83287917806, 6638.800835974928, 6681.0, 6692.230393504809, 6715.529912906245, 6731.259561448334, 6318.336574659595, 6437.452816510649, 6489.870202595827, 6566.310537393505, 6570.507211959218], "value": 6184.482773874154, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.9397342186046, 885.6014793342997, 886.1581497577299, 887.3389324952911, 887.6937456577483, 39028.41457378138, 42870.571294287045, 42891.78554107225, 43160.784196079054, 43166.92082697934, 38239.80880095596, 38406.23187536248, 38569.0, 38639.22721545568, 38640.227195456144, 7296.197418283985, 7923.7226697065325, 8096.271335579797, 8166.1833816618455, 8167.95916020422, 7956.085050219241, 8071.556064416454, 8099.635516401776, 8135.0, 8135.2678258956685, 8090.0, 8102.5138491690395, 8135.389845761562, 8157.673693052271, 8177.959110204441, 8105.27052565269, 8108.472949258279, 8152.796180095485, 8153.714619988302, 8154.8776768348225, 7952.045754509496, 8036.879446808302, 8036.919630803675, 8045.115037345888, 8054.355651547862, 37270.44094338587, 37272.25455490902, 37494.25011499782, 37504.81247593772, 37562.81218593917, 3586.318599466093, 3631.4371272452813, 3631.9818400907825, 3633.5458067741533, 3640.908977275563, 30823.845880770677, 30859.22851928697, 31012.0, 31035.22411939697, 31232.21919452009, 5028.321176641143, 5058.949410505885, 5120.257562653394, 5141.871453213662, 5191.792328306863, 187059.0, 187628.0, 187930.0, 188277.0, 189104.99999999863, 41811.372829407585, 42530.72407827708, 47352.0, 48064.5193548067, 48070.5192948066, 50548.0, 50636.0, 50681.493185068415, 50686.746566266935, 50700.49299506958, 15579.922100389425, 15666.843331566539, 15698.843011569967, 15709.764353534707, 15743.606409839846, 12655.746885062246, 12672.80990785139, 12697.428615712315, 12746.80879786804, 12782.488700452064, 13835.30823458823, 14000.579982600539, 14006.159630422208, 14133.71732565343, 14176.929115354356, 5762.308522977186, 5780.797672081457, 5793.710314484259, 5802.970985145132, 5951.613145145551, 1005.1456262177092, 1006.4263369879245, 1017.862588550551, 1031.3863251365426, 1032.7211652853687, 23388.415289617555, 23506.177283794987, 23526.764732352458, 23547.411314716926, 23626.0, 1133.863936327646, 1134.4384529657873, 1141.7431078007382, 1148.1446322489633, 1156.3408856951626, 12251.632451026484, 12438.129330946927, 12556.309402982924, 12562.497500100077, 12576.937115314548, 230233.0, 232084.0, 232145.0, 232237.0, 232291.0, 53577.464225358024, 53819.19271210935, 53930.460695393325, 53989.73005134949, 54040.72979635155, 1439.2228196773835, 1468.5007097586872, 1470.0150898897764, 1477.9926100369644, 1480.6224412774889, 22566.661500077185, 22675.433114172276, 22722.318330450114, 22815.832495912862, 23305.650415244123, 37830.0, 38093.0, 38102.2379552413, 38104.0, 38147.61852381441, 142861.0, 143830.0, 143910.0, 144482.0, 145118.0, 32188.356232875678, 32976.34047319088, 33211.0, 33348.499772503914, 33462.330753385286, 41281.58718412898, 41316.586834131274, 41892.79053604713, 41894.58105419029, 42233.57766422419, 31134.53298200574, 31333.686663133078, 31337.686623133475, 31595.84202078975, 31597.526037109, 31263.84368078145, 31406.842965785025, 31671.0, 31752.523712144797, 32000.67999319977, 35481.29037419289, 35961.640383595826, 35997.64002359943, 36067.27865442624, 36176.09559761027, 30225.395492090476, 30359.0, 30587.2353191172, 30648.387032258783, 30670.386592268474, 22671.0, 22735.51675035586, 22831.543369132192, 22860.0, 23033.65449518225], "value": 17002.52056042718, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.6393835821791, 590.769599856056, 593.6230493636542, 611.5446482373271, 611.9020956646938, 21391.786082139173, 22091.889540552314, 22147.0033848477, 22163.224287149933, 22184.88907555464, 22634.092944571606, 23677.171299004538, 23753.40616484589, 23762.405939851513, 23907.76092239077, 6439.195100612424, 6483.189601299838, 6544.345565443466, 6578.046183303418, 6590.110335104748, 6368.713407896656, 6442.48460123189, 6469.4824414047, 6473.8705225895455, 6699.598024118569, 6671.766488172915, 6675.132232809728, 6682.498812589075, 6682.532222744409, 6687.465002799764, 6649.0691303217345, 6653.135092438, 6654.733810647568, 6657.667116644148, 6683.6992335344785, 6702.396784289389, 6957.686904089278, 6974.163100427931, 6982.1970473395195, 6983.89524157138, 29247.2688182795, 29746.40507189844, 29805.40389192204, 29879.253018674488, 29892.402151956838, 2118.9046492907855, 2139.465133716571, 2141.5716856628737, 2144.57108578285, 2149.0651566568595, 19427.514312142168, 19937.700934485994, 20310.0, 20624.69062964057, 20728.58542829135, 2555.7060937992183, 2570.820042597019, 2615.8168928175037, 2632.8551929643863, 2637.024301008639, 121651.39174304072, 126754.0, 126811.0, 133690.0, 141099.29450352682, 19908.0, 20387.898060509604, 21166.259180928595, 21520.246791362228, 21677.78322216789, 41235.0, 41242.38136427956, 41422.58577414187, 41557.376639350434, 41562.79218603948, 11212.775744485065, 11301.71745706364, 11431.657050288504, 11496.252743571642, 11532.538698451968, 9426.670066547638, 9452.149306562442, 9463.479508626953, 9527.857082143773, 9616.807663846685, 10552.683419497427, 10637.627683031056, 10897.400642964554, 10990.670279891616, 11061.0, 3811.3520701480816, 3840.731148819556, 4015.819288132041, 4072.450219220367, 4255.957440425556, 939.7979434421633, 942.330945029037, 970.7718686108759, 985.2807450560987, 986.4919566423298, 18719.157637906326, 18961.81038189628, 19134.71297930532, 19261.807381926283, 19309.22763089489, 1033.0857191385678, 1044.8955104489642, 1045.3309881675734, 1051.5530899367739, 1058.5289546152042, 10545.921195204335, 10606.045455909005, 10693.83959240612, 10699.358038517714, 10714.946425267979, 453387.0, 455549.0, 475963.0, 479910.0, 483852.0, 107791.46104269428, 109601.0, 109698.0, 109990.0, 110244.0, 1139.629620373382, 1139.6979800352913, 1170.6546568762064, 1170.9004734597654, 1173.284296579086, 16885.32458701638, 16891.831081689026, 17011.574710631838, 17337.479875603753, 17431.47705568835, 50727.0, 51055.744721276154, 51906.22140667969, 52228.0, 52298.477015230885, 65835.67082164559, 66521.66739166467, 66569.0, 66585.66707166433, 66851.0, 19161.808381916002, 19316.130774115478, 19349.0, 19477.220911163397, 19497.415077547696, 21439.46401339979, 21811.890940545196, 21821.23625673094, 21942.341729748132, 21992.120315187854, 17306.394276200273, 17649.735253970943, 17743.20155593027, 17777.46667599974, 17782.0, 15507.147106909228, 15586.454474093352, 15884.0, 16058.197090145679, 16233.0, 17814.46556603304, 18085.728714069035, 18249.726254106463, 18276.45170644883, 18295.26818927228, 18886.244550217838, 18892.716609251147, 18988.525286867385, 19075.618487630447, 19222.8077719221, 17529.211185496937, 17801.020943848212, 17812.732809008136, 17833.73249401234, 17852.285908563514], "value": 12565.8753970183, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1349.116328804633, 1360.4082224232454, 1388.2295326094027, 1395.246566853899, 1407.8885662685461, 14970.850291497081, 15690.137042462675, 15698.37206511741, 15703.293351799168, 15705.136217508032, 15066.548003559883, 16183.919080404581, 16186.109763963006, 16225.756613650805, 16228.83771162288, 2769.155407600686, 2838.3755573773738, 2845.6869744328105, 2847.2312475631566, 2849.9002534911283, 2715.7148499407576, 2727.618133461317, 2805.102367242483, 2805.382815780535, 2826.067397758735, 2797.049003338864, 2860.5709143628414, 2862.0269108503103, 2863.4988876946554, 2864.1980245531286, 2672.973270267292, 2769.2938300733385, 2773.265084752532, 2789.707080756532, 2790.9302267443272, 2700.6894207166033, 2704.0, 2728.0997270900557, 2731.5356389413846, 2732.1393760965234, 14685.55943321702, 14959.551213463614, 15023.924880375638, 15038.32327545263, 15056.0, 7679.424043196726, 7974.122846486882, 7975.322097621709, 7977.920220797776, 8000.8799868002025, 5081.085404627165, 5235.764390602394, 5374.220737992969, 5407.648502847303, 5413.485718856702, 1913.569446874456, 1959.0792327606002, 1990.790966948464, 2006.244536030973, 2022.8078332558384, 26730.5990410144, 26881.327966800793, 26901.192964211106, 26908.0, 26951.865240673866, 6679.632620205935, 6721.66391680414, 6752.088468056848, 6785.219699734594, 6902.447804175654, 16951.745723814154, 17041.573960650836, 17041.82958170427, 17049.14754262282, 17072.0, 7675.270849269311, 8318.875216871751, 8548.0, 8599.312055035582, 8616.396852220285, 6453.322401147906, 6475.870482590416, 6687.398134167851, 6702.061711360413, 7679.539227646247, 5183.559397451183, 5302.1781623848365, 5330.946690533045, 5334.59990500714, 5388.326459192601, 1564.7496400575853, 1567.6472793621572, 1585.2232406120918, 1596.2098761113323, 1615.0874755762957, 915.5879854065697, 927.5779520318239, 932.7528205025674, 940.2853831088286, 941.6139382852997, 7642.847143057108, 7764.495307805088, 7780.066392032993, 7862.488938218998, 7891.408144389194, 683.2108914204102, 686.2348481443255, 694.9478789090839, 702.8769965256059, 706.2725392845377, 4092.9590704093175, 4098.077932465231, 4123.587641235852, 4126.277901367249, 4166.395872598457, 21293.0, 21479.355619331443, 22439.10243590238, 22440.887795561244, 22454.438639034153, 20843.687344689843, 21383.57232855365, 21788.782112178993, 22070.779292207193, 22100.2264920727, 11165.609203677832, 11384.886151138382, 11500.539978400937, 11597.420128993517, 11618.186726929034, 8193.262606365455, 8325.375596830141, 8602.698905538276, 8692.348073894356, 8855.0, 18588.721169182743, 18724.625507490047, 18903.90548047251, 18962.24151033943, 19024.809751902303, 19142.904285478482, 19258.61482770365, 19311.613767724284, 19339.4198174055, 19344.32294869673, 11087.55649774, 11333.886661133283, 11335.0, 11355.659330220107, 11365.147613928822, 14154.929225353808, 14155.858441415454, 14312.57062288133, 14384.06503577264, 14463.13221206731, 11693.298402095901, 11820.290782553075, 11870.0, 11941.880581194077, 12142.0, 11179.496922638664, 11302.378369189444, 11453.942730286295, 11610.303381797119, 11643.068554515448, 10040.196784257389, 10121.684181056415, 10214.233932454947, 10233.641822536176, 10283.640072597424, 7225.1329840419485, 7284.708611655687, 7331.3768329692575, 7332.23011583776, 7356.742514011984, 6510.381513756186, 6547.377999090078, 6568.7372505099265, 6639.601623902581, 6723.562968407038], "value": 6643.016103248719, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.7777855522125, 519.1537793396762, 542.9755660995255, 550.9118541033434, 561.5563704673308, 6342.509510265084, 6398.3601639836, 6434.227892652876, 6440.5491615586925, 6481.0926470294135, 6165.907511387321, 6238.376162383759, 6271.843203919904, 6291.842703932404, 6302.968485157579, 5147.665401748884, 5169.121249387613, 5190.71451070191, 5194.844154675366, 5197.766100525467, 5114.948850511503, 5118.769655365518, 5120.487951204868, 5133.383993920735, 5183.196604526304, 4862.34358361622, 5056.924146137811, 5074.238864170386, 5081.415637201713, 5122.564582010534, 5019.874503137433, 5061.5950723942, 5103.209002604603, 5117.948820511784, 5129.410117836422, 4564.246899261618, 4573.702709323883, 4586.289125185602, 4597.885052873671, 4612.915964748281, 6062.696865156725, 6162.198914141133, 6165.198524191826, 6197.721102550351, 6224.657643829588, 5858.47273745365, 5884.293884733859, 5893.322267939199, 5900.232969713953, 5932.9703351483395, 6478.190226221723, 6506.93493065068, 6538.869222615569, 6556.475481961432, 6559.245686746038, 4988.950110498885, 4989.426215985171, 4991.176455884811, 4995.225740010304, 5006.098902197603, 6939.618320992344, 7079.221285658574, 7108.537945033608, 7114.14630244374, 7178.5692858428665, 5035.521625445577, 5098.745062746848, 5100.719460429637, 5136.563392111712, 5171.793488037366, 5203.869903252374, 5214.400343960493, 5227.634065615371, 5256.632035757537, 5259.868503287448, 5751.8562035949435, 5863.120531920246, 5870.119482077637, 5883.794067207627, 5891.4403131703275, 6729.899051514231, 6812.0, 6848.0, 6853.211880633691, 6871.5189936703955, 1682.966340673204, 1732.159902447312, 1798.1998010885031, 1801.4505575799508, 1806.5844855683267, 1149.4597539156582, 1235.3020543393134, 1260.3887114749339, 1263.1347526944073, 1267.752788206291, 5544.722763861791, 5626.0, 5647.644565304297, 5678.432156784289, 5755.165501002415, 6626.635535045619, 6638.900416493757, 6641.402273795382, 6668.499862510331, 6771.8984215236815, 5602.915956260659, 5620.57845661577, 5674.971625141848, 5687.60186786921, 5790.36306006339, 3509.7367697422796, 3516.837145155711, 3533.982330088333, 3575.9284814304087, 3581.8746343877838, 6366.713497892606, 6406.8718625627225, 6418.26189988148, 6493.603875166862, 6500.902486462707, 6767.240517465494, 6854.760083397057, 6867.141607299088, 6871.415929646036, 6894.551854129566, 6314.810555683337, 6357.682115894187, 6365.745370185141, 6413.35866413355, 6447.548671592944, 6570.277269500256, 6610.4050635443045, 6622.867542649024, 6628.038934354588, 6645.169353830771, 6423.96788016057, 6510.023496475566, 6567.408933196035, 6569.37590928861, 6623.966880165568, 6384.968075159594, 6420.26166990802, 6422.39440139965, 6489.9026514603265, 6490.253620833664, 5016.523430274118, 5089.9745501272255, 5091.338126043591, 5100.336956195671, 5112.795488180431, 6633.6351500666, 6667.766628167991, 6689.2976237496305, 6694.732210711712, 6695.397414232742, 6240.313565507881, 6284.528660350401, 6376.266729326187, 6495.772647957298, 6528.575642583407, 6404.295527492065, 6414.5189110815945, 6419.45434638061, 6446.387593178641, 6481.773137940149, 6061.0604606525485, 6250.9062364065485, 6327.0, 6339.0, 6419.197600299963, 6385.297617262005, 6442.130312407765, 6443.71003304843, 6460.321666225167, 6544.149260596092, 6220.112429731418, 6237.5321850860455, 6263.592866463665, 6291.245050593775, 6292.402221788922], "value": 5090.522874039321, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [916.7478943290595, 919.7792529792849, 922.8569571716378, 923.6536298887919, 932.0260327957284, 19007.0, 21846.781532184672, 23455.1790687326, 23510.0, 23581.938812753426, 19636.803631963678, 21363.359099227007, 21697.3490795276, 21815.781842181572, 21845.453863653416, 973.6056896956726, 990.747359423346, 990.8513722941544, 993.0813997052697, 997.2470784557648, 984.2077127912028, 985.8816941966974, 986.6793292180034, 987.0, 990.1633120013577, 1051.3061379489538, 1053.6680945502142, 1055.8152323343422, 1058.201058201061, 1060.083028180622, 1041.281515754131, 1043.9269251152423, 1049.4647729657854, 1052.6526246338767, 1055.076807793181, 777.6073083093058, 778.5484419037001, 778.8987431633908, 781.144646611959, 782.6360742254878, 19724.0, 20060.598788024305, 20533.4866628334, 20578.176872925065, 20594.485137871525, 21390.0, 21997.340079797632, 22237.332880013626, 22307.776922230732, 22398.888005559867, 17631.206595703225, 17863.0, 17863.553411164696, 17913.462596122135, 18191.636167276713, 3738.8317525711136, 3808.352580061396, 3808.352580061396, 3811.6378944000553, 3813.180166264239, 129829.0, 131794.0, 133493.0, 133918.0, 135559.32220338934, 14116.85883141176, 14179.432822687184, 14494.637634059232, 14543.20012399307, 14589.635259118395, 30765.538516922265, 31690.207744806565, 31694.207644809063, 31697.20756981094, 31762.0, 9000.954995225113, 9077.137671921157, 9134.132257435533, 9156.954215228881, 9200.125988031126, 4047.5547689754103, 4130.690198235145, 4131.173765246963, 4135.6898232632675, 4136.669066474675, 5669.858253543694, 5691.203231547586, 5719.170720245625, 5772.6536407815665, 5776.884462310731, 996.4419924842111, 1014.1177175857051, 1023.902729240721, 1031.7369070886875, 1034.813733527972, 665.6671664167916, 668.3083009085573, 676.1075380497795, 680.1803826389189, 693.7537174303151, 11639.06887449002, 12191.573294934635, 12404.62786116418, 12755.68110797219, 13199.868001320056, 687.1685260834347, 692.8752824491539, 693.5873155472486, 703.8873780195145, 704.3168126916883, 5668.149777533403, 5770.0, 5793.971030144822, 5803.419658034163, 5886.3230728465915, 114728.0, 117734.41132794453, 118167.0, 118684.40657796655, 123914.0, 105261.0, 106668.46665766621, 106969.46515267374, 109132.45433772939, 109966.45016775026, 92703.07296926944, 95070.52464737633, 96919.0, 97408.0, 98299.50850245703, 10713.625023124154, 10719.24965252456, 10823.458827058774, 10859.733024491097, 10939.726506837074, 43311.783441083855, 43329.783351083046, 44049.0, 44603.363529065886, 45468.31797522974, 55324.170137448775, 55660.16509752276, 55941.0, 57364.42635573591, 58387.416125839896, 13997.0, 14203.28983550839, 14275.357608907416, 14389.352479138674, 14426.42294308216, 14865.256737162883, 14975.176365300013, 15070.472533461276, 15183.24083795828, 15200.771988420403, 16116.919415402848, 16338.183090845172, 16388.75416868772, 16592.751108733137, 16604.335826566803, 15785.131817749674, 15800.683986320439, 15874.0, 16128.435504757746, 16142.596435089217, 16339.836601633831, 16420.671586568442, 16514.091724955244, 16614.501564953072, 16701.415450459175, 11650.70873228176, 11687.590934317259, 11687.941560292484, 11750.705789214506, 12198.390080496118, 12052.216605920587, 12244.265344079384, 12284.692882678004, 12627.74744505123, 12651.683707907376], "value": 9001.860766462038, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1425.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 109.0, 112.0, 107.0, 107.0, 99.0, 109.0, 108.0, 108.0, 107.0], "value": 107.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 118.0, 116.0, 115.0, 121.0, 118.0, 116.0, 115.0, 120.0], "value": 117.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 162.0, 163.0, 164.0, 162.0, 164.0, 162.0, 176.0, 170.0, 165.0], "value": 165.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 77.0, 78.0, 75.0, 81.0, 79.0, 75.0, 75.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 80.0, 79.0, 79.0, 132.0, 80.0, 81.0, 80.0, 79.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 115.0, 114.0, 114.0, 115.0, 115.0, 114.0, 115.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 148.0, 147.0, 148.0, 149.0, 150.0, 147.0, 149.0, 149.0, 151.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 96.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 56.0, 56.0, 56.0, 55.0, 53.0, 53.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 39.0, 39.0, 39.0, 38.0, 39.0, 38.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 71.0, 74.0, 73.0, 76.0, 73.0, 73.0, 75.0, 75.0, 73.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 114.0, 111.0, 112.0, 114.0, 114.0, 114.0, 115.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 166.0, 166.0, 164.0, 168.0, 165.0, 183.0, 168.0, 176.0, 168.0], "value": 170.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 63.0, 67.0, 67.0, 65.0, 68.0, 65.0, 70.0], "value": 66.0, "unit": "score"}]}]} 04:26:00 INFO - Return code: 0 04:26:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:26:00 INFO - # TBPL SUCCESS # 04:26:00 INFO - Running post-action listener: _package_coverage_data 04:26:00 INFO - Running post-action listener: _resource_record_post_action 04:26:00 INFO - [mozharness: 2017-09-26 11:26:00.355133Z] Finished run-tests step (success) 04:26:00 INFO - Running post-run listener: _resource_record_post_run 04:26:00 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 346550272; Read time: 20; Write time: 302564 04:26:00 INFO - TinderboxPrint: CPU usage
13.1% 04:26:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 04:26:00 INFO - TinderboxPrint: I/O write bytes / time
346,550,272 / 302,564 04:26:00 INFO - TinderboxPrint: CPU idle
8,234.2 (86.9%) 04:26:00 INFO - TinderboxPrint: CPU user
1,193.7 (12.6%) 04:26:00 INFO - TinderboxPrint: Swap in / out
0 / 0 04:26:00 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10715136; Read time: 0; Write time: 972 04:26:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:26:00 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 335835136; Read time: 0; Write time: 301592 04:26:00 INFO - Running post-run listener: _upload_blobber_files 04:26:00 INFO - Blob upload gear active. 04:26:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:26:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:26:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:26:01 INFO - (blobuploader) - INFO - Open directory for files ... 04:26:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:26:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:26:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:02 INFO - (blobuploader) - INFO - Done attempting. 04:26:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:26:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:26:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:03 INFO - (blobuploader) - INFO - Done attempting. 04:26:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:26:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:26:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:04 INFO - (blobuploader) - INFO - Done attempting. 04:26:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:26:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:26:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:26:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:26:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:26:12 INFO - (blobuploader) - INFO - Done attempting. 04:26:12 INFO - (blobuploader) - INFO - Iteration through files over. 04:26:12 INFO - Return code: 0 04:26:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:26:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:26:12 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d943d14b5d9d82cf921c88f0a6ada14aa31fd043c4d49497ba5a8b62a5756a92b0438bc302a5228b0b1158c5278e3f59551c7b68e2976c1791555c6cc01d9367", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9db0bf7818fadabf78c2176070393f98df26cfef125552462162f3ff9ab5771ead106714ad953a092e79acb334275375163f2ba708671b21ae565f63ef92bda2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b3c4eef24c630dc543735de8b54e418bc7ce17f9c42719cb35ca18aebfad06f8e0078e8789d6ae9a8c3e44368b03956c2abf6c675792cc63074e5836fa84e210", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61f0a7260c869876c2f78bee90d84180ae553399d94ed2b888cc02b43db445856c4a4e4653214081f6f1636fc386c30177674b48479affd8fc822f3b74e0d086"} 04:26:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:26:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:26:12 INFO - Contents: 04:26:12 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d943d14b5d9d82cf921c88f0a6ada14aa31fd043c4d49497ba5a8b62a5756a92b0438bc302a5228b0b1158c5278e3f59551c7b68e2976c1791555c6cc01d9367", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9db0bf7818fadabf78c2176070393f98df26cfef125552462162f3ff9ab5771ead106714ad953a092e79acb334275375163f2ba708671b21ae565f63ef92bda2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b3c4eef24c630dc543735de8b54e418bc7ce17f9c42719cb35ca18aebfad06f8e0078e8789d6ae9a8c3e44368b03956c2abf6c675792cc63074e5836fa84e210", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61f0a7260c869876c2f78bee90d84180ae553399d94ed2b888cc02b43db445856c4a4e4653214081f6f1636fc386c30177674b48479affd8fc822f3b74e0d086"} 04:26:12 INFO - Running post-run listener: copy_logs_to_upload_dir 04:26:12 INFO - Copying logs to upload dir... 04:26:12 INFO - mkdir: /builds/slave/test/build/upload/logs 04:26:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.278510 ========= master_lag: 0.89 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-26 04:26:12.427543) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 04:26:12.431184) ========= 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=14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d943d14b5d9d82cf921c88f0a6ada14aa31fd043c4d49497ba5a8b62a5756a92b0438bc302a5228b0b1158c5278e3f59551c7b68e2976c1791555c6cc01d9367", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9db0bf7818fadabf78c2176070393f98df26cfef125552462162f3ff9ab5771ead106714ad953a092e79acb334275375163f2ba708671b21ae565f63ef92bda2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b3c4eef24c630dc543735de8b54e418bc7ce17f9c42719cb35ca18aebfad06f8e0078e8789d6ae9a8c3e44368b03956c2abf6c675792cc63074e5836fa84e210", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61f0a7260c869876c2f78bee90d84180ae553399d94ed2b888cc02b43db445856c4a4e4653214081f6f1636fc386c30177674b48479affd8fc822f3b74e0d086"} build_url:https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011427 build_url: 'https://queue.taskcluster.net/v1/task/NZTur4OUTTCzffNcI7ihNw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d943d14b5d9d82cf921c88f0a6ada14aa31fd043c4d49497ba5a8b62a5756a92b0438bc302a5228b0b1158c5278e3f59551c7b68e2976c1791555c6cc01d9367", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9db0bf7818fadabf78c2176070393f98df26cfef125552462162f3ff9ab5771ead106714ad953a092e79acb334275375163f2ba708671b21ae565f63ef92bda2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b3c4eef24c630dc543735de8b54e418bc7ce17f9c42719cb35ca18aebfad06f8e0078e8789d6ae9a8c3e44368b03956c2abf6c675792cc63074e5836fa84e210", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61f0a7260c869876c2f78bee90d84180ae553399d94ed2b888cc02b43db445856c4a4e4653214081f6f1636fc386c30177674b48479affd8fc822f3b74e0d086"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 04:26:12.461031) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 04:26:12.461397) ========= 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=14ef55db36f7623abb31b9cf000001d5-1506423815.793602-370687971 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004499 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 04:26:12.542665) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 04:26:12.542990) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 04:26:12.543350) ========= ========= Total master_lag: 1.05 =========