builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-045 starttime: 1501290140.31 results: success (0) revision: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:20.309001) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:20.309571) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:20.309874) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:20.336195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:20.336501) ========= 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=4300f881736b422476174874000001bc-1501285988.489652-1908195001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-28 18:02:20-- 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% 228M=0s 2017-07-28 18:02:20 (228 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078103 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:20.428654) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:20.428979) ========= 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=4300f881736b422476174874000001bc-1501285988.489652-1908195001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016185 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:20.481528) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-28 18:02:20.481834) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ec329722b2f8bad3b1b9d0829e8d89764a879fd1 --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 ec329722b2f8bad3b1b9d0829e8d89764a879fd1 --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=4300f881736b422476174874000001bc-1501285988.489652-1908195001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-28 18:02:20,569 truncating revision to first 12 chars 2017-07-28 18:02:20,569 Setting DEBUG logging. 2017-07-28 18:02:20,569 attempt 1/10 2017-07-28 18:02:20,569 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ec329722b2f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-28 18:02:21,404 unpacking tar archive at: mozilla-central-ec329722b2f8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.173437 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-28 18:02:21.667772) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:21.668121) ========= script_repo_revision: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:21.668500) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:21.668763) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:21.676128) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-07-28 18:02:21.676416) ========= /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=4300f881736b422476174874000001bc-1501285988.489652-1908195001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:02:21 INFO - MultiFileLogger online at 20170728 18:02:21 in /builds/slave/test 18:02:21 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 18:02:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:02:21 INFO - {'append_to_log': False, 18:02:21 INFO - 'base_work_dir': '/builds/slave/test', 18:02:21 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:02:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:02:21 INFO - 'branch': 'Firefox-Non-PGO', 18:02:21 INFO - 'buildbot_json_path': 'buildprops.json', 18:02:21 INFO - 'code_coverage': False, 18:02:21 INFO - 'config_files': ('talos/linux_config.py',), 18:02:21 INFO - 'default_actions': ('clobber', 18:02:21 INFO - 'read-buildbot-config', 18:02:21 INFO - 'download-and-extract', 18:02:21 INFO - 'populate-webroot', 18:02:21 INFO - 'create-virtualenv', 18:02:21 INFO - 'install', 18:02:21 INFO - 'setup-mitmproxy', 18:02:21 INFO - 'run-tests'), 18:02:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:02:21 INFO - 'disable_ccov_upload': False, 18:02:21 INFO - 'download_minidump_stackwalk': True, 18:02:21 INFO - 'download_symbols': 'ondemand', 18:02:21 INFO - 'enable_stylo': False, 18:02:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:02:21 INFO - 'tooltool.py': '/tools/tooltool.py', 18:02:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:02:21 INFO - '/tools/misc-python/virtualenv.py')}, 18:02:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:02:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:02:21 INFO - 'gecko_profile': False, 18:02:21 INFO - 'gecko_profile_interval': 0, 18:02:21 INFO - 'installer_path': 'installer.exe', 18:02:21 INFO - 'log_level': 'info', 18:02:21 INFO - 'log_name': 'talos', 18:02:21 INFO - 'log_to_console': True, 18:02:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:02:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:02:21 INFO - 'opt_config_files': (), 18:02:21 INFO - 'pip_index': False, 18:02:21 INFO - 'suite': 'dromaeojs-e10s', 18:02:21 INFO - 'system_bits': '32', 18:02:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:02:21 INFO - 'title': 'talos-linux64-ix-045', 18:02:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:02:21 INFO - 'use_talos_json': True, 18:02:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:02:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:02:21 INFO - 'work_dir': 'build'} 18:02:21 INFO - [mozharness: 2017-07-29 01:02:21.863009Z] Running clobber step. 18:02:21 INFO - Running pre-action listener: _resource_record_pre_action 18:02:21 INFO - Running main action method: clobber 18:02:21 INFO - rmtree: /builds/slave/test/build 18:02:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:02:23 INFO - Running post-action listener: _resource_record_post_action 18:02:23 INFO - [mozharness: 2017-07-29 01:02:23.415191Z] Finished clobber step (success) 18:02:23 INFO - [mozharness: 2017-07-29 01:02:23.415293Z] Running read-buildbot-config step. 18:02:23 INFO - Running pre-action listener: _resource_record_pre_action 18:02:23 INFO - Running main action method: read_buildbot_config 18:02:23 INFO - Using buildbot properties: 18:02:23 INFO - { 18:02:23 INFO - "project": "", 18:02:23 INFO - "product": "firefox", 18:02:23 INFO - "who": "kwierso@gmail.com", 18:02:23 INFO - "installer_path": "public/build/target.tar.bz2", 18:02:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:02:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:02:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:02:23 INFO - "stage_platform": "linux64", 18:02:23 INFO - "basedir": "/builds/slave/test", 18:02:23 INFO - "buildnumber": 1044, 18:02:23 INFO - "platform": "ubuntu64_hw", 18:02:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:02:23 INFO - "slavebuilddir": "test", 18:02:23 INFO - "taskId": "IZQ4spXuRcu06m8lug7zbg", 18:02:23 INFO - "branch": "mozilla-central", 18:02:23 INFO - "script_repo_revision": "production", 18:02:23 INFO - "revision": "ec329722b2f8bad3b1b9d0829e8d89764a879fd1", 18:02:23 INFO - "slavename": "talos-linux64-ix-045", 18:02:23 INFO - "repo_path": "mozilla-central" 18:02:23 INFO - } 18:02:23 INFO - Finding installer, test and symbols from parent task. 18:02:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IZQ4spXuRcu06m8lug7zbg'}, attempt #1 18:02:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IZQ4spXuRcu06m8lug7zbg'}, attempt #1 18:02:24 INFO - Task dependencies: JZh5JewrR7CLMNL_lRmk9w 18:02:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w'}, attempt #1 18:02:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/test_packages.json 18:02:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip 18:02:25 INFO - Running post-action listener: _resource_record_post_action 18:02:25 INFO - [mozharness: 2017-07-29 01:02:25.004714Z] Finished read-buildbot-config step (success) 18:02:25 INFO - [mozharness: 2017-07-29 01:02:25.004936Z] Running download-and-extract step. 18:02:25 INFO - Running pre-action listener: _resource_record_pre_action 18:02:25 INFO - Running main action method: download_and_extract 18:02:25 INFO - mkdir: /builds/slave/test/build/tests 18:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:25 INFO - trying https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.test_packages.json 18:02:25 INFO - Downloading https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:02:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:02:25 INFO - Downloaded 989 bytes. 18:02:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:02:25 INFO - Using the following test package requirements: 18:02:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:02:25 INFO - u'common': [u'target.common.tests.zip'], 18:02:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:02:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:02:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:02:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:02:25 INFO - u'mozbase': [u'target.common.tests.zip'], 18:02:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:02:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:02:25 INFO - u'web-platform': [u'target.common.tests.zip', 18:02:25 INFO - u'target.web-platform.tests.tar.gz'], 18:02:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:02:25 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:02:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip 18:02:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:02:25 INFO - Fetch https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip into memory 18:02:27 INFO - Content-Length response header: 72564963 18:02:27 INFO - Bytes received: 72564963 18:02:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:02:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip 18:02:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:02:38 INFO - Fetch https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip into memory 18:02:39 INFO - Content-Length response header: 72564963 18:02:39 INFO - Bytes received: 72564963 18:02:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.talos.tests.zip 18:02:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:02:54 INFO - Fetch https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.talos.tests.zip into memory 18:02:55 INFO - Content-Length response header: 14496686 18:02:55 INFO - Bytes received: 14496686 18:02:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:55 INFO - trying https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:55 INFO - Downloading https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:02:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:02:56 INFO - Downloaded 62227675 bytes. 18:02:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:56 INFO - mkdir: /builds/slave/test/properties 18:02:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:02:56 INFO - Writing to file /builds/slave/test/properties/build_url 18:02:56 INFO - Contents: 18:02:56 INFO - build_url:https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:56 INFO - Running post-action listener: _resource_record_post_action 18:02:56 INFO - Running post-action listener: set_extra_try_arguments 18:02:56 INFO - [mozharness: 2017-07-29 01:02:56.669715Z] Finished download-and-extract step (success) 18:02:56 INFO - [mozharness: 2017-07-29 01:02:56.669813Z] Running populate-webroot step. 18:02:56 INFO - Running pre-action listener: _resource_record_pre_action 18:02:56 INFO - Running main action method: populate_webroot 18:02:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:02:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:02:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:02:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:02:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:02:56 INFO - 'tests': ['damp', 'tps']}, 18:02:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:02:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:02:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:02:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:02:56 INFO - 'other-e10s': {'tests': ['a11yr', 18:02:56 INFO - 'ts_paint', 18:02:56 INFO - 'tpaint', 18:02:56 INFO - 'sessionrestore', 18:02:56 INFO - 'sessionrestore_many_windows', 18:02:56 INFO - 'sessionrestore_no_auto_restore', 18:02:56 INFO - 'tabpaint']}, 18:02:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:02:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:02:56 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:02:56 INFO - 'talos_options': ['--mitmproxy', 18:02:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:02:56 INFO - '--firstNonBlankPaint'], 18:02:56 INFO - 'tests': ['quantum_pageload_google', 18:02:56 INFO - 'quantum_pageload_youtube', 18:02:56 INFO - 'quantum_pageload_amazon', 18:02:56 INFO - 'quantum_pageload_facebook']}, 18:02:56 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:02:56 INFO - 'talos_options': ['--stylo', 18:02:56 INFO - '--mitmproxy', 18:02:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:02:56 INFO - '--firstNonBlankPaint'], 18:02:56 INFO - 'tests': ['quantum_pageload_google', 18:02:56 INFO - 'quantum_pageload_youtube', 18:02:56 INFO - 'quantum_pageload_amazon', 18:02:56 INFO - 'quantum_pageload_facebook']}, 18:02:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:02:56 INFO - 'tsvgr_opacity', 18:02:56 INFO - 'tart', 18:02:56 INFO - 'tscrollx', 18:02:56 INFO - 'cart', 18:02:56 INFO - 'tsvg_static']}, 18:02:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:02:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:02:56 INFO - 'talos_options': ['--stylo', 18:02:56 INFO - '--stylo-threads=1', 18:02:56 INFO - '--mitmproxy', 18:02:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:02:56 INFO - '--firstNonBlankPaint'], 18:02:56 INFO - 'tests': ['tp6_google', 18:02:56 INFO - 'tp6_youtube', 18:02:56 INFO - 'tp6_amazon', 18:02:56 INFO - 'tp6_facebook']}, 18:02:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:02:56 INFO - 'talos_options': ['--xperf_path', 18:02:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:02:56 INFO - 'tests': ['tp5n']}}} 18:02:56 INFO - Running post-action listener: _resource_record_post_action 18:02:56 INFO - [mozharness: 2017-07-29 01:02:56.678535Z] Finished populate-webroot step (success) 18:02:56 INFO - [mozharness: 2017-07-29 01:02:56.678626Z] Running create-virtualenv step. 18:02:56 INFO - Running pre-action listener: _resource_record_pre_action 18:02:56 INFO - Running main action method: create_virtualenv 18:02:56 INFO - Creating virtualenv /builds/slave/test/build/venv 18:02:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:02:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:02:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:02:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:02:56 INFO - Using real prefix '/usr' 18:02:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:02:57 INFO - Installing distribute.............................................................................................................................................................................................done. 18:02:59 INFO - Installing pip.................done. 18:02:59 INFO - Return code: 0 18:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:02:59 INFO - Reading from file tmpfile_stdout 18:02:59 INFO - Output received: 18:02:59 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:02:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1929a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1accd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9eb0>, '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 0x1acadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acb240>, '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': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', '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 18:02:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:02:59 INFO - Using env: {'DISPLAY': ':0', 18:02:59 INFO - 'HOME': '/home/cltbld', 18:02:59 INFO - 'LANG': 'en_US.UTF-8', 18:02:59 INFO - 'LANGUAGE': 'en_US:en', 18:02:59 INFO - 'LOGNAME': 'cltbld', 18:02:59 INFO - 'MAIL': '/var/mail/cltbld', 18:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:59 INFO - 'MOZ_NO_REMOTE': '1', 18:02:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:59 INFO - 'NO_EM_RESTART': '1', 18:02:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:59 INFO - 'PWD': '/builds/slave/test', 18:02:59 INFO - 'SHELL': '/bin/bash', 18:02:59 INFO - 'SHLVL': '1', 18:02:59 INFO - 'TERM': 'linux', 18:02:59 INFO - 'TMOUT': '86400', 18:02:59 INFO - 'USER': 'cltbld', 18:02:59 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', 18:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:59 INFO - '_': '/tools/buildbot/bin/python'} 18:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:02:59 INFO - Cleaning up... 18:02:59 INFO - Return code: 0 18:02:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x175c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1929a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1accd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9eb0>, '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 0x1acadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acb240>, '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': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', '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 18:02:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:02:59 INFO - Using env: {'DISPLAY': ':0', 18:02:59 INFO - 'HOME': '/home/cltbld', 18:02:59 INFO - 'LANG': 'en_US.UTF-8', 18:02:59 INFO - 'LANGUAGE': 'en_US:en', 18:02:59 INFO - 'LOGNAME': 'cltbld', 18:02:59 INFO - 'MAIL': '/var/mail/cltbld', 18:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:59 INFO - 'MOZ_NO_REMOTE': '1', 18:02:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:59 INFO - 'NO_EM_RESTART': '1', 18:02:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:59 INFO - 'PWD': '/builds/slave/test', 18:02:59 INFO - 'SHELL': '/bin/bash', 18:02:59 INFO - 'SHLVL': '1', 18:02:59 INFO - 'TERM': 'linux', 18:02:59 INFO - 'TMOUT': '86400', 18:02:59 INFO - 'USER': 'cltbld', 18:02:59 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', 18:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:59 INFO - '_': '/tools/buildbot/bin/python'} 18:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:59 INFO - Downloading/unpacking psutil>=3.1.1 18:02: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 18:02: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 18:02: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 18:02: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 18:03:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:03:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:03:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:03:01 INFO - Installing collected packages: psutil 18:03:01 INFO - Running setup.py install for psutil 18:03:01 INFO - building 'psutil._psutil_linux' extension 18:03:01 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 18:03:01 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 18:03:01 INFO - building 'psutil._psutil_posix' extension 18:03:01 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 18:03:01 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 18:03:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:03:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:03:01 INFO - Successfully installed psutil 18:03:01 INFO - Cleaning up... 18:03:01 INFO - Return code: 0 18:03:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:03: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')]} 18:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03: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')]} 18:03:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x175c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1929a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1accd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9eb0>, '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 0x1acadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acb240>, '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': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', '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 18:03:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:03:01 INFO - Using env: {'DISPLAY': ':0', 18:03:01 INFO - 'HOME': '/home/cltbld', 18:03:01 INFO - 'LANG': 'en_US.UTF-8', 18:03:01 INFO - 'LANGUAGE': 'en_US:en', 18:03:01 INFO - 'LOGNAME': 'cltbld', 18:03:01 INFO - 'MAIL': '/var/mail/cltbld', 18:03:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:01 INFO - 'MOZ_NO_REMOTE': '1', 18:03:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:01 INFO - 'NO_EM_RESTART': '1', 18:03:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:01 INFO - 'PWD': '/builds/slave/test', 18:03:01 INFO - 'SHELL': '/bin/bash', 18:03:01 INFO - 'SHLVL': '1', 18:03:01 INFO - 'TERM': 'linux', 18:03:01 INFO - 'TMOUT': '86400', 18:03:01 INFO - 'USER': 'cltbld', 18:03:01 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', 18:03:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:01 INFO - '_': '/tools/buildbot/bin/python'} 18:03:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:03:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:03:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:03:02 INFO - Installing collected packages: mozsystemmonitor 18:03:02 INFO - Running setup.py install for mozsystemmonitor 18:03:03 INFO - Successfully installed mozsystemmonitor 18:03:03 INFO - Cleaning up... 18:03:03 INFO - Return code: 0 18:03:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:03:03 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')]} 18:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:03 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')]} 18:03:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:03 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 0x175c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1929a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1accd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9eb0>, '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 0x1acadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acb240>, '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': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', '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 18:03:03 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 18:03:03 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 18:03:03 INFO - Using env: {'DISPLAY': ':0', 18:03:03 INFO - 'HOME': '/home/cltbld', 18:03:03 INFO - 'LANG': 'en_US.UTF-8', 18:03:03 INFO - 'LANGUAGE': 'en_US:en', 18:03:03 INFO - 'LOGNAME': 'cltbld', 18:03:03 INFO - 'MAIL': '/var/mail/cltbld', 18:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:03 INFO - 'MOZ_NO_REMOTE': '1', 18:03:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:03 INFO - 'NO_EM_RESTART': '1', 18:03:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:03 INFO - 'PWD': '/builds/slave/test', 18:03:03 INFO - 'SHELL': '/bin/bash', 18:03:03 INFO - 'SHLVL': '1', 18:03:03 INFO - 'TERM': 'linux', 18:03:03 INFO - 'TMOUT': '86400', 18:03:03 INFO - 'USER': 'cltbld', 18:03:03 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', 18:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:03 INFO - '_': '/tools/buildbot/bin/python'} 18:03:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:03 INFO - Downloading/unpacking jsonschema==2.5.1 18:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:03:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:03:04 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 18:03:04 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 18:03:04 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 18:03:04 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 18:03:04 INFO - Downloading functools32-3.2.3-2.tar.gz 18:03:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:03:04 INFO - warning: no files found matching '*.txt' 18:03:04 INFO - no previously-included directories found matching 'build' 18:03:04 INFO - no previously-included directories found matching 'dist' 18:03:04 INFO - no previously-included directories found matching '.git*' 18:03:04 INFO - Installing collected packages: jsonschema, functools32 18:03:04 INFO - Running setup.py install for functools32 18:03:04 INFO - warning: no files found matching '*.txt' 18:03:04 INFO - no previously-included directories found matching 'build' 18:03:04 INFO - no previously-included directories found matching 'dist' 18:03:04 INFO - no previously-included directories found matching '.git*' 18:03:04 INFO - Successfully installed jsonschema functools32 18:03:04 INFO - Cleaning up... 18:03:04 INFO - Return code: 0 18:03:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:03: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')]} 18:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03: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')]} 18:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:04 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 0x175c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1929a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1accd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9eb0>, '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 0x1acadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acb240>, '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': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', '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 18:03:04 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 18:03:04 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 18:03:04 INFO - Using env: {'DISPLAY': ':0', 18:03:04 INFO - 'HOME': '/home/cltbld', 18:03:04 INFO - 'LANG': 'en_US.UTF-8', 18:03:04 INFO - 'LANGUAGE': 'en_US:en', 18:03:04 INFO - 'LOGNAME': 'cltbld', 18:03:04 INFO - 'MAIL': '/var/mail/cltbld', 18:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:04 INFO - 'MOZ_NO_REMOTE': '1', 18:03:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:04 INFO - 'NO_EM_RESTART': '1', 18:03:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:04 INFO - 'PWD': '/builds/slave/test', 18:03:04 INFO - 'SHELL': '/bin/bash', 18:03:04 INFO - 'SHLVL': '1', 18:03:04 INFO - 'TERM': 'linux', 18:03:04 INFO - 'TMOUT': '86400', 18:03:04 INFO - 'USER': 'cltbld', 18:03:04 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', 18:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:04 INFO - '_': '/tools/buildbot/bin/python'} 18:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:03:04 INFO - Cleaning up... 18:03:04 INFO - Return code: 0 18:03:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:03: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')]} 18:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03: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')]} 18:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:04 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 0x175c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1929a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1accd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9eb0>, '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 0x1acadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acb240>, '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': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', '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 18:03:04 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 18:03:04 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 18:03:04 INFO - Using env: {'DISPLAY': ':0', 18:03:04 INFO - 'HOME': '/home/cltbld', 18:03:04 INFO - 'LANG': 'en_US.UTF-8', 18:03:04 INFO - 'LANGUAGE': 'en_US:en', 18:03:04 INFO - 'LOGNAME': 'cltbld', 18:03:04 INFO - 'MAIL': '/var/mail/cltbld', 18:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:04 INFO - 'MOZ_NO_REMOTE': '1', 18:03:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:04 INFO - 'NO_EM_RESTART': '1', 18:03:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:04 INFO - 'PWD': '/builds/slave/test', 18:03:04 INFO - 'SHELL': '/bin/bash', 18:03:04 INFO - 'SHLVL': '1', 18:03:04 INFO - 'TERM': 'linux', 18:03:04 INFO - 'TMOUT': '86400', 18:03:04 INFO - 'USER': 'cltbld', 18:03:04 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', 18:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:04 INFO - '_': '/tools/buildbot/bin/python'} 18:03:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:05 INFO - Downloading/unpacking blobuploader==1.2.4 18:03:05 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 18:03:05 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 18:03:05 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 18:03:05 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 18:03:06 INFO - Downloading blobuploader-1.2.4.tar.gz 18:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:03:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:03: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 18:03: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 18:03: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 18:03: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 18:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:03:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:03: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 18:03: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 18:03: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 18:03: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 18:03:06 INFO - Downloading docopt-0.6.1.tar.gz 18:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:03:06 INFO - Installing collected packages: blobuploader, requests, docopt 18:03:06 INFO - Running setup.py install for blobuploader 18:03:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:03:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:03:06 INFO - Running setup.py install for requests 18:03:07 INFO - Running setup.py install for docopt 18:03:07 INFO - Successfully installed blobuploader requests docopt 18:03:07 INFO - Cleaning up... 18:03:07 INFO - Return code: 0 18:03:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03: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')]} 18:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03: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')]} 18:03:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:07 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 0x175c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1929a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1accd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9eb0>, '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 0x1acadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acb240>, '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': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', '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 18:03:07 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 18:03:07 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 18:03:07 INFO - Using env: {'DISPLAY': ':0', 18:03:07 INFO - 'HOME': '/home/cltbld', 18:03:07 INFO - 'LANG': 'en_US.UTF-8', 18:03:07 INFO - 'LANGUAGE': 'en_US:en', 18:03:07 INFO - 'LOGNAME': 'cltbld', 18:03:07 INFO - 'MAIL': '/var/mail/cltbld', 18:03:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:07 INFO - 'MOZ_NO_REMOTE': '1', 18:03:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:07 INFO - 'NO_EM_RESTART': '1', 18:03:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:07 INFO - 'PWD': '/builds/slave/test', 18:03:07 INFO - 'SHELL': '/bin/bash', 18:03:07 INFO - 'SHLVL': '1', 18:03:07 INFO - 'TERM': 'linux', 18:03:07 INFO - 'TMOUT': '86400', 18:03:07 INFO - 'USER': 'cltbld', 18:03:07 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', 18:03:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:07 INFO - '_': '/tools/buildbot/bin/python'} 18:03:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:07 INFO - Running setup.py (path:/tmp/pip-Tcveg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:07 INFO - Running setup.py (path:/tmp/pip-DCA9_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:07 INFO - Running setup.py (path:/tmp/pip-ZDgAMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:07 INFO - Running setup.py (path:/tmp/pip-HFjVLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:07 INFO - Running setup.py (path:/tmp/pip-57MCX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:08 INFO - Running setup.py (path:/tmp/pip-aCX_Qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:08 INFO - Running setup.py (path:/tmp/pip-x6AMgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:08 INFO - Running setup.py (path:/tmp/pip-iZDimy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:08 INFO - Running setup.py (path:/tmp/pip-Cv3n6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:08 INFO - Running setup.py (path:/tmp/pip-8peNVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:08 INFO - Running setup.py (path:/tmp/pip-g0LMqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:08 INFO - Running setup.py (path:/tmp/pip-sXicay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:08 INFO - Running setup.py (path:/tmp/pip-2MDVS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:08 INFO - Running setup.py (path:/tmp/pip-NZ27ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:08 INFO - Running setup.py (path:/tmp/pip-MY8bwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:08 INFO - Running setup.py (path:/tmp/pip-0B1rje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:08 INFO - Running setup.py (path:/tmp/pip-syJCL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:03:08 INFO - Running setup.py install for manifestparser 18:03:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:03:09 INFO - Running setup.py install for mozcrash 18:03:09 INFO - Running setup.py install for mozdebug 18:03:09 INFO - Running setup.py install for mozdevice 18:03:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:03:09 INFO - Running setup.py install for mozfile 18:03:09 INFO - Running setup.py install for mozhttpd 18:03:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:03:09 INFO - Running setup.py install for mozinfo 18:03:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:03:09 INFO - Running setup.py install for mozInstall 18:03:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:03:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:03:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:03:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:03:09 INFO - Running setup.py install for mozleak 18:03:09 INFO - Running setup.py install for mozlog 18:03:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:03:09 INFO - Running setup.py install for moznetwork 18:03:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:03:10 INFO - Running setup.py install for mozprocess 18:03:10 INFO - Running setup.py install for mozprofile 18:03:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:03:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:03:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:03:10 INFO - Running setup.py install for mozrunner 18:03:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:03:10 INFO - Running setup.py install for mozscreenshot 18:03:10 INFO - Running setup.py install for moztest 18:03:10 INFO - Running setup.py install for mozversion 18:03:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:03:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:03:10 INFO - Cleaning up... 18:03:10 INFO - Return code: 0 18:03:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03: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')]} 18:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03: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')]} 18:03:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:10 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 0x175c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1929a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1accd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9eb0>, '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 0x1acadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acb240>, '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': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', '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 18:03:10 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 18:03:10 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 18:03:10 INFO - Using env: {'DISPLAY': ':0', 18:03:10 INFO - 'HOME': '/home/cltbld', 18:03:10 INFO - 'LANG': 'en_US.UTF-8', 18:03:10 INFO - 'LANGUAGE': 'en_US:en', 18:03:10 INFO - 'LOGNAME': 'cltbld', 18:03:10 INFO - 'MAIL': '/var/mail/cltbld', 18:03:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:10 INFO - 'MOZ_NO_REMOTE': '1', 18:03:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:10 INFO - 'NO_EM_RESTART': '1', 18:03:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:10 INFO - 'PWD': '/builds/slave/test', 18:03:10 INFO - 'SHELL': '/bin/bash', 18:03:10 INFO - 'SHLVL': '1', 18:03:10 INFO - 'TERM': 'linux', 18:03:10 INFO - 'TMOUT': '86400', 18:03:10 INFO - 'USER': 'cltbld', 18:03:10 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', 18:03:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:10 INFO - '_': '/tools/buildbot/bin/python'} 18:03:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:10 INFO - Running setup.py (path:/tmp/pip-I_IklO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:10 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)) 18:03:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:10 INFO - Running setup.py (path:/tmp/pip-RUXnnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:11 INFO - Running setup.py (path:/tmp/pip-YdaEzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:11 INFO - Running setup.py (path:/tmp/pip-CXuIMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:11 INFO - Running setup.py (path:/tmp/pip-T4jD77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:11 INFO - Running setup.py (path:/tmp/pip-2ctSI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:11 INFO - Running setup.py (path:/tmp/pip-rGh5GR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:11 INFO - Running setup.py (path:/tmp/pip-l9InF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:11 INFO - Running setup.py (path:/tmp/pip-mTRnpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:11 INFO - Running setup.py (path:/tmp/pip-XqvJGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:11 INFO - Running setup.py (path:/tmp/pip-bdC7tO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:11 INFO - Running setup.py (path:/tmp/pip-nebDcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:11 INFO - Running setup.py (path:/tmp/pip-fYF1TV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:11 INFO - Running setup.py (path:/tmp/pip-EisSyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:11 INFO - Running setup.py (path:/tmp/pip-I9iUMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:11 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)) 18:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:11 INFO - Running setup.py (path:/tmp/pip-MBs50f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:12 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)) 18:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:12 INFO - Running setup.py (path:/tmp/pip-VmhwNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:12 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)) 18:03:12 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)) 18:03:12 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)) 18:03:12 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)) 18:03:12 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)) 18:03:12 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)) 18:03:12 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)) 18:03:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:03:12 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 18:03:12 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 18:03:12 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 18:03:12 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 18:03:13 INFO - Downloading blessings-1.6.tar.gz 18:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:03:13 INFO - Installing collected packages: blessings 18:03:13 INFO - Running setup.py install for blessings 18:03:13 INFO - Successfully installed blessings 18:03:13 INFO - Cleaning up... 18:03:13 INFO - Return code: 0 18:03:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:03:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:03:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:03:13 INFO - Reading from file tmpfile_stdout 18:03:13 INFO - Current package versions: 18:03:13 INFO - argparse == 1.2.1 18:03:13 INFO - blessings == 1.6 18:03:13 INFO - blobuploader == 1.2.4 18:03:13 INFO - docopt == 0.6.1 18:03:13 INFO - functools32 == 3.2.3-2 18:03:13 INFO - jsonschema == 2.5.1 18:03:13 INFO - manifestparser == 1.1 18:03:13 INFO - mozInstall == 1.13 18:03:13 INFO - mozcrash == 1.0 18:03:13 INFO - mozdebug == 0.1 18:03:13 INFO - mozdevice == 0.51 18:03:13 INFO - mozfile == 1.2 18:03:13 INFO - mozhttpd == 0.7 18:03:13 INFO - mozinfo == 0.10 18:03:13 INFO - mozleak == 0.1 18:03:13 INFO - mozlog == 3.5 18:03:13 INFO - moznetwork == 0.27 18:03:13 INFO - mozprocess == 0.25 18:03:13 INFO - mozprofile == 0.28 18:03:13 INFO - mozrunner == 6.13 18:03:13 INFO - mozscreenshot == 0.1 18:03:13 INFO - mozsystemmonitor == 0.3 18:03:13 INFO - moztest == 0.8 18:03:13 INFO - mozversion == 1.4 18:03:13 INFO - psutil == 3.1.1 18:03:13 INFO - requests == 1.2.3 18:03:13 INFO - wsgiref == 0.1.2 18:03:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:13 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')]} 18:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:13 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')]} 18:03:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:13 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 0x175c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1929a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1accd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9eb0>, '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 0x1acadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acb240>, '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': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', '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 18:03:13 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 18:03:13 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 18:03:13 INFO - Using env: {'DISPLAY': ':0', 18:03:13 INFO - 'HOME': '/home/cltbld', 18:03:13 INFO - 'LANG': 'en_US.UTF-8', 18:03:13 INFO - 'LANGUAGE': 'en_US:en', 18:03:13 INFO - 'LOGNAME': 'cltbld', 18:03:13 INFO - 'MAIL': '/var/mail/cltbld', 18:03:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:13 INFO - 'MOZ_NO_REMOTE': '1', 18:03:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:13 INFO - 'NO_EM_RESTART': '1', 18:03:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:13 INFO - 'PWD': '/builds/slave/test', 18:03:13 INFO - 'SHELL': '/bin/bash', 18:03:13 INFO - 'SHLVL': '1', 18:03:13 INFO - 'TERM': 'linux', 18:03:13 INFO - 'TMOUT': '86400', 18:03:13 INFO - 'USER': 'cltbld', 18:03:13 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', 18:03:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:13 INFO - '_': '/tools/buildbot/bin/python'} 18:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:13 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)) 18:03:13 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)) 18:03:13 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)) 18:03:13 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)) 18:03:13 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)) 18:03:13 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)) 18:03:13 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)) 18:03:13 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)) 18:03:13 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)) 18:03:13 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:03:13 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 18:03:13 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 18:03:13 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 18:03:13 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 18:03:14 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:03:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:03:15 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 18:03:15 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 18:03:15 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 18:03:15 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 18:03:15 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 18:03:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:03:15 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)) 18:03:15 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)) 18:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:03:15 INFO - Installing collected packages: marionette-driver, simplejson 18:03:15 INFO - Running setup.py install for marionette-driver 18:03:15 INFO - Running setup.py install for simplejson 18:03:15 INFO - building 'simplejson._speedups' extension 18:03:15 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 18:03:16 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 18:03:16 INFO - Successfully installed marionette-driver simplejson 18:03:16 INFO - Cleaning up... 18:03:16 INFO - Return code: 0 18:03:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:03:16 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')]} 18:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:16 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')]} 18:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:16 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 0x175c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1929a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1accd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ac9eb0>, '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 0x1acadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acb240>, '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': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', '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 18:03:16 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 18:03:16 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 18:03:16 INFO - Using env: {'DISPLAY': ':0', 18:03:16 INFO - 'HOME': '/home/cltbld', 18:03:16 INFO - 'LANG': 'en_US.UTF-8', 18:03:16 INFO - 'LANGUAGE': 'en_US:en', 18:03:16 INFO - 'LOGNAME': 'cltbld', 18:03:16 INFO - 'MAIL': '/var/mail/cltbld', 18:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:16 INFO - 'MOZ_NO_REMOTE': '1', 18:03:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:16 INFO - 'NO_EM_RESTART': '1', 18:03:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:16 INFO - 'PWD': '/builds/slave/test', 18:03:16 INFO - 'SHELL': '/bin/bash', 18:03:16 INFO - 'SHLVL': '1', 18:03:16 INFO - 'TERM': 'linux', 18:03:16 INFO - 'TMOUT': '86400', 18:03:16 INFO - 'USER': 'cltbld', 18:03:16 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', 18:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:16 INFO - '_': '/tools/buildbot/bin/python'} 18:03:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:03:16 INFO - Cleaning up... 18:03:16 INFO - Return code: 0 18:03:16 INFO - Running post-action listener: _resource_record_post_action 18:03:16 INFO - Running post-action listener: _start_resource_monitoring 18:03:16 INFO - Starting resource monitoring. 18:03:16 INFO - [mozharness: 2017-07-29 01:03:16.715461Z] Finished create-virtualenv step (success) 18:03:16 INFO - [mozharness: 2017-07-29 01:03:16.715968Z] Running install step. 18:03:16 INFO - Running pre-action listener: _resource_record_pre_action 18:03:16 INFO - Running main action method: install 18:03:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:03:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:03:16 INFO - Reading from file tmpfile_stdout 18:03:16 INFO - Detecting whether we're running mozinstall >=1.0... 18:03:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:03:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:03:17 INFO - Reading from file tmpfile_stdout 18:03:17 INFO - Output received: 18:03:17 INFO - Usage: mozinstall [options] installer 18:03:17 INFO - Options: 18:03:17 INFO - -h, --help show this help message and exit 18:03:17 INFO - -d DEST, --destination=DEST 18:03:17 INFO - Directory to install application into. [default: 18:03:17 INFO - "/builds/slave/test"] 18:03:17 INFO - --app=APP Application being installed. [default: firefox] 18:03:17 INFO - mkdir: /builds/slave/test/build/application 18:03:17 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'] 18:03:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:03:35 INFO - Reading from file tmpfile_stdout 18:03:35 INFO - Output received: 18:03:35 INFO - /builds/slave/test/build/application/firefox/firefox 18:03:35 INFO - Running post-action listener: _resource_record_post_action 18:03:35 INFO - [mozharness: 2017-07-29 01:03:35.149069Z] Finished install step (success) 18:03:35 INFO - [mozharness: 2017-07-29 01:03:35.149247Z] Running setup-mitmproxy step. 18:03:35 INFO - Running pre-action listener: _resource_record_pre_action 18:03:35 INFO - Running main action method: setup_mitmproxy 18:03:35 INFO - Skipping: mitmproxy is not required 18:03:35 INFO - Running post-action listener: _resource_record_post_action 18:03:35 INFO - [mozharness: 2017-07-29 01:03:35.150401Z] Finished setup-mitmproxy step (success) 18:03:35 INFO - [mozharness: 2017-07-29 01:03:35.150624Z] Running run-tests step. 18:03:35 INFO - Running pre-action listener: _resource_record_pre_action 18:03:35 INFO - Running pre-action listener: _set_gcov_prefix 18:03:35 INFO - Running main action method: run_tests 18:03:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:03:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:03:35 INFO - Python 2.7.3 18:03:35 INFO - Return code: 0 18:03:35 INFO - grabbing minidump binary from tooltool 18:03:35 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')]} 18:03:35 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0x1ac9eb0>, '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 0x1acadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1acb240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:03:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 18:03:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:03:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 18:03:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:03:35 INFO - Return code: 0 18:03:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:03:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:03:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:03:35 INFO - ENV: RUST_BACKTRACE is now 1 18:03:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:03:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:03:35 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/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 18:03:35 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/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 18:03:35 INFO - Using env: {'DISPLAY': ':0', 18:03:35 INFO - 'HOME': '/home/cltbld', 18:03:35 INFO - 'LANG': 'en_US.UTF-8', 18:03:35 INFO - 'LANGUAGE': 'en_US:en', 18:03:35 INFO - 'LOGNAME': 'cltbld', 18:03:35 INFO - 'MAIL': '/var/mail/cltbld', 18:03:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:03:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:35 INFO - 'MOZ_NO_REMOTE': '1', 18:03:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:03:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:35 INFO - 'NO_EM_RESTART': '1', 18:03:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:35 INFO - 'PWD': '/builds/slave/test', 18:03:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:03:35 INFO - 'RUST_BACKTRACE': '1', 18:03:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:03:35 INFO - 'SHELL': '/bin/bash', 18:03:35 INFO - 'SHLVL': '1', 18:03:35 INFO - 'TERM': 'linux', 18:03:35 INFO - 'TMOUT': '86400', 18:03:35 INFO - 'USER': 'cltbld', 18:03:35 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1501285988.489652-1908195001', 18:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:35 INFO - '_': '/tools/buildbot/bin/python'} 18:03:35 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/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 18:03:35 INFO - mozversion application_buildid: 20170729001707 18:03:35 INFO - mozversion application_changeset: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 18:03:35 INFO - mozversion application_display_name: Nightly 18:03:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:03:35 INFO - mozversion application_name: Firefox 18:03:35 INFO - mozversion application_remotingname: firefox 18:03:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:03:35 INFO - mozversion application_vendor: Mozilla 18:03:35 INFO - mozversion application_version: 56.0a1 18:03:35 INFO - mozversion platform_buildid: 20170729001707 18:03:35 INFO - mozversion platform_changeset: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 18:03:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:03:35 INFO - mozversion platform_version: 56.0a1 18:03:35 INFO - using testdate: 1501290215 18:03:35 INFO - actual date: 1501290215 18:03:35 INFO - starting webserver on 'localhost:43580' 18:03:35 INFO - SUITE-START | Running 2 tests 18:03:35 INFO - TEST-START | dromaeo_css 18:03:35 INFO - Initialising browser for dromaeo_css test... 18:03:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43580/getInfo.html -profile /tmp/tmp0CIXXu/profile 18:03:35 INFO - TEST-INFO | started process 10813 (/builds/slave/test/build/application/firefox/firefox http://localhost:43580/getInfo.html) 18:03:41 INFO - TEST-INFO | 10813: exit 0 18:03:41 INFO - Browser initialized. 18:03:41 INFO - Running cycle 1/1 for dromaeo_css test... 18:03:41 INFO - TEST-INFO | started process 11019 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0CIXXu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:03:42 INFO - PID 11019 | 18:03:42 INFO - PID 11019 | (/builds/slave/test/build/application/firefox/firefox:11068): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:03:42 INFO - PID 11019 | 18:03:53 INFO - PID 11019 | RSS: Main: 171220992 18:03:53 INFO - PID 11019 | 18:06:52 INFO - PID 11019 | Cycle 1(1): loaded http://localhost:43580/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43580/tests/dromaeo/cssquery-ext.html) 18:06:54 INFO - PID 11019 | RSS: Main: 173010944 18:06:54 INFO - PID 11019 | 18:09:56 INFO - PID 11019 | Cycle 1(1): loaded http://localhost:43580/tests/dromaeo/cssquery-ext.html (next: http://localhost:43580/tests/dromaeo/cssquery-jquery.html) 18:09:56 INFO - PID 11019 | RSS: Main: 170979328 18:09:56 INFO - PID 11019 | 18:12:58 INFO - PID 11019 | Cycle 1(1): loaded http://localhost:43580/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43580/tests/dromaeo/cssquery-mootools.html) 18:12:59 INFO - PID 11019 | RSS: Main: 172871680 18:12:59 INFO - PID 11019 | 18:16:00 INFO - PID 11019 | Cycle 1(1): loaded http://localhost:43580/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43580/tests/dromaeo/cssquery-prototype.html) 18:16:01 INFO - PID 11019 | RSS: Main: 171069440 18:16:01 INFO - PID 11019 | 18:19:05 INFO - PID 11019 | Cycle 1(1): loaded http://localhost:43580/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43580/tests/dromaeo/cssquery-yui.html) 18:19:05 INFO - PID 11019 | RSS: Main: 172240896 18:19:05 INFO - PID 11019 | 18:22:07 INFO - PID 11019 | Cycle 1(1): loaded http://localhost:43580/tests/dromaeo/cssquery-yui.html (next: http://localhost:43580/tests/dromaeo/cssquery-dojo.html) 18:22:08 INFO - PID 11019 | RSS: Main: 174772224 18:22:08 INFO - PID 11019 | 18:22:08 INFO - PID 11019 | __start_tp_report 18:22:08 INFO - PID 11019 | _x_x_mozilla_page_load 18:22:08 INFO - PID 11019 | _x_x_mozilla_page_load_details 18:22:08 INFO - PID 11019 | |i|pagename|runs| 18:22:08 INFO - PID 11019 | |0;dojo.html;3134.357456721371;3173.12738996776;3197.8560964756584;3198.504231844064;3213.3412650406653;6156.692165391735;6229.595076320036;6260.311365749764;6279.372062793724;6281.748730050804;6345.460635845947;6353.618782873019;6354.7775827846035;6379.553431259814;6418.743250269995;5726.713664316778;5800.680962547058;5801.796937107202;5869.031609784381;5910.143029260755;5705.885882282352;5709.486146246837;5717.485426311631;5826.504747096502;5828.5337173026055;5710.343310519302;5759.798407055754;5764.51001664859;5802.912956305659;5821.3305469870875;5734.827955161351;5743.712814359307;5779.9422005779825;5788.334341550733;5795.391483894173;5589;5634.521065709419;5666.631668941505;5675.489205971482;5772.855678608026;6124.877502449971;6159.045347971071;6182.907256391158;6206.968965155191;6213.2233470816145;6784.898226526606;6820.863582728318;6834.111565496453;6839.692213850339;6862.794116176523;6828.2488926218075;6834.316568343175;6838.179418469766;6908.758193463204;6909.896351554731;6285.402886725754;6310.621362718298;6383.265924418658;6385.712642931079;6424.839379015515;6874.140732408449;6903.171619405603;6907.274736152732;6911.447084233249;6989.825254368681;6484.416402523795;6561.114249576246;6572.211334639778;6612.206535215805;6655.301193374723;6744.5278830482375;6747.797566073025;6751.122354093936;6758.594484330956;6763.188417389944;6648.5346025777735;6658.101156343929;6690.2306234782645;6705.966470167618;6737.023131645884;6754.5609535380045;6760.425363844129;6765.458763298924;6800.591964482147;6892.758753443605;5385.542228910508;5410.756515956791;5463.48096930791;5473.343198816088;5493.615446918758;4585.747783871919;4616.907661846744;4646.233371493666;4671.532846715301;4721.976390118028;5888.587798854125;5895.115732640138;5896.557758168155;5902.675352855634;5920.585559010828;6798.93201067983;6881.587104773629;6897.862042759117;6903.482238832108;6908.205556360982;5848.5321174305955;5865.736041878125;5945.672987985616;5953.880922381528;5963.463288303987;5821.79623713168;5913.733881975236;5914.260717410324;5915.024021036481;6012.819615411545;6536.084948107269;6536.379043990908;6573.046908198285;6574.572652777554;6575.178102737158;6640;6740.797776066725;6822.590644561342;6822.795316140524;6826.829329266808;6221.657808820558;6555.049517819984;6596.571222870498;6640.83397915056;6690.19717633887;6661.1007513985;6675.8664826702225;6763;6768.763093291711;6780.288069752605;6750.088738020305;6768.255491895986;6849.1780986281965;6885.208201056943;6915.792526224222;6578.144841170617;6596.60420374779;6596.7031483584315;6597.241317248578;6604.306547812603;6547.214334279917;6581.144451221502;6593.439557637655;6602.40578347951;6605.174353205849;6474.967625161844;6481.611103333815;6506.512011599244;6515.73937042532;6558.114654521579;6612.603243805387;6656.167979002625;6680.298568650222;6755.3582409671;6783.864322713617;6332.208473940757;6434.388733070351;6443.162388889415;6445.581037232752;6510.121133646897 18:22:08 INFO - PID 11019 | |1;ext.html;818.7052661042028;850.8808766772652;871.7384784564631;875.4572165257541;1192.791261529232;30379.392412151745;41278.58721412785;41814;42068.789656051755;42074.15851682968;42935.57064429355;43163.56836431635;43214.7839260804;43488.347674784905;43568.3464748029;7677.616119194032;7830.491018083835;7832.921670783277;7834.647440865175;7846;7644.082710074771;7724.884126738103;7794.805129871742;7853.371730261565;7859.921400785976;7620.276073772982;7662.310392064713;7770.57261850598;7780.338671212954;7782.883256751154;7691.961540192319;7711.383089352838;7743.148253692089;7755.069391673007;7756;7603.087629484442;7725.381969442431;7818.8827167592535;7818.921810781933;7822.569758663271;39191.216175676615;39588.40617390742;39663.20673586541;39746.60253397487;39761.80119099386;3575.3385623659706;3638.745287829853;3645.7265705072227;3645.963540364589;3646.6171052039417;31201.687983120104;31233.8438307807;31370.21574460634;31466.685333146605;31468.527972080436;4829.9034019319415;4846.079244943449;4846.079244943485;4889.926651100236;4903.730294833783;188800;190181;191148.9999999986;191302;191581;49201.26198107031;49233.507664922894;49367;49432.75283623631;49560.75219623879;52673.473265267625;52886;52923.73538132285;53137.7343113282;53248.20127698088;15589.688206236038;15632.765508517381;15647.295871685801;15647.452339168074;15662.138582378078;12807.61577152687;12815.871841281469;12830.358482075859;12853.485860565472;12853.93573032129;14586.41654333836;15011.324490397958;15340.616484587752;15503.767443488357;15532.611684707972;5804.883902322014;6187.16473276111;6214.9689251553455;6220.720067596879;6280.120783090371;966.5022513405602;983.1053741095569;990.8315586350337;1004.42747633848;1005.7686732051669;22939.72909403789;23089.42276443069;23285;23345.649815252786;23954.161604343764;1066.5413872034944;1085.2039874008528;1101.9504122314515;1105.4030823355458;1110.2672236323988;11887.952404561229;11995;12237.93881030607;15266;15334;219196;220236;221441;222547;223061;57120.428795712345;57304.14043789347;57686.42313576894;57776;57842.7107864458;1446.1250943179436;1457.511733569245;1498.3257534109694;1512.4933147395525;1512.8335883052855;26530;26816.86591567108;27078.593821093094;27235.86382068077;27406.314842128308;38459.23081538409;38632.61367386403;38646.2270754589;38689;38886.41670375003;194497;195554;195913;196280;196337;32628.184295391857;33615.495767563014;33725.49411758874;33747.325053499284;34333.25333973326;42737.57262427336;42856.142877142905;42864;43266.35100473432;43340.13319733524;28543.571846422736;29511.55732663969;30179;30409.847950760104;30509.084727458212;27814.582781257894;27873.303167420978;29730.702692972794;29875.25311867134;30207.848960755055;37994.240115198096;38333.23333533258;38989.2202155961;39224.41163382609;39590.802045989585;31200;33701.15747106342;33958.83020584881;33961.66038339585;34177;24046.27861164168;24661.26016219516;25026;25304;25614.10350637719 18:22:08 INFO - PID 11019 | |2;jquery.html;577.9653220806752;583.4457265597681;584.3396961433584;587.8706684529403;600.5885968111844;22091.668624970618;22546;23247.418814529647;23255.651165232513;23273.418164545896;25449.491010179787;25585.104521341756;25684.74315256847;25799.484010319782;25920.48159036818;6380.106785050097;6579.835504112388;6589.143411356541;6600.339966003409;6656.833579160511;5740.7703691852485;6338.904916426257;6359.8092057238355;6396.168498095241;6467.094606755058;6295.5907865988565;6297.811065668015;6302.212223472066;6303.716332765013;6308.4322410983;6291.842703932392;6301.432871041606;6304.464120549759;6304.810855674337;6312.589681670676;5845.2401187845735;5870.589058765845;5876.382979787147;5884.970575147139;5895.793647222326;28621.42757144866;28689;28930;29074.27314317138;29261.268468288465;1852.8147185281498;1909.76127984002;1914.329984505426;1919.9520011999673;1921.423572928116;18783.90608046951;19395.030248487517;19496.80503194978;19558.70661940072;19562.31531896391;2304.8962796674023;2358.622620380731;2392.676988606533;2406.795422389099;2436.0864675746593;131504;132522;133963;134947.6505234945;135290.3235483826;14254.287285635677;14920.850791492163;15025.398984040734;15054.042974413474;15572.766408503881;22961.425964351023;23175;23205.187818426613;23216.65175022376;23254.7674523256;10113.797724045478;10272.794544109076;10410.323328983592;10428.113610343205;10555.63055293061;9673.419594824332;9696.17582505495;9757.951210243904;9901.554430050664;9942.453165075991;8857.955710221408;9144.3598948073;9170.770730731785;9431;9515.524223788783;2666.440047589989;2720.3607152319587;2730.153652367733;2785.2758282846607;2795.916122516328;905.1129892705186;923.3075193604797;934.6495064350869;941.6045260990263;955.3885513271512;18300.48374845073;18843;18881.90559047196;19236;19475.610487790163;986.4771671014374;986.7582442301682;999.8300288950895;1016.5781200801732;1019.4851599941982;10334.741631459123;10482.528286227138;10621.415822129702;10655.147588192925;10805.13558915285;449439;464374;474551;475722;481808;112106;113068.43465782783;114017;114812.42593786978;115523;1191.7854786138405;1194.510250797169;1204.379744431636;1219.2196993923897;1221.2367270455966;16800.32798688088;16930.746038809182;19802.90098549498;21399.57200855943;21426.250081247083;54875.72562137164;56789.43210567842;57978.710106449194;58177;59819.70090149521;66799.66600166968;68519;68566.314336856;68625.3137468619;68952.6552367235;12249.816252756395;12855.678608034874;14750.114993100447;14983.625409364853;15055.698886022437;18581.16384762662;21925.451863703536;22812.31563053111;24094.277171684877;24418.51162976766;17750.644987100444;18598.721019184995;18969.43091707251;19045.666803323635;19152.904235478734;13416.195028298333;14590;14652.340644670801;14752.63118422048;14886.255687215813;18643;18981.050947452295;19287;19397.70903436478;19466.513337166118;14688.706225875636;14796.334164962387;14840.480583179537;14862.182579958206;14959.850401496282;18627;18630.347937822113;18777;18862.905685471484;18902.7164592534 18:22:08 INFO - PID 11019 | |3;mootools.html;1334.4862228042205;1386.8682475164856;1420.2046853761901;1424.3163281624813;1427.9714405711882;11723.179377443561;13506.18962862229;14113.92943035286;14151.57545273642;14156.575302740908;11732.35472049037;11933.045356371491;13653.590392288223;14033.15801051935;14044.087134336287;2589.2750029991585;2637.512060268856;2652.5755879059357;2661.787057035442;2668.519666460036;2630.5659566171557;2707.6073969274444;2714.1179116787025;2716.3209197700576;2717.4836781011645;2626.5272250994813;2662.347724807425;2666.6666666666665;2682.0210623122516;2686.9462610747773;2595.8702064896775;2616.869156542175;2628.54000549903;2630.93422664433;2631.723669014745;2564.1410127607273;2564.294818924792;2565.8203925725206;2581.3675649465813;2582.496413199427;13487.797683034762;13602.863971360259;13630.454781808716;13644.658883527893;13652.24912629805;7425.480216384855;7631.732889348902;7678.462507624468;7702.229777022309;7725.652345644459;4774.116788394123;4812.927806082912;4907.362042934431;5084.110280700863;5090.974545127251;1867.2344338821154;1886.2455017992775;1902.581432084939;1914.9042547872689;1949.3664559018375;22749.31752047441;22835.771642283366;22882.65676014861;22892.65661015086;22919.885400573057;6329.715162817684;6611.107500487469;6640.236372817188;6807.2512023677355;6822.454203663695;17697.203625836868;17713.911430442764;17716.936983780757;17722.91138544299;17739.37912173068;7448.292412220831;7466.775996720107;7479;7482.401407887356;7492.063492063492;5679.4320567943705;5933.465988061095;6192.318844927055;6206;6750.561213521106;5201.933603611251;5352.892942141136;5420.159875219348;5472.480114389126;5504.862378440571;1632.6000129967997;1639.7540368944753;1647.5881029742563;1648.4972083514403;1666.208550938319;835.2315869400167;847.3221422861686;852.2159613155911;871.4030888841164;891.3404080980029;6775.525713200127;6969.7909062728195;7173.390261827803;7230.276972302833;7255.709771609182;662.6587307536622;706.4313227851629;709.9325564071405;711.4983936324878;721.0770214125927;3938.4092386142306;4065;4119.526254480713;4189.392538081961;4211.1998720243055;17710.911445442947;18158.636827263646;18482;18504.16731247097;18548.536286592673;18464.261429542938;18522.629547408975;18553.721694174597;18562.25750969973;18603.720944185847;10515.631952881766;10563.63027294041;10567.101796347397;10591.523381447852;10600.734981625521;6932.896006560007;7571.659275332513;7619.65711542993;7825.804354891174;7939.642716077906;16036.358545658042;16445.835541644432;16503.257353419365;16537.007779532785;16541.834581654028;16090.356385744439;16470.176491175633;16574.502764917073;16908.32366705318;16977.49067527976;12788.872111278768;13007.609771706864;13183.53857614979;13192;13201.867981320063;12465.626031219077;13110.541131060367;13150;13250.734985300047;13260.138091024439;9929.255305851946;10270.743231419274;10391.37651740898;10548.947255263674;10653.786924261316;10336.638217662347;10492.94753526258;10507.47462626881;10578.206634502598;10637.414942178255;8607.053224145213;8758.737237882873;8827.382083254111;8842.425242359226;8864.290856731575;6528.086067950396;6718.395344419026;6768.153980752406;6850.21222559412;6917.412019978358;6540.411362977355;6568.868622627616;6745.831354216184;7020.157581090302;7126.714931402686 18:22:08 INFO - PID 11019 | |4;prototype.html;493.3684883349312;504.74762618690664;517.9559737422319;522.2218893848166;527.2934268080771;6257.8122656320265;6341.175647165867;6348.682565871705;6354.205724284465;6386.680665966706;5949.970250148754;6034.788782392618;6055.061465472848;6108.602940808855;6116.4800991915745;4877.609791216694;4916.50834916509;4932.679375840567;4958.925616115761;4985.052839960414;4973.52751488608;4997.225430058346;5003.874903127415;5004.949950500485;5018.824341148061;4796.616270698353;4879.194932836078;4904.48502907195;4939.8024079036795;4971.950280497203;4868.245421959602;4883.316335713003;4892.535209155125;4917.164082106033;4937.333459982892;4375.956240437618;4394.670399720034;4404.7357158570585;4439.933400998988;4479.4176757021705;5982.282126144847;6021.277446706379;6075.17985072014;6087.512998960073;6134.69326533676;5672.319321681383;5676.545876329884;5697.914531282034;5708;5741.25363702716;6446.355364463563;6478.643674597895;6508.4142427181305;6591.901121483182;6618.7021584028835;4865.683730557503;4870.683405578626;4881.219004959189;4892.0705066037335;4893.192623217201;6927.826804329881;6995.475339349569;7003.2296447390745;7012.474064445187;7040.964795176043;4861.683990540603;4935.925961110586;4980.302757613937;5042.823501177441;5048.722320272347;5045.31888195089;5071.721055341918;5080.441151473335;5096.541311282002;5105.723569107723;5636.154576813511;5711.514521265739;5722.942770572325;5729.341125770507;5762.9135562966585;6537.986612075136;6568.277489476154;6614.040964060185;6675.532712710064;6679.866402672016;1678.387388603151;1708.436216048701;1723.8448539631493;1731.7488964100387;1762.207006846924;1164.9359285239223;1183.2486371154207;1203.9157258991786;1233.210745123122;1242.7203879127123;5772.711364431846;5777;5782;5786.826395208151;5798.9420105799245;6570.868582628416;6655.667216639149;6656.800295991031;6673.666316684146;6733.397632266811;5618.325800903917;5790.218320526759;5792.913106303409;5806.970965145232;5835;3562.4656301554974;3590.8743193988084;3613.1689711366535;3627.7097832173363;3628.9818550907603;6446.419822216023;6446.967765161238;6511.706973186218;6545.541812073109;6549.738010479623;6866.553674011173;6878.759243426456;6892.965535172292;6905.75829845953;6953.617551034742;6440.291567927524;6455.903161452488;6538.967305163539;6550.659163826483;6554.9672251639395;6688.899666505104;6722.495812813961;6742.359475849787;6756;6770.966145169243;6627.403533681992;6665.633390163393;6667.099746648071;6707.195136583641;6773.6951837168435;6525.086487891604;6575.882100042909;6579.651171509925;6606.10817539626;6609.504287178384;4945.714114330228;4958.636399968005;4978.929530151035;4988.75056247179;5003.324551185543;6804.523683342317;6811.9659401702675;6817.625030623363;6820.897686534805;6858.6227757471825;6576.243731970884;6602;6602.471802255904;6604.867902641824;6610.338966103352;6452.22573291208;6519.902201466887;6529.669868367072;6590.8352291193105;6621.569597976116;6188.226471691039;6412.583182093335;6438.645874476949;6468.579542329733;6478.514111441755;6222.844428889314;6402;6501.804945851632;6598.37315455031;6639.70525747494;6289.14470231292;6290.46531044848;6332;6362.713677884413;6363.268224154282 18:22:08 INFO - PID 11019 | |5;yui.html;872.5419154943654;897.5467388968572;901.4050726520496;903.7740564858781;912.325882006518;17931.82068179318;18931.810681893177;18994.71507927382;19009.52476188096;19015.524611884674;19884.60230795383;21556.245531406403;21621.027053782578;21622.351329460103;21788.3463496095;1053.5627714498494;1057.2440704895998;1063.5107850388831;1064.973375665607;1067.4075887882223;748.3040772081962;762.6872982077349;1013.9138173255245;1055.3826011783117;1059.099765199582;1100.2408338246623;1112.454897100423;1112.571659910936;1113.5545781687308;1115.6262652011546;1094.68801391603;1098.8571485706848;1100.2958106811607;1101.9063379612742;1102.1403305421784;801.410962942237;808.9676412943476;809.1180613131656;809.8542262392767;811.0834756724922;19431.4170574883;19776.70334944977;19959;20020.49948751278;20021.399358019284;20817.68773468399;20932;21111.894440527853;21246.681299780517;21278.574428511496;16101.355945762156;16166.515004549883;16278.26747796352;16411;16437.753433698505;3430.451127819562;3460.653934606544;3465.7747246429153;3474.1488335358;3475.0269924421204;110767;114521;115398.84601153964;118881.40559297234;125970;16233.918830406008;16700.331986720394;16907.746383804253;16919.323227071025;16920;30177.245568860955;30748.38503229923;30769;30941.535876961865;31075.378492430023;8293.875591866006;8644.351673624378;8706.172913573328;8713.3464990126;8720.825583488295;4154.750714957112;4213.6839737019845;4215.430916826189;4219.810108545123;4222.4299719538085;4878.975605121952;5003.074431230161;5118.25785261135;5943.613665111754;5946.167536544887;1014.6702321745462;1020.4642562654517;1036.0571879589688;1044.2637940252105;1050.3015494695942;646.9417752402306;656.9244536878225;656.957297775643;665.6971078159426;677.4749569083999;11286.887131128748;11410.885891140982;11625.418729063513;11794.587189448328;12271.877281227073;658.4386013255794;683.2723149845399;685.266764561914;693.8438851258527;697.7767114523406;5283.788648454096;5349.732513374393;5377.865553361197;5432.29380180574;5528.889422211533;96528;100851.49574252228;101089.49455252677;102805.48597256966;103422;85083.57458212669;88541.11458885329;88997;91020.54489727509;93171;76334.23665763272;79075;79385.60307198427;81318.18681813106;82665.58667206626;10738.355698657793;10884.129269658566;10965.725856853642;11100.222984391337;11225.270357426742;34799;35277.82361088178;35980.820095899355;36190.45714314235;38171;45164.77417612891;45738.32895347004;45801.312980305986;45957;46804.53195468002;14033.859661403256;18079.728804067687;18717.157727902006;18902;18948.43154705361;20952.266670667064;21501.354959351243;21589.784102158777;21822.45443863916;21880.343589692333;18863.339783107524;19116;19213.231470741015;19243.422697319103;19306.131224094668;17106.82893171102;17654.117294134983;17783.288668453635;18617.720734189268;18853.434396968114;17955.102244887963;18213.45359639213;18536.721949170504;18909.527261818566;19036.33372831944;12715.300658463873;13169.275689837152;13548.916086713236;13806.585802425943;14115.576532704035;13633;14585.489507867174;14627.707445851236;14650.413983440543;14670.633234168805 18:22:08 INFO - PID 11019 | __end_tp_report 18:22:08 INFO - PID 11019 | __start_cc_report 18:22:08 INFO - PID 11019 | _x_x_mozilla_cycle_collect,374 18:22:08 INFO - PID 11019 | __end_cc_report 18:22:08 INFO - PID 11019 | __startTimestamp1501291328005__endTimestamp 18:22:08 INFO - PID 11019 | 18:22:08 INFO - PID 11019 | ------- Summary: start ------- 18:22:08 INFO - PID 11019 | Number of tests: 6 18:22:08 INFO - PID 11019 | 18:22:08 INFO - PID 11019 | [#0] dojo.html Cycles:165 Average:6227.17 Median:6484.42 stddev:745.85 (11.5%) stddev-sans-first:707.57 18:22:08 INFO - PID 11019 | Values: 3134.4 3173.1 3197.9 3198.5 3213.3 6156.7 6229.6 6260.3 6279.4 6281.7 6345.5 6353.6 6354.8 6379.6 6418.7 5726.7 5800.7 5801.8 5869.0 5910.1 5705.9 5709.5 5717.5 5826.5 5828.5 5710.3 5759.8 5764.5 5802.9 5821.3 5734.8 5743.7 5779.9 5788.3 5795.4 5589.0 5634.5 5666.6 5675.5 5772.9 6124.9 6159.0 6182.9 6207.0 6213.2 6784.9 6820.9 6834.1 6839.7 6862.8 6828.2 6834.3 6838.2 6908.8 6909.9 6285.4 6310.6 6383.3 6385.7 6424.8 6874.1 6903.2 6907.3 6911.4 6989.8 6484.4 6561.1 6572.2 6612.2 6655.3 6744.5 6747.8 6751.1 6758.6 6763.2 6648.5 6658.1 6690.2 6706.0 6737.0 6754.6 6760.4 6765.5 6800.6 6892.8 5385.5 5410.8 5463.5 5473.3 5493.6 4585.7 4616.9 4646.2 4671.5 4722.0 5888.6 5895.1 5896.6 5902.7 5920.6 6798.9 6881.6 6897.9 6903.5 6908.2 5848.5 5865.7 5945.7 5953.9 5963.5 5821.8 5913.7 5914.3 5915.0 6012.8 6536.1 6536.4 6573.0 6574.6 6575.2 6640.0 6740.8 6822.6 6822.8 6826.8 6221.7 6555.0 6596.6 6640.8 6690.2 6661.1 6675.9 6763.0 6768.8 6780.3 6750.1 6768.3 6849.2 6885.2 6915.8 6578.1 6596.6 6596.7 6597.2 6604.3 6547.2 6581.1 6593.4 6602.4 6605.2 6475.0 6481.6 6506.5 6515.7 6558.1 6612.6 6656.2 6680.3 6755.4 6783.9 6332.2 6434.4 6443.2 6445.6 6510.1 18:22:08 INFO - PID 11019 | 18:22:08 INFO - PID 11019 | [#1] ext.html Cycles:180 Average:37718.46 Median:3642.24 stddev:52557.08 (1443.0%) stddev-sans-first:52631.48 18:22:08 INFO - PID 11019 | Values: 818.7 850.9 871.7 875.5 1192.8 30379.4 41278.6 41814.0 42068.8 42074.2 42935.6 43163.6 43214.8 43488.3 43568.3 7677.6 7830.5 7832.9 7834.6 7846.0 7644.1 7724.9 7794.8 7853.4 7859.9 7620.3 7662.3 7770.6 7780.3 7782.9 7692.0 7711.4 7743.1 7755.1 7756.0 7603.1 7725.4 7818.9 7818.9 7822.6 39191.2 39588.4 39663.2 39746.6 39761.8 3575.3 3638.7 3645.7 3646.0 3646.6 31201.7 31233.8 31370.2 31466.7 31468.5 4829.9 4846.1 4846.1 4889.9 4903.7 188800.0 190181.0 191149.0 191302.0 191581.0 49201.3 49233.5 49367.0 49432.8 49560.8 52673.5 52886.0 52923.7 53137.7 53248.2 15589.7 15632.8 15647.3 15647.5 15662.1 12807.6 12815.9 12830.4 12853.5 12853.9 14586.4 15011.3 15340.6 15503.8 15532.6 5804.9 6187.2 6215.0 6220.7 6280.1 966.5 983.1 990.8 1004.4 1005.8 22939.7 23089.4 23285.0 23345.6 23954.2 1066.5 1085.2 1102.0 1105.4 1110.3 11888.0 11995.0 12237.9 15266.0 15334.0 219196.0 220236.0 221441.0 222547.0 223061.0 57120.4 57304.1 57686.4 57776.0 57842.7 1446.1 1457.5 1498.3 1512.5 1512.8 26530.0 26816.9 27078.6 27235.9 27406.3 38459.2 38632.6 38646.2 38689.0 38886.4 194497.0 195554.0 195913.0 196280.0 196337.0 32628.2 33615.5 33725.5 33747.3 34333.3 42737.6 42856.1 42864.0 43266.4 43340.1 28543.6 29511.6 30179.0 30409.8 30509.1 27814.6 27873.3 29730.7 29875.3 30207.8 37994.2 38333.2 38989.2 39224.4 39590.8 31200.0 33701.2 33958.8 33961.7 34177.0 24046.3 24661.3 25026.0 25304.0 25614.1 18:22:08 INFO - PID 11019 | 18:22:08 INFO - PID 11019 | [#2] jquery.html Cycles:180 Average:33888.91 Median:23210.92 stddev:79178.00 (341.1%) stddev-sans-first:79360.61 18:22:08 INFO - PID 11019 | Values: 578.0 583.4 584.3 587.9 600.6 22091.7 22546.0 23247.4 23255.7 23273.4 25449.5 25585.1 25684.7 25799.5 25920.5 6380.1 6579.8 6589.1 6600.3 6656.8 5740.8 6338.9 6359.8 6396.2 6467.1 6295.6 6297.8 6302.2 6303.7 6308.4 6291.8 6301.4 6304.5 6304.8 6312.6 5845.2 5870.6 5876.4 5885.0 5895.8 28621.4 28689.0 28930.0 29074.3 29261.3 1852.8 1909.8 1914.3 1920.0 1921.4 18783.9 19395.0 19496.8 19558.7 19562.3 2304.9 2358.6 2392.7 2406.8 2436.1 131504.0 132522.0 133963.0 134947.7 135290.3 14254.3 14920.9 15025.4 15054.0 15572.8 22961.4 23175.0 23205.2 23216.7 23254.8 10113.8 10272.8 10410.3 10428.1 10555.6 9673.4 9696.2 9758.0 9901.6 9942.5 8858.0 9144.4 9170.8 9431.0 9515.5 2666.4 2720.4 2730.2 2785.3 2795.9 905.1 923.3 934.6 941.6 955.4 18300.5 18843.0 18881.9 19236.0 19475.6 986.5 986.8 999.8 1016.6 1019.5 10334.7 10482.5 10621.4 10655.1 10805.1 449439.0 464374.0 474551.0 475722.0 481808.0 112106.0 113068.4 114017.0 114812.4 115523.0 1191.8 1194.5 1204.4 1219.2 1221.2 16800.3 16930.7 19802.9 21399.6 21426.3 54875.7 56789.4 57978.7 58177.0 59819.7 66799.7 68519.0 68566.3 68625.3 68952.7 12249.8 12855.7 14750.1 14983.6 15055.7 18581.2 21925.5 22812.3 24094.3 24418.5 17750.6 18598.7 18969.4 19045.7 19152.9 13416.2 14590.0 14652.3 14752.6 14886.3 18643.0 18981.1 19287.0 19397.7 19466.5 14688.7 14796.3 14840.5 14862.2 14959.9 18627.0 18630.3 18777.0 18862.9 18902.7 18:22:08 INFO - PID 11019 | 18:22:08 INFO - PID 11019 | [#3] mootools.html Cycles:180 Average:8533.87 Median:2606.37 stddev:5879.40 (225.6%) stddev-sans-first:5871.01 18:22:08 INFO - PID 11019 | Values: 1334.5 1386.9 1420.2 1424.3 1428.0 11723.2 13506.2 14113.9 14151.6 14156.6 11732.4 11933.0 13653.6 14033.2 14044.1 2589.3 2637.5 2652.6 2661.8 2668.5 2630.6 2707.6 2714.1 2716.3 2717.5 2626.5 2662.3 2666.7 2682.0 2686.9 2595.9 2616.9 2628.5 2630.9 2631.7 2564.1 2564.3 2565.8 2581.4 2582.5 13487.8 13602.9 13630.5 13644.7 13652.2 7425.5 7631.7 7678.5 7702.2 7725.7 4774.1 4812.9 4907.4 5084.1 5091.0 1867.2 1886.2 1902.6 1914.9 1949.4 22749.3 22835.8 22882.7 22892.7 22919.9 6329.7 6611.1 6640.2 6807.3 6822.5 17697.2 17713.9 17716.9 17722.9 17739.4 7448.3 7466.8 7479.0 7482.4 7492.1 5679.4 5933.5 6192.3 6206.0 6750.6 5201.9 5352.9 5420.2 5472.5 5504.9 1632.6 1639.8 1647.6 1648.5 1666.2 835.2 847.3 852.2 871.4 891.3 6775.5 6969.8 7173.4 7230.3 7255.7 662.7 706.4 709.9 711.5 721.1 3938.4 4065.0 4119.5 4189.4 4211.2 17710.9 18158.6 18482.0 18504.2 18548.5 18464.3 18522.6 18553.7 18562.3 18603.7 10515.6 10563.6 10567.1 10591.5 10600.7 6932.9 7571.7 7619.7 7825.8 7939.6 16036.4 16445.8 16503.3 16537.0 16541.8 16090.4 16470.2 16574.5 16908.3 16977.5 12788.9 13007.6 13183.5 13192.0 13201.9 12465.6 13110.5 13150.0 13250.7 13260.1 9929.3 10270.7 10391.4 10548.9 10653.8 10336.6 10492.9 10507.5 10578.2 10637.4 8607.1 8758.7 8827.4 8842.4 8864.3 6528.1 6718.4 6768.2 6850.2 6917.4 6540.4 6568.9 6745.8 7020.2 7126.7 18:22:08 INFO - PID 11019 | 18:22:08 INFO - PID 11019 | [#4] prototype.html Cycles:180 Average:5510.87 Median:6081.35 stddev:1557.54 (25.6%) stddev-sans-first:1515.69 18:22:08 INFO - PID 11019 | Values: 493.4 504.7 518.0 522.2 527.3 6257.8 6341.2 6348.7 6354.2 6386.7 5950.0 6034.8 6055.1 6108.6 6116.5 4877.6 4916.5 4932.7 4958.9 4985.1 4973.5 4997.2 5003.9 5004.9 5018.8 4796.6 4879.2 4904.5 4939.8 4972.0 4868.2 4883.3 4892.5 4917.2 4937.3 4376.0 4394.7 4404.7 4439.9 4479.4 5982.3 6021.3 6075.2 6087.5 6134.7 5672.3 5676.5 5697.9 5708.0 5741.3 6446.4 6478.6 6508.4 6591.9 6618.7 4865.7 4870.7 4881.2 4892.1 4893.2 6927.8 6995.5 7003.2 7012.5 7041.0 4861.7 4935.9 4980.3 5042.8 5048.7 5045.3 5071.7 5080.4 5096.5 5105.7 5636.2 5711.5 5722.9 5729.3 5762.9 6538.0 6568.3 6614.0 6675.5 6679.9 1678.4 1708.4 1723.8 1731.7 1762.2 1164.9 1183.2 1203.9 1233.2 1242.7 5772.7 5777.0 5782.0 5786.8 5798.9 6570.9 6655.7 6656.8 6673.7 6733.4 5618.3 5790.2 5792.9 5807.0 5835.0 3562.5 3590.9 3613.2 3627.7 3629.0 6446.4 6447.0 6511.7 6545.5 6549.7 6866.6 6878.8 6893.0 6905.8 6953.6 6440.3 6455.9 6539.0 6550.7 6555.0 6688.9 6722.5 6742.4 6756.0 6771.0 6627.4 6665.6 6667.1 6707.2 6773.7 6525.1 6575.9 6579.7 6606.1 6609.5 4945.7 4958.6 4978.9 4988.8 5003.3 6804.5 6812.0 6817.6 6820.9 6858.6 6576.2 6602.0 6602.5 6604.9 6610.3 6452.2 6519.9 6529.7 6590.8 6621.6 6188.2 6412.6 6438.6 6468.6 6478.5 6222.8 6402.0 6501.8 6598.4 6639.7 6289.1 6290.5 6332.0 6362.7 6363.3 18:22:08 INFO - PID 11019 | 18:22:08 INFO - PID 11019 | [#5] yui.html Cycles:180 Average:22076.68 Median:19989.75 stddev:28913.11 (144.6%) stddev-sans-first:28950.38 18:22:08 INFO - PID 11019 | Values: 872.5 897.5 901.4 903.8 912.3 17931.8 18931.8 18994.7 19009.5 19015.5 19884.6 21556.2 21621.0 21622.4 21788.3 1053.6 1057.2 1063.5 1065.0 1067.4 748.3 762.7 1013.9 1055.4 1059.1 1100.2 1112.5 1112.6 1113.6 1115.6 1094.7 1098.9 1100.3 1101.9 1102.1 801.4 809.0 809.1 809.9 811.1 19431.4 19776.7 19959.0 20020.5 20021.4 20817.7 20932.0 21111.9 21246.7 21278.6 16101.4 16166.5 16278.3 16411.0 16437.8 3430.5 3460.7 3465.8 3474.1 3475.0 110767.0 114521.0 115398.8 118881.4 125970.0 16233.9 16700.3 16907.7 16919.3 16920.0 30177.2 30748.4 30769.0 30941.5 31075.4 8293.9 8644.4 8706.2 8713.3 8720.8 4154.8 4213.7 4215.4 4219.8 4222.4 4879.0 5003.1 5118.3 5943.6 5946.2 1014.7 1020.5 1036.1 1044.3 1050.3 646.9 656.9 657.0 665.7 677.5 11286.9 11410.9 11625.4 11794.6 12271.9 658.4 683.3 685.3 693.8 697.8 5283.8 5349.7 5377.9 5432.3 5528.9 96528.0 100851.5 101089.5 102805.5 103422.0 85083.6 88541.1 88997.0 91020.5 93171.0 76334.2 79075.0 79385.6 81318.2 82665.6 10738.4 10884.1 10965.7 11100.2 11225.3 34799.0 35277.8 35980.8 36190.5 38171.0 45164.8 45738.3 45801.3 45957.0 46804.5 14033.9 18079.7 18717.2 18902.0 18948.4 20952.3 21501.4 21589.8 21822.5 21880.3 18863.3 19116.0 19213.2 19243.4 19306.1 17106.8 17654.1 17783.3 18617.7 18853.4 17955.1 18213.5 18536.7 18909.5 19036.3 12715.3 13169.3 13548.9 13806.6 14115.6 13633.0 14585.5 14627.7 14650.4 14670.6 18:22:08 INFO - PID 11019 | -------- Summary: end -------- 18:22:08 INFO - PID 11019 | 18:22:08 INFO - TEST-INFO | 11019: exit 0 18:22:08 INFO - TEST-OK | dromaeo_css | took 1113271ms 18:22:08 INFO - TEST-START | kraken 18:22:08 INFO - Initialising browser for kraken test... 18:22:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43580/getInfo.html -profile /tmp/tmp1guXi9/profile 18:22:08 INFO - TEST-INFO | started process 11385 (/builds/slave/test/build/application/firefox/firefox http://localhost:43580/getInfo.html) 18:22:14 INFO - TEST-INFO | 11385: exit 0 18:22:14 INFO - Browser initialized. 18:22:14 INFO - Running cycle 1/1 for kraken test... 18:22:14 INFO - TEST-INFO | started process 11590 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1guXi9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:22:15 INFO - PID 11590 | 18:22:15 INFO - PID 11590 | (/builds/slave/test/build/application/firefox/firefox:11639): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:22:15 INFO - PID 11590 | 18:22:26 INFO - PID 11590 | RSS: Main: 171225088 18:22:26 INFO - PID 11590 | 18:22:28 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43580/tests/kraken/driver.html?testName=audio-beat-detection) 18:22:29 INFO - PID 11590 | RSS: Main: 177356800 18:22:29 INFO - PID 11590 | 18:22:32 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43580/tests/kraken/driver.html?testName=audio-dft) 18:22:33 INFO - PID 11590 | RSS: Main: 177741824 18:22:33 INFO - PID 11590 | 18:22:36 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43580/tests/kraken/driver.html?testName=audio-fft) 18:22:36 INFO - PID 11590 | RSS: Main: 170958848 18:22:36 INFO - PID 11590 | 18:22:38 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43580/tests/kraken/driver.html?testName=audio-oscillator) 18:22:39 INFO - PID 11590 | RSS: Main: 171978752 18:22:39 INFO - PID 11590 | 18:22:41 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43580/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:22:42 INFO - PID 11590 | RSS: Main: 171814912 18:22:42 INFO - PID 11590 | 18:22:45 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43580/tests/kraken/driver.html?testName=imaging-darkroom) 18:22:46 INFO - PID 11590 | RSS: Main: 174530560 18:22:46 INFO - PID 11590 | 18:22:49 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43580/tests/kraken/driver.html?testName=imaging-desaturate) 18:22:50 INFO - PID 11590 | RSS: Main: 174485504 18:22:50 INFO - PID 11590 | 18:22:52 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43580/tests/kraken/driver.html?testName=json-parse-financial) 18:22:53 INFO - PID 11590 | RSS: Main: 174694400 18:22:53 INFO - PID 11590 | 18:22:55 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43580/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:22:55 INFO - PID 11590 | RSS: Main: 174567424 18:22:55 INFO - PID 11590 | 18:22:57 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43580/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:22:58 INFO - PID 11590 | RSS: Main: 172363776 18:22:58 INFO - PID 11590 | 18:23:00 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43580/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:23:01 INFO - PID 11590 | RSS: Main: 172171264 18:23:01 INFO - PID 11590 | 18:23:03 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43580/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:23:04 INFO - PID 11590 | RSS: Main: 172552192 18:23:04 INFO - PID 11590 | 18:23:07 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43580/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:23:08 INFO - PID 11590 | RSS: Main: 172765184 18:23:08 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | Cycle 1(1): loaded http://localhost:43580/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43580/tests/kraken/driver.html?testName=ai-astar) 18:23:10 INFO - PID 11590 | RSS: Main: 174112768 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | __start_tp_report 18:23:10 INFO - PID 11590 | _x_x_mozilla_page_load 18:23:10 INFO - PID 11590 | _x_x_mozilla_page_load_details 18:23:10 INFO - PID 11590 | |i|pagename|runs| 18:23:10 INFO - PID 11590 | |0;ai-astar;99;107;104;108;110;108;107;100;110;111 18:23:10 INFO - PID 11590 | |1;audio-beat-detection;160;118;152;169;123;152;146;122;178;124 18:23:10 INFO - PID 11590 | |2;audio-dft;164;164;163;162;163;166;166;165;168;163 18:23:10 INFO - PID 11590 | |3;audio-fft;77;77;75;186;77;74;76;74;156;75 18:23:10 INFO - PID 11590 | |4;audio-oscillator;80;79;128;79;79;79;141;79;79;125 18:23:10 INFO - PID 11590 | |5;imaging-gaussian-blur;115;114;115;114;114;114;114;114;114;114 18:23:10 INFO - PID 11590 | |6;imaging-darkroom;152;153;152;151;150;150;150;150;149;151 18:23:10 INFO - PID 11590 | |7;imaging-desaturate;94;94;94;95;94;94;94;94;94;94 18:23:10 INFO - PID 11590 | |8;json-parse-financial;57;56;58;58;59;57;57;57;57;58 18:23:10 INFO - PID 11590 | |9;json-stringify-tinderbox;55;55;55;54;55;54;54;55;55;55 18:23:10 INFO - PID 11590 | |10;stanford-crypto-aes;80;84;81;82;79;81;81;81;81;81 18:23:10 INFO - PID 11590 | |11;stanford-crypto-ccm;142;131;136;129;134;135;132;129;133;165 18:23:10 INFO - PID 11590 | |12;stanford-crypto-pbkdf2;196;198;184;182;191;178;180;186;185;185 18:23:10 INFO - PID 11590 | |13;stanford-crypto-sha256-iterative;71;68;72;68;68;68;66;68;67;66 18:23:10 INFO - PID 11590 | __end_tp_report 18:23:10 INFO - PID 11590 | __start_cc_report 18:23:10 INFO - PID 11590 | _x_x_mozilla_cycle_collect,951 18:23:10 INFO - PID 11590 | __end_cc_report 18:23:10 INFO - PID 11590 | __startTimestamp1501291390337__endTimestamp 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | ------- Summary: start ------- 18:23:10 INFO - PID 11590 | Number of tests: 14 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#0] ai-astar Cycles:10 Average:106.40 Median:109.00 stddev:4.14 (3.8%) stddev-sans-first:3.42 18:23:10 INFO - PID 11590 | Values: 99.0 107.0 104.0 108.0 110.0 108.0 107.0 100.0 110.0 111.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#1] audio-beat-detection Cycles:10 Average:144.40 Median:152.00 stddev:21.52 (14.2%) stddev-sans-first:22.07 18:23:10 INFO - PID 11590 | Values: 160.0 118.0 152.0 169.0 123.0 152.0 146.0 122.0 178.0 124.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.50 stddev:1.84 (1.1%) stddev-sans-first:1.94 18:23:10 INFO - PID 11590 | Values: 164.0 164.0 163.0 162.0 163.0 166.0 166.0 165.0 168.0 163.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#3] audio-fft Cycles:10 Average:94.70 Median:75.50 stddev:40.85 (54.1%) stddev-sans-first:42.82 18:23:10 INFO - PID 11590 | Values: 77.0 77.0 75.0 186.0 77.0 74.0 76.0 74.0 156.0 75.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.00 stddev:25.53 (32.3%) stddev-sans-first:26.51 18:23:10 INFO - PID 11590 | Values: 80.0 79.0 128.0 79.0 79.0 79.0 141.0 79.0 79.0 125.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 18:23:10 INFO - PID 11590 | Values: 115.0 114.0 115.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:1.23 (0.8%) stddev-sans-first:1.22 18:23:10 INFO - PID 11590 | Values: 152.0 153.0 152.0 151.0 150.0 150.0 150.0 150.0 149.0 151.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:23:10 INFO - PID 11590 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:57.50 stddev:0.84 (1.5%) stddev-sans-first:0.88 18:23:10 INFO - PID 11590 | Values: 57.0 56.0 58.0 58.0 59.0 57.0 57.0 57.0 57.0 58.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#9] json-stringify-tinderbox Cycles:10 Average:54.70 Median:55.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 18:23:10 INFO - PID 11590 | Values: 55.0 55.0 55.0 54.0 55.0 54.0 54.0 55.0 55.0 55.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:81.00 stddev:1.29 (1.6%) stddev-sans-first:1.30 18:23:10 INFO - PID 11590 | Values: 80.0 84.0 81.0 82.0 79.0 81.0 81.0 81.0 81.0 81.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#11] stanford-crypto-ccm Cycles:10 Average:136.60 Median:134.50 stddev:10.68 (7.9%) stddev-sans-first:11.15 18:23:10 INFO - PID 11590 | Values: 142.0 131.0 136.0 129.0 134.0 135.0 132.0 129.0 133.0 165.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.50 Median:185.50 stddev:6.57 (3.5%) stddev-sans-first:6.00 18:23:10 INFO - PID 11590 | Values: 196.0 198.0 184.0 182.0 191.0 178.0 180.0 186.0 185.0 185.0 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:1.93 (2.8%) stddev-sans-first:1.76 18:23:10 INFO - PID 11590 | Values: 71.0 68.0 72.0 68.0 68.0 68.0 66.0 68.0 67.0 66.0 18:23:10 INFO - PID 11590 | -------- Summary: end -------- 18:23:10 INFO - PID 11590 | 18:23:10 INFO - PID 11590 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:23:11 INFO - TEST-INFO | 11590: exit 0 18:23:11 INFO - TEST-OK | kraken | took 62268ms 18:23:11 INFO - SUITE-END | took 1175s 18:23:11 INFO - Completed test suite (00:19:35) 18:23:11 INFO - javascript benchmark 18:23:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8417.524162277314, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3134.357456721371, 3173.12738996776, 3197.8560964756584, 3198.504231844064, 3213.3412650406653, 6156.692165391735, 6229.595076320036, 6260.311365749764, 6279.372062793724, 6281.748730050804, 6345.460635845947, 6353.618782873019, 6354.7775827846035, 6379.553431259814, 6418.743250269995, 5726.713664316778, 5800.680962547058, 5801.796937107202, 5869.031609784381, 5910.143029260755, 5705.885882282352, 5709.486146246837, 5717.485426311631, 5826.504747096502, 5828.5337173026055, 5710.343310519302, 5759.798407055754, 5764.51001664859, 5802.912956305659, 5821.3305469870875, 5734.827955161351, 5743.712814359307, 5779.9422005779825, 5788.334341550733, 5795.391483894173, 5589.0, 5634.521065709419, 5666.631668941505, 5675.489205971482, 5772.855678608026, 6124.877502449971, 6159.045347971071, 6182.907256391158, 6206.968965155191, 6213.2233470816145, 6784.898226526606, 6820.863582728318, 6834.111565496453, 6839.692213850339, 6862.794116176523, 6828.2488926218075, 6834.316568343175, 6838.179418469766, 6908.758193463204, 6909.896351554731, 6285.402886725754, 6310.621362718298, 6383.265924418658, 6385.712642931079, 6424.839379015515, 6874.140732408449, 6903.171619405603, 6907.274736152732, 6911.447084233249, 6989.825254368681, 6484.416402523795, 6561.114249576246, 6572.211334639778, 6612.206535215805, 6655.301193374723, 6744.5278830482375, 6747.797566073025, 6751.122354093936, 6758.594484330956, 6763.188417389944, 6648.5346025777735, 6658.101156343929, 6690.2306234782645, 6705.966470167618, 6737.023131645884, 6754.5609535380045, 6760.425363844129, 6765.458763298924, 6800.591964482147, 6892.758753443605, 5385.542228910508, 5410.756515956791, 5463.48096930791, 5473.343198816088, 5493.615446918758, 4585.747783871919, 4616.907661846744, 4646.233371493666, 4671.532846715301, 4721.976390118028, 5888.587798854125, 5895.115732640138, 5896.557758168155, 5902.675352855634, 5920.585559010828, 6798.93201067983, 6881.587104773629, 6897.862042759117, 6903.482238832108, 6908.205556360982, 5848.5321174305955, 5865.736041878125, 5945.672987985616, 5953.880922381528, 5963.463288303987, 5821.79623713168, 5913.733881975236, 5914.260717410324, 5915.024021036481, 6012.819615411545, 6536.084948107269, 6536.379043990908, 6573.046908198285, 6574.572652777554, 6575.178102737158, 6640.0, 6740.797776066725, 6822.590644561342, 6822.795316140524, 6826.829329266808, 6221.657808820558, 6555.049517819984, 6596.571222870498, 6640.83397915056, 6690.19717633887, 6661.1007513985, 6675.8664826702225, 6763.0, 6768.763093291711, 6780.288069752605, 6750.088738020305, 6768.255491895986, 6849.1780986281965, 6885.208201056943, 6915.792526224222, 6578.144841170617, 6596.60420374779, 6596.7031483584315, 6597.241317248578, 6604.306547812603, 6547.214334279917, 6581.144451221502, 6593.439557637655, 6602.40578347951, 6605.174353205849, 6474.967625161844, 6481.611103333815, 6506.512011599244, 6515.73937042532, 6558.114654521579, 6612.603243805387, 6656.167979002625, 6680.298568650222, 6755.3582409671, 6783.864322713617, 6332.208473940757, 6434.388733070351, 6443.162388889415, 6445.581037232752, 6510.121133646897], "value": 6170.422937365524, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.7052661042028, 850.8808766772652, 871.7384784564631, 875.4572165257541, 1192.791261529232, 30379.392412151745, 41278.58721412785, 41814.0, 42068.789656051755, 42074.15851682968, 42935.57064429355, 43163.56836431635, 43214.7839260804, 43488.347674784905, 43568.3464748029, 7677.616119194032, 7830.491018083835, 7832.921670783277, 7834.647440865175, 7846.0, 7644.082710074771, 7724.884126738103, 7794.805129871742, 7853.371730261565, 7859.921400785976, 7620.276073772982, 7662.310392064713, 7770.57261850598, 7780.338671212954, 7782.883256751154, 7691.961540192319, 7711.383089352838, 7743.148253692089, 7755.069391673007, 7756.0, 7603.087629484442, 7725.381969442431, 7818.8827167592535, 7818.921810781933, 7822.569758663271, 39191.216175676615, 39588.40617390742, 39663.20673586541, 39746.60253397487, 39761.80119099386, 3575.3385623659706, 3638.745287829853, 3645.7265705072227, 3645.963540364589, 3646.6171052039417, 31201.687983120104, 31233.8438307807, 31370.21574460634, 31466.685333146605, 31468.527972080436, 4829.9034019319415, 4846.079244943449, 4846.079244943485, 4889.926651100236, 4903.730294833783, 188800.0, 190181.0, 191148.9999999986, 191302.0, 191581.0, 49201.26198107031, 49233.507664922894, 49367.0, 49432.75283623631, 49560.75219623879, 52673.473265267625, 52886.0, 52923.73538132285, 53137.7343113282, 53248.20127698088, 15589.688206236038, 15632.765508517381, 15647.295871685801, 15647.452339168074, 15662.138582378078, 12807.61577152687, 12815.871841281469, 12830.358482075859, 12853.485860565472, 12853.93573032129, 14586.41654333836, 15011.324490397958, 15340.616484587752, 15503.767443488357, 15532.611684707972, 5804.883902322014, 6187.16473276111, 6214.9689251553455, 6220.720067596879, 6280.120783090371, 966.5022513405602, 983.1053741095569, 990.8315586350337, 1004.42747633848, 1005.7686732051669, 22939.72909403789, 23089.42276443069, 23285.0, 23345.649815252786, 23954.161604343764, 1066.5413872034944, 1085.2039874008528, 1101.9504122314515, 1105.4030823355458, 1110.2672236323988, 11887.952404561229, 11995.0, 12237.93881030607, 15266.0, 15334.0, 219196.0, 220236.0, 221441.0, 222547.0, 223061.0, 57120.428795712345, 57304.14043789347, 57686.42313576894, 57776.0, 57842.7107864458, 1446.1250943179436, 1457.511733569245, 1498.3257534109694, 1512.4933147395525, 1512.8335883052855, 26530.0, 26816.86591567108, 27078.593821093094, 27235.86382068077, 27406.314842128308, 38459.23081538409, 38632.61367386403, 38646.2270754589, 38689.0, 38886.41670375003, 194497.0, 195554.0, 195913.0, 196280.0, 196337.0, 32628.184295391857, 33615.495767563014, 33725.49411758874, 33747.325053499284, 34333.25333973326, 42737.57262427336, 42856.142877142905, 42864.0, 43266.35100473432, 43340.13319733524, 28543.571846422736, 29511.55732663969, 30179.0, 30409.847950760104, 30509.084727458212, 27814.582781257894, 27873.303167420978, 29730.702692972794, 29875.25311867134, 30207.848960755055, 37994.240115198096, 38333.23333533258, 38989.2202155961, 39224.41163382609, 39590.802045989585, 31200.0, 33701.15747106342, 33958.83020584881, 33961.66038339585, 34177.0, 24046.27861164168, 24661.26016219516, 25026.0, 25304.0, 25614.10350637719], "value": 17424.95183955358, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.9653220806752, 583.4457265597681, 584.3396961433584, 587.8706684529403, 600.5885968111844, 22091.668624970618, 22546.0, 23247.418814529647, 23255.651165232513, 23273.418164545896, 25449.491010179787, 25585.104521341756, 25684.74315256847, 25799.484010319782, 25920.48159036818, 6380.106785050097, 6579.835504112388, 6589.143411356541, 6600.339966003409, 6656.833579160511, 5740.7703691852485, 6338.904916426257, 6359.8092057238355, 6396.168498095241, 6467.094606755058, 6295.5907865988565, 6297.811065668015, 6302.212223472066, 6303.716332765013, 6308.4322410983, 6291.842703932392, 6301.432871041606, 6304.464120549759, 6304.810855674337, 6312.589681670676, 5845.2401187845735, 5870.589058765845, 5876.382979787147, 5884.970575147139, 5895.793647222326, 28621.42757144866, 28689.0, 28930.0, 29074.27314317138, 29261.268468288465, 1852.8147185281498, 1909.76127984002, 1914.329984505426, 1919.9520011999673, 1921.423572928116, 18783.90608046951, 19395.030248487517, 19496.80503194978, 19558.70661940072, 19562.31531896391, 2304.8962796674023, 2358.622620380731, 2392.676988606533, 2406.795422389099, 2436.0864675746593, 131504.0, 132522.0, 133963.0, 134947.6505234945, 135290.3235483826, 14254.287285635677, 14920.850791492163, 15025.398984040734, 15054.042974413474, 15572.766408503881, 22961.425964351023, 23175.0, 23205.187818426613, 23216.65175022376, 23254.7674523256, 10113.797724045478, 10272.794544109076, 10410.323328983592, 10428.113610343205, 10555.63055293061, 9673.419594824332, 9696.17582505495, 9757.951210243904, 9901.554430050664, 9942.453165075991, 8857.955710221408, 9144.3598948073, 9170.770730731785, 9431.0, 9515.524223788783, 2666.440047589989, 2720.3607152319587, 2730.153652367733, 2785.2758282846607, 2795.916122516328, 905.1129892705186, 923.3075193604797, 934.6495064350869, 941.6045260990263, 955.3885513271512, 18300.48374845073, 18843.0, 18881.90559047196, 19236.0, 19475.610487790163, 986.4771671014374, 986.7582442301682, 999.8300288950895, 1016.5781200801732, 1019.4851599941982, 10334.741631459123, 10482.528286227138, 10621.415822129702, 10655.147588192925, 10805.13558915285, 449439.0, 464374.0, 474551.0, 475722.0, 481808.0, 112106.0, 113068.43465782783, 114017.0, 114812.42593786978, 115523.0, 1191.7854786138405, 1194.510250797169, 1204.379744431636, 1219.2196993923897, 1221.2367270455966, 16800.32798688088, 16930.746038809182, 19802.90098549498, 21399.57200855943, 21426.250081247083, 54875.72562137164, 56789.43210567842, 57978.710106449194, 58177.0, 59819.70090149521, 66799.66600166968, 68519.0, 68566.314336856, 68625.3137468619, 68952.6552367235, 12249.816252756395, 12855.678608034874, 14750.114993100447, 14983.625409364853, 15055.698886022437, 18581.16384762662, 21925.451863703536, 22812.31563053111, 24094.277171684877, 24418.51162976766, 17750.644987100444, 18598.721019184995, 18969.43091707251, 19045.666803323635, 19152.904235478734, 13416.195028298333, 14590.0, 14652.340644670801, 14752.63118422048, 14886.255687215813, 18643.0, 18981.050947452295, 19287.0, 19397.70903436478, 19466.513337166118, 14688.706225875636, 14796.334164962387, 14840.480583179537, 14862.182579958206, 14959.850401496282, 18627.0, 18630.347937822113, 18777.0, 18862.905685471484, 18902.7164592534], "value": 11820.391089323019, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.4862228042205, 1386.8682475164856, 1420.2046853761901, 1424.3163281624813, 1427.9714405711882, 11723.179377443561, 13506.18962862229, 14113.92943035286, 14151.57545273642, 14156.575302740908, 11732.35472049037, 11933.045356371491, 13653.590392288223, 14033.15801051935, 14044.087134336287, 2589.2750029991585, 2637.512060268856, 2652.5755879059357, 2661.787057035442, 2668.519666460036, 2630.5659566171557, 2707.6073969274444, 2714.1179116787025, 2716.3209197700576, 2717.4836781011645, 2626.5272250994813, 2662.347724807425, 2666.6666666666665, 2682.0210623122516, 2686.9462610747773, 2595.8702064896775, 2616.869156542175, 2628.54000549903, 2630.93422664433, 2631.723669014745, 2564.1410127607273, 2564.294818924792, 2565.8203925725206, 2581.3675649465813, 2582.496413199427, 13487.797683034762, 13602.863971360259, 13630.454781808716, 13644.658883527893, 13652.24912629805, 7425.480216384855, 7631.732889348902, 7678.462507624468, 7702.229777022309, 7725.652345644459, 4774.116788394123, 4812.927806082912, 4907.362042934431, 5084.110280700863, 5090.974545127251, 1867.2344338821154, 1886.2455017992775, 1902.581432084939, 1914.9042547872689, 1949.3664559018375, 22749.31752047441, 22835.771642283366, 22882.65676014861, 22892.65661015086, 22919.885400573057, 6329.715162817684, 6611.107500487469, 6640.236372817188, 6807.2512023677355, 6822.454203663695, 17697.203625836868, 17713.911430442764, 17716.936983780757, 17722.91138544299, 17739.37912173068, 7448.292412220831, 7466.775996720107, 7479.0, 7482.401407887356, 7492.063492063492, 5679.4320567943705, 5933.465988061095, 6192.318844927055, 6206.0, 6750.561213521106, 5201.933603611251, 5352.892942141136, 5420.159875219348, 5472.480114389126, 5504.862378440571, 1632.6000129967997, 1639.7540368944753, 1647.5881029742563, 1648.4972083514403, 1666.208550938319, 835.2315869400167, 847.3221422861686, 852.2159613155911, 871.4030888841164, 891.3404080980029, 6775.525713200127, 6969.7909062728195, 7173.390261827803, 7230.276972302833, 7255.709771609182, 662.6587307536622, 706.4313227851629, 709.9325564071405, 711.4983936324878, 721.0770214125927, 3938.4092386142306, 4065.0, 4119.526254480713, 4189.392538081961, 4211.1998720243055, 17710.911445442947, 18158.636827263646, 18482.0, 18504.16731247097, 18548.536286592673, 18464.261429542938, 18522.629547408975, 18553.721694174597, 18562.25750969973, 18603.720944185847, 10515.631952881766, 10563.63027294041, 10567.101796347397, 10591.523381447852, 10600.734981625521, 6932.896006560007, 7571.659275332513, 7619.65711542993, 7825.804354891174, 7939.642716077906, 16036.358545658042, 16445.835541644432, 16503.257353419365, 16537.007779532785, 16541.834581654028, 16090.356385744439, 16470.176491175633, 16574.502764917073, 16908.32366705318, 16977.49067527976, 12788.872111278768, 13007.609771706864, 13183.53857614979, 13192.0, 13201.867981320063, 12465.626031219077, 13110.541131060367, 13150.0, 13250.734985300047, 13260.138091024439, 9929.255305851946, 10270.743231419274, 10391.37651740898, 10548.947255263674, 10653.786924261316, 10336.638217662347, 10492.94753526258, 10507.47462626881, 10578.206634502598, 10637.414942178255, 8607.053224145213, 8758.737237882873, 8827.382083254111, 8842.425242359226, 8864.290856731575, 6528.086067950396, 6718.395344419026, 6768.153980752406, 6850.21222559412, 6917.412019978358, 6540.411362977355, 6568.868622627616, 6745.831354216184, 7020.157581090302, 7126.714931402686], "value": 6197.560902324983, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.3684883349312, 504.74762618690664, 517.9559737422319, 522.2218893848166, 527.2934268080771, 6257.8122656320265, 6341.175647165867, 6348.682565871705, 6354.205724284465, 6386.680665966706, 5949.970250148754, 6034.788782392618, 6055.061465472848, 6108.602940808855, 6116.4800991915745, 4877.609791216694, 4916.50834916509, 4932.679375840567, 4958.925616115761, 4985.052839960414, 4973.52751488608, 4997.225430058346, 5003.874903127415, 5004.949950500485, 5018.824341148061, 4796.616270698353, 4879.194932836078, 4904.48502907195, 4939.8024079036795, 4971.950280497203, 4868.245421959602, 4883.316335713003, 4892.535209155125, 4917.164082106033, 4937.333459982892, 4375.956240437618, 4394.670399720034, 4404.7357158570585, 4439.933400998988, 4479.4176757021705, 5982.282126144847, 6021.277446706379, 6075.17985072014, 6087.512998960073, 6134.69326533676, 5672.319321681383, 5676.545876329884, 5697.914531282034, 5708.0, 5741.25363702716, 6446.355364463563, 6478.643674597895, 6508.4142427181305, 6591.901121483182, 6618.7021584028835, 4865.683730557503, 4870.683405578626, 4881.219004959189, 4892.0705066037335, 4893.192623217201, 6927.826804329881, 6995.475339349569, 7003.2296447390745, 7012.474064445187, 7040.964795176043, 4861.683990540603, 4935.925961110586, 4980.302757613937, 5042.823501177441, 5048.722320272347, 5045.31888195089, 5071.721055341918, 5080.441151473335, 5096.541311282002, 5105.723569107723, 5636.154576813511, 5711.514521265739, 5722.942770572325, 5729.341125770507, 5762.9135562966585, 6537.986612075136, 6568.277489476154, 6614.040964060185, 6675.532712710064, 6679.866402672016, 1678.387388603151, 1708.436216048701, 1723.8448539631493, 1731.7488964100387, 1762.207006846924, 1164.9359285239223, 1183.2486371154207, 1203.9157258991786, 1233.210745123122, 1242.7203879127123, 5772.711364431846, 5777.0, 5782.0, 5786.826395208151, 5798.9420105799245, 6570.868582628416, 6655.667216639149, 6656.800295991031, 6673.666316684146, 6733.397632266811, 5618.325800903917, 5790.218320526759, 5792.913106303409, 5806.970965145232, 5835.0, 3562.4656301554974, 3590.8743193988084, 3613.1689711366535, 3627.7097832173363, 3628.9818550907603, 6446.419822216023, 6446.967765161238, 6511.706973186218, 6545.541812073109, 6549.738010479623, 6866.553674011173, 6878.759243426456, 6892.965535172292, 6905.75829845953, 6953.617551034742, 6440.291567927524, 6455.903161452488, 6538.967305163539, 6550.659163826483, 6554.9672251639395, 6688.899666505104, 6722.495812813961, 6742.359475849787, 6756.0, 6770.966145169243, 6627.403533681992, 6665.633390163393, 6667.099746648071, 6707.195136583641, 6773.6951837168435, 6525.086487891604, 6575.882100042909, 6579.651171509925, 6606.10817539626, 6609.504287178384, 4945.714114330228, 4958.636399968005, 4978.929530151035, 4988.75056247179, 5003.324551185543, 6804.523683342317, 6811.9659401702675, 6817.625030623363, 6820.897686534805, 6858.6227757471825, 6576.243731970884, 6602.0, 6602.471802255904, 6604.867902641824, 6610.338966103352, 6452.22573291208, 6519.902201466887, 6529.669868367072, 6590.8352291193105, 6621.569597976116, 6188.226471691039, 6412.583182093335, 6438.645874476949, 6468.579542329733, 6478.514111441755, 6222.844428889314, 6402.0, 6501.804945851632, 6598.37315455031, 6639.70525747494, 6289.14470231292, 6290.46531044848, 6332.0, 6362.713677884413, 6363.268224154282], "value": 5050.789061009142, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [872.5419154943654, 897.5467388968572, 901.4050726520496, 903.7740564858781, 912.325882006518, 17931.82068179318, 18931.810681893177, 18994.71507927382, 19009.52476188096, 19015.524611884674, 19884.60230795383, 21556.245531406403, 21621.027053782578, 21622.351329460103, 21788.3463496095, 1053.5627714498494, 1057.2440704895998, 1063.5107850388831, 1064.973375665607, 1067.4075887882223, 748.3040772081962, 762.6872982077349, 1013.9138173255245, 1055.3826011783117, 1059.099765199582, 1100.2408338246623, 1112.454897100423, 1112.571659910936, 1113.5545781687308, 1115.6262652011546, 1094.68801391603, 1098.8571485706848, 1100.2958106811607, 1101.9063379612742, 1102.1403305421784, 801.410962942237, 808.9676412943476, 809.1180613131656, 809.8542262392767, 811.0834756724922, 19431.4170574883, 19776.70334944977, 19959.0, 20020.49948751278, 20021.399358019284, 20817.68773468399, 20932.0, 21111.894440527853, 21246.681299780517, 21278.574428511496, 16101.355945762156, 16166.515004549883, 16278.26747796352, 16411.0, 16437.753433698505, 3430.451127819562, 3460.653934606544, 3465.7747246429153, 3474.1488335358, 3475.0269924421204, 110767.0, 114521.0, 115398.84601153964, 118881.40559297234, 125970.0, 16233.918830406008, 16700.331986720394, 16907.746383804253, 16919.323227071025, 16920.0, 30177.245568860955, 30748.38503229923, 30769.0, 30941.535876961865, 31075.378492430023, 8293.875591866006, 8644.351673624378, 8706.172913573328, 8713.3464990126, 8720.825583488295, 4154.750714957112, 4213.6839737019845, 4215.430916826189, 4219.810108545123, 4222.4299719538085, 4878.975605121952, 5003.074431230161, 5118.25785261135, 5943.613665111754, 5946.167536544887, 1014.6702321745462, 1020.4642562654517, 1036.0571879589688, 1044.2637940252105, 1050.3015494695942, 646.9417752402306, 656.9244536878225, 656.957297775643, 665.6971078159426, 677.4749569083999, 11286.887131128748, 11410.885891140982, 11625.418729063513, 11794.587189448328, 12271.877281227073, 658.4386013255794, 683.2723149845399, 685.266764561914, 693.8438851258527, 697.7767114523406, 5283.788648454096, 5349.732513374393, 5377.865553361197, 5432.29380180574, 5528.889422211533, 96528.0, 100851.49574252228, 101089.49455252677, 102805.48597256966, 103422.0, 85083.57458212669, 88541.11458885329, 88997.0, 91020.54489727509, 93171.0, 76334.23665763272, 79075.0, 79385.60307198427, 81318.18681813106, 82665.58667206626, 10738.355698657793, 10884.129269658566, 10965.725856853642, 11100.222984391337, 11225.270357426742, 34799.0, 35277.82361088178, 35980.820095899355, 36190.45714314235, 38171.0, 45164.77417612891, 45738.32895347004, 45801.312980305986, 45957.0, 46804.53195468002, 14033.859661403256, 18079.728804067687, 18717.157727902006, 18902.0, 18948.43154705361, 20952.266670667064, 21501.354959351243, 21589.784102158777, 21822.45443863916, 21880.343589692333, 18863.339783107524, 19116.0, 19213.231470741015, 19243.422697319103, 19306.131224094668, 17106.82893171102, 17654.117294134983, 17783.288668453635, 18617.720734189268, 18853.434396968114, 17955.102244887963, 18213.45359639213, 18536.721949170504, 18909.527261818566, 19036.33372831944, 12715.300658463873, 13169.275689837152, 13548.916086713236, 13806.585802425943, 14115.576532704035, 13633.0, 14585.489507867174, 14627.707445851236, 14650.413983440543, 14670.633234168805], "value": 8940.889327327846, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 104.0, 108.0, 110.0, 108.0, 107.0, 100.0, 110.0, 111.0], "value": 106.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 118.0, 152.0, 169.0, 123.0, 152.0, 146.0, 122.0, 178.0, 124.0], "value": 144.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 163.0, 162.0, 163.0, 166.0, 166.0, 165.0, 168.0, 163.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 75.0, 186.0, 77.0, 74.0, 76.0, 74.0, 156.0, 75.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 128.0, 79.0, 79.0, 79.0, 141.0, 79.0, 79.0, 125.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 152.0, 151.0, 150.0, 150.0, 150.0, 150.0, 149.0, 151.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 58.0, 58.0, 59.0, 57.0, 57.0, 57.0, 57.0, 58.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 55.0, 54.0, 54.0, 55.0, 55.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 81.0, 82.0, 79.0, 81.0, 81.0, 81.0, 81.0, 81.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 131.0, 136.0, 129.0, 134.0, 135.0, 132.0, 129.0, 133.0, 165.0], "value": 136.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 198.0, 184.0, 182.0, 191.0, 178.0, 180.0, 186.0, 185.0, 185.0], "value": 186.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 72.0, 68.0, 68.0, 68.0, 66.0, 68.0, 67.0, 66.0], "value": 68.2, "unit": "score"}]}]} 18:23:11 INFO - Return code: 0 18:23:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:23:11 INFO - # TBPL SUCCESS # 18:23:11 INFO - Running post-action listener: _package_coverage_data 18:23:11 INFO - Running post-action listener: _resource_record_post_action 18:23:11 INFO - [mozharness: 2017-07-29 01:23:11.627717Z] Finished run-tests step (success) 18:23:11 INFO - Running post-run listener: _resource_record_post_run 18:23:11 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 12288; Write bytes: 438538240; Read time: 448; Write time: 439988 18:23:11 INFO - TinderboxPrint: CPU usage
13.1% 18:23:11 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 448 18:23:11 INFO - TinderboxPrint: I/O write bytes / time
438,538,240 / 439,988 18:23:11 INFO - TinderboxPrint: CPU idle
8,270.5 (86.9%) 18:23:11 INFO - TinderboxPrint: CPU user
1,199.5 (12.6%) 18:23:11 INFO - TinderboxPrint: Swap in / out
0 / 0 18:23:11 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 115355648; Read time: 144; Write time: 110724 18:23:11 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:23:11 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 323182592; Read time: 288; Write time: 329264 18:23:12 INFO - Running post-run listener: _upload_blobber_files 18:23:12 INFO - Blob upload gear active. 18:23:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:23:12 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 18:23:12 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'] 18:23:12 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 18:23:12 INFO - (blobuploader) - INFO - Open directory for files ... 18:23:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:23:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:23:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:13 INFO - (blobuploader) - INFO - Done attempting. 18:23:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:23:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:23:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:20 INFO - (blobuploader) - INFO - Done attempting. 18:23:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:23:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:23:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:21 INFO - (blobuploader) - INFO - Done attempting. 18:23:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:23:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:23:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:22 INFO - (blobuploader) - INFO - Done attempting. 18:23:22 INFO - (blobuploader) - INFO - Iteration through files over. 18:23:22 INFO - Return code: 0 18:23:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:23:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:23:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fa6238e8e27bbcc148ee1e9b544192dd17b02668f41933771e9e98f32304d4e445574526264d0c8883601447ed4afe59c712fede4cead6da90dcb332ffe5889c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd8caf7eb94157b7528b8cfe1181f59ca86d19c83841e1fbc5d4147b793d061f845aed561afbce412eb6548eac77e436c04d0070294f41ad22488650eb96ebce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4b11c6de74383fafecd07408ab8c2064b6d0ae64339373902925527ac04ecb3863f44fc23570e1a4aaf77689e5845f66d9f64c74e453e1235faa9d318d180282", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62579d11f1b176d78cff6a819c0406ac3a1d3a4ad62ea16c018aee1c2bed0a88d4dc185e3c325d98b348d9ff840a22292418f25172dc5c60f3c6c733d273dc24"} 18:23:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:23:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:23:22 INFO - Contents: 18:23:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fa6238e8e27bbcc148ee1e9b544192dd17b02668f41933771e9e98f32304d4e445574526264d0c8883601447ed4afe59c712fede4cead6da90dcb332ffe5889c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd8caf7eb94157b7528b8cfe1181f59ca86d19c83841e1fbc5d4147b793d061f845aed561afbce412eb6548eac77e436c04d0070294f41ad22488650eb96ebce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4b11c6de74383fafecd07408ab8c2064b6d0ae64339373902925527ac04ecb3863f44fc23570e1a4aaf77689e5845f66d9f64c74e453e1235faa9d318d180282", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62579d11f1b176d78cff6a819c0406ac3a1d3a4ad62ea16c018aee1c2bed0a88d4dc185e3c325d98b348d9ff840a22292418f25172dc5c60f3c6c733d273dc24"} 18:23:22 INFO - Running post-run listener: copy_logs_to_upload_dir 18:23:22 INFO - Copying logs to upload dir... 18:23:22 INFO - mkdir: /builds/slave/test/build/upload/logs 18:23:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.668608 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-07-28 18:23:22.383883) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:22.387405) ========= 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=4300f881736b422476174874000001bc-1501285988.489652-1908195001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fa6238e8e27bbcc148ee1e9b544192dd17b02668f41933771e9e98f32304d4e445574526264d0c8883601447ed4afe59c712fede4cead6da90dcb332ffe5889c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd8caf7eb94157b7528b8cfe1181f59ca86d19c83841e1fbc5d4147b793d061f845aed561afbce412eb6548eac77e436c04d0070294f41ad22488650eb96ebce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4b11c6de74383fafecd07408ab8c2064b6d0ae64339373902925527ac04ecb3863f44fc23570e1a4aaf77689e5845f66d9f64c74e453e1235faa9d318d180282", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62579d11f1b176d78cff6a819c0406ac3a1d3a4ad62ea16c018aee1c2bed0a88d4dc185e3c325d98b348d9ff840a22292418f25172dc5c60f3c6c733d273dc24"} build_url:https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011316 build_url: 'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fa6238e8e27bbcc148ee1e9b544192dd17b02668f41933771e9e98f32304d4e445574526264d0c8883601447ed4afe59c712fede4cead6da90dcb332ffe5889c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd8caf7eb94157b7528b8cfe1181f59ca86d19c83841e1fbc5d4147b793d061f845aed561afbce412eb6548eac77e436c04d0070294f41ad22488650eb96ebce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4b11c6de74383fafecd07408ab8c2064b6d0ae64339373902925527ac04ecb3863f44fc23570e1a4aaf77689e5845f66d9f64c74e453e1235faa9d318d180282", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/62579d11f1b176d78cff6a819c0406ac3a1d3a4ad62ea16c018aee1c2bed0a88d4dc185e3c325d98b348d9ff840a22292418f25172dc5c60f3c6c733d273dc24"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:22.411657) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:22.411990) ========= 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=4300f881736b422476174874000001bc-1501285988.489652-1908195001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004423 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:22.497084) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-28 18:23:22.497400) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-28 18:23:22.497700) ========= ========= Total master_lag: 0.20 =========