builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-044 starttime: 1501290124.23 results: success (0) revision: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:04.234018) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:04.234474) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:04.234747) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:04.327432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:04.327725) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-28 18:02:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 34.3M=0s 2017-07-28 18:02:04 (34.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130731 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:04.512406) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:04.512745) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019927 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:04.565214) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-07-28 18:02:04.569617) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-28 18:02:04,997 truncating revision to first 12 chars 2017-07-28 18:02:04,997 Setting DEBUG logging. 2017-07-28 18:02:04,997 attempt 1/10 2017-07-28 18:02:04,997 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:06,233 attempt 1/10 2017-07-28 18:02:06,796 current task status: no status available at this point. state: STARTED 2017-07-28 18:02:06,797 sleeping for 9.00s (attempt 1/10) 2017-07-28 18:02:15,806 attempt 2/10 2017-07-28 18:02:16,142 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-07-28 18:02:16,330 unpacking tar archive at: mozilla-central-ec329722b2f8/testing/mozharness/ program finished with exit code 0 elapsedTime=11.639854 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-07-28 18:02:16.221201) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:16.224517) ========= script_repo_revision: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:16.224896) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:16.225149) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:16.233894) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-07-28 18:02:16.234200) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:02:16 INFO - MultiFileLogger online at 20170728 18:02:16 in /builds/slave/test 18:02:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:02:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:02:16 INFO - {'append_to_log': False, 18:02:16 INFO - 'base_work_dir': '/builds/slave/test', 18:02:16 INFO - 'blob_upload_branch': 'Firefox', 18:02:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:02:16 INFO - 'branch': 'Firefox', 18:02:16 INFO - 'buildbot_json_path': 'buildprops.json', 18:02:16 INFO - 'code_coverage': False, 18:02:16 INFO - 'config_files': ('talos/linux_config.py',), 18:02:16 INFO - 'default_actions': ('clobber', 18:02:16 INFO - 'read-buildbot-config', 18:02:16 INFO - 'download-and-extract', 18:02:16 INFO - 'populate-webroot', 18:02:16 INFO - 'create-virtualenv', 18:02:16 INFO - 'install', 18:02:16 INFO - 'setup-mitmproxy', 18:02:16 INFO - 'run-tests'), 18:02:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:02:16 INFO - 'disable_ccov_upload': False, 18:02:16 INFO - 'download_minidump_stackwalk': True, 18:02:16 INFO - 'download_symbols': 'ondemand', 18:02:16 INFO - 'enable_stylo': False, 18:02:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:02:16 INFO - 'tooltool.py': '/tools/tooltool.py', 18:02:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:02:16 INFO - '/tools/misc-python/virtualenv.py')}, 18:02:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:02:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:02:16 INFO - 'gecko_profile': False, 18:02:16 INFO - 'gecko_profile_interval': 0, 18:02:16 INFO - 'installer_path': 'installer.exe', 18:02:16 INFO - 'log_level': 'info', 18:02:16 INFO - 'log_name': 'talos', 18:02:16 INFO - 'log_to_console': True, 18:02:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:02:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:02:16 INFO - 'opt_config_files': (), 18:02:16 INFO - 'pip_index': False, 18:02:16 INFO - 'suite': 'dromaeojs-e10s', 18:02:16 INFO - 'system_bits': '32', 18:02:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:02:16 INFO - 'title': 'talos-linux64-ix-044', 18:02:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:02:16 INFO - 'use_talos_json': True, 18:02:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:02:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:02:16 INFO - 'work_dir': 'build'} 18:02:16 INFO - [mozharness: 2017-07-29 01:02:16.770462Z] Running clobber step. 18:02:16 INFO - Running pre-action listener: _resource_record_pre_action 18:02:16 INFO - Running main action method: clobber 18:02:16 INFO - rmtree: /builds/slave/test/build 18:02:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:02:18 INFO - Running post-action listener: _resource_record_post_action 18:02:18 INFO - [mozharness: 2017-07-29 01:02:18.297686Z] Finished clobber step (success) 18:02:18 INFO - [mozharness: 2017-07-29 01:02:18.297787Z] Running read-buildbot-config step. 18:02:18 INFO - Running pre-action listener: _resource_record_pre_action 18:02:18 INFO - Running main action method: read_buildbot_config 18:02:18 INFO - Using buildbot properties: 18:02:18 INFO - { 18:02:18 INFO - "project": "", 18:02:18 INFO - "product": "firefox", 18:02:18 INFO - "who": "kwierso@gmail.com", 18:02:18 INFO - "installer_path": "public/build/target.tar.bz2", 18:02:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:02:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:02:18 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:02:18 INFO - "stage_platform": "linux64-stylosequential", 18:02:18 INFO - "basedir": "/builds/slave/test", 18:02:18 INFO - "buildnumber": 9, 18:02:18 INFO - "platform": "ubuntu64_hw_styloseq", 18:02:18 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:02:18 INFO - "slavebuilddir": "test", 18:02:18 INFO - "taskId": "CD3Rs30PSxCBDFSllGZ68Q", 18:02:18 INFO - "branch": "mozilla-central", 18:02:18 INFO - "script_repo_revision": "production", 18:02:18 INFO - "revision": "ec329722b2f8bad3b1b9d0829e8d89764a879fd1", 18:02:18 INFO - "slavename": "talos-linux64-ix-044", 18:02:18 INFO - "repo_path": "mozilla-central" 18:02:18 INFO - } 18:02:18 INFO - Finding installer, test and symbols from parent task. 18:02:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CD3Rs30PSxCBDFSllGZ68Q'}, attempt #1 18:02:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CD3Rs30PSxCBDFSllGZ68Q'}, attempt #1 18:02:19 INFO - Task dependencies: JZh5JewrR7CLMNL_lRmk9w 18:02:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w'}, attempt #1 18:02:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/test_packages.json 18:02:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip 18:02:19 INFO - Running post-action listener: _resource_record_post_action 18:02:19 INFO - [mozharness: 2017-07-29 01:02:19.736853Z] Finished read-buildbot-config step (success) 18:02:19 INFO - [mozharness: 2017-07-29 01:02:19.737075Z] Running download-and-extract step. 18:02:19 INFO - Running pre-action listener: _resource_record_pre_action 18:02:19 INFO - Running main action method: download_and_extract 18:02:19 INFO - mkdir: /builds/slave/test/build/tests 18:02:19 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:19 INFO - trying https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.test_packages.json 18:02:19 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:19 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:20 INFO - Downloaded 989 bytes. 18:02:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:02:20 INFO - Using the following test package requirements: 18:02:20 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:02:20 INFO - u'common': [u'target.common.tests.zip'], 18:02:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:02:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:02:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:02:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:02:20 INFO - u'mozbase': [u'target.common.tests.zip'], 18:02:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:02:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:02:20 INFO - u'web-platform': [u'target.common.tests.zip', 18:02:20 INFO - u'target.web-platform.tests.tar.gz'], 18:02:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:02:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:02:20 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:20 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:20 INFO - Fetch https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip into memory 18:02:22 INFO - Content-Length response header: 72564963 18:02:22 INFO - Bytes received: 72564963 18:02:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:02:33 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:33 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:33 INFO - Fetch https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip into memory 18:02:35 INFO - Content-Length response header: 72564963 18:02:35 INFO - Bytes received: 72564963 18:02:50 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:50 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:50 INFO - Fetch https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.talos.tests.zip into memory 18:02:50 INFO - Content-Length response header: 14496686 18:02:50 INFO - Bytes received: 14496686 18:02:51 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:51 INFO - trying https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:51 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:51 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:52 INFO - Downloaded 62227675 bytes. 18:02:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:52 INFO - mkdir: /builds/slave/test/properties 18:02:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:02:52 INFO - Writing to file /builds/slave/test/properties/build_url 18:02:52 INFO - Contents: 18:02:52 INFO - build_url:https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:52 INFO - Running post-action listener: _resource_record_post_action 18:02:52 INFO - Running post-action listener: set_extra_try_arguments 18:02:52 INFO - [mozharness: 2017-07-29 01:02:52.375829Z] Finished download-and-extract step (success) 18:02:52 INFO - [mozharness: 2017-07-29 01:02:52.375926Z] Running populate-webroot step. 18:02:52 INFO - Running pre-action listener: _resource_record_pre_action 18:02:52 INFO - Running main action method: populate_webroot 18:02:52 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:02:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:02:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:02:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:02:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:02:52 INFO - 'tests': ['damp', 'tps']}, 18:02:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:02:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:02:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:02:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:02:52 INFO - 'other-e10s': {'tests': ['a11yr', 18:02:52 INFO - 'ts_paint', 18:02:52 INFO - 'tpaint', 18:02:52 INFO - 'sessionrestore', 18:02:52 INFO - 'sessionrestore_many_windows', 18:02:52 INFO - 'sessionrestore_no_auto_restore', 18:02:52 INFO - 'tabpaint']}, 18:02:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:02:52 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:02:52 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:02:52 INFO - 'talos_options': ['--mitmproxy', 18:02:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:02:52 INFO - '--firstNonBlankPaint'], 18:02:52 INFO - 'tests': ['quantum_pageload_google', 18:02:52 INFO - 'quantum_pageload_youtube', 18:02:52 INFO - 'quantum_pageload_amazon', 18:02:52 INFO - 'quantum_pageload_facebook']}, 18:02:52 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:02:52 INFO - 'talos_options': ['--stylo', 18:02:52 INFO - '--mitmproxy', 18:02:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:02:52 INFO - '--firstNonBlankPaint'], 18:02:52 INFO - 'tests': ['quantum_pageload_google', 18:02:52 INFO - 'quantum_pageload_youtube', 18:02:52 INFO - 'quantum_pageload_amazon', 18:02:52 INFO - 'quantum_pageload_facebook']}, 18:02:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:02:52 INFO - 'tsvgr_opacity', 18:02:52 INFO - 'tart', 18:02:52 INFO - 'tscrollx', 18:02:52 INFO - 'cart', 18:02:52 INFO - 'tsvg_static']}, 18:02:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:02:52 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:02:52 INFO - 'talos_options': ['--stylo', 18:02:52 INFO - '--stylo-threads=1', 18:02:52 INFO - '--mitmproxy', 18:02:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:02:52 INFO - '--firstNonBlankPaint'], 18:02:52 INFO - 'tests': ['tp6_google', 18:02:52 INFO - 'tp6_youtube', 18:02:52 INFO - 'tp6_amazon', 18:02:52 INFO - 'tp6_facebook']}, 18:02:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:02:52 INFO - 'talos_options': ['--xperf_path', 18:02:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:02:52 INFO - 'tests': ['tp5n']}}} 18:02:52 INFO - Running post-action listener: _resource_record_post_action 18:02:52 INFO - [mozharness: 2017-07-29 01:02:52.384615Z] Finished populate-webroot step (success) 18:02:52 INFO - [mozharness: 2017-07-29 01:02:52.384706Z] Running create-virtualenv step. 18:02:52 INFO - Running pre-action listener: _resource_record_pre_action 18:02:52 INFO - Running main action method: create_virtualenv 18:02:52 INFO - Creating virtualenv /builds/slave/test/build/venv 18:02:52 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:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:02:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:02:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:02:52 INFO - Using real prefix '/usr' 18:02:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:02:53 INFO - Installing distribute.............................................................................................................................................................................................done. 18:02:54 INFO - Installing pip.................done. 18:02:54 INFO - Return code: 0 18:02:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:02:55 INFO - Reading from file tmpfile_stdout 18:02:55 INFO - Output received: 18:02:55 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:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b37a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7eb0>, '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 0x1cd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd9240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', '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:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:02:55 INFO - Using env: {'DISPLAY': ':0', 18:02:55 INFO - 'HOME': '/home/cltbld', 18:02:55 INFO - 'LANG': 'en_US.UTF-8', 18:02:55 INFO - 'LANGUAGE': 'en_US:en', 18:02:55 INFO - 'LOGNAME': 'cltbld', 18:02:55 INFO - 'MAIL': '/var/mail/cltbld', 18:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:55 INFO - 'MOZ_NO_REMOTE': '1', 18:02:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:55 INFO - 'NO_EM_RESTART': '1', 18:02:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:55 INFO - 'PWD': '/builds/slave/test', 18:02:55 INFO - 'SHELL': '/bin/bash', 18:02:55 INFO - 'SHLVL': '1', 18:02:55 INFO - 'TERM': 'linux', 18:02:55 INFO - 'TMOUT': '86400', 18:02:55 INFO - 'USER': 'cltbld', 18:02:55 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', 18:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:55 INFO - '_': '/tools/buildbot/bin/python'} 18:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:55 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:55 INFO - Cleaning up... 18:02:55 INFO - Return code: 0 18:02:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b37a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7eb0>, '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 0x1cd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd9240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', '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:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:02:55 INFO - Using env: {'DISPLAY': ':0', 18:02:55 INFO - 'HOME': '/home/cltbld', 18:02:55 INFO - 'LANG': 'en_US.UTF-8', 18:02:55 INFO - 'LANGUAGE': 'en_US:en', 18:02:55 INFO - 'LOGNAME': 'cltbld', 18:02:55 INFO - 'MAIL': '/var/mail/cltbld', 18:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:55 INFO - 'MOZ_NO_REMOTE': '1', 18:02:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:55 INFO - 'NO_EM_RESTART': '1', 18:02:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:55 INFO - 'PWD': '/builds/slave/test', 18:02:55 INFO - 'SHELL': '/bin/bash', 18:02:55 INFO - 'SHLVL': '1', 18:02:55 INFO - 'TERM': 'linux', 18:02:55 INFO - 'TMOUT': '86400', 18:02:55 INFO - 'USER': 'cltbld', 18:02:55 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', 18:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:55 INFO - '_': '/tools/buildbot/bin/python'} 18:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:55 INFO - Downloading/unpacking psutil>=3.1.1 18:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:02:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:56 INFO - Installing collected packages: psutil 18:02:56 INFO - Running setup.py install for psutil 18:02:56 INFO - building 'psutil._psutil_linux' extension 18:02:56 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:02:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:02:57 INFO - building 'psutil._psutil_posix' extension 18:02:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:02:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:02:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:57 INFO - Successfully installed psutil 18:02:57 INFO - Cleaning up... 18:02:57 INFO - Return code: 0 18:02:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b37a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7eb0>, '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 0x1cd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd9240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', '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:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:02:57 INFO - Using env: {'DISPLAY': ':0', 18:02:57 INFO - 'HOME': '/home/cltbld', 18:02:57 INFO - 'LANG': 'en_US.UTF-8', 18:02:57 INFO - 'LANGUAGE': 'en_US:en', 18:02:57 INFO - 'LOGNAME': 'cltbld', 18:02:57 INFO - 'MAIL': '/var/mail/cltbld', 18:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:57 INFO - 'MOZ_NO_REMOTE': '1', 18:02:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:57 INFO - 'NO_EM_RESTART': '1', 18:02:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:57 INFO - 'PWD': '/builds/slave/test', 18:02:57 INFO - 'SHELL': '/bin/bash', 18:02:57 INFO - 'SHLVL': '1', 18:02:57 INFO - 'TERM': 'linux', 18:02:57 INFO - 'TMOUT': '86400', 18:02:57 INFO - 'USER': 'cltbld', 18:02:57 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', 18:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:57 INFO - '_': '/tools/buildbot/bin/python'} 18:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:02:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:02:58 INFO - Installing collected packages: mozsystemmonitor 18:02:58 INFO - Running setup.py install for mozsystemmonitor 18:02:58 INFO - Successfully installed mozsystemmonitor 18:02:58 INFO - Cleaning up... 18:02:58 INFO - Return code: 0 18:02:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x196b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b37a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7eb0>, '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 0x1cd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd9240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', '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:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:02:58 INFO - Using env: {'DISPLAY': ':0', 18:02:58 INFO - 'HOME': '/home/cltbld', 18:02:58 INFO - 'LANG': 'en_US.UTF-8', 18:02:58 INFO - 'LANGUAGE': 'en_US:en', 18:02:58 INFO - 'LOGNAME': 'cltbld', 18:02:58 INFO - 'MAIL': '/var/mail/cltbld', 18:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:58 INFO - 'MOZ_NO_REMOTE': '1', 18:02:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:58 INFO - 'NO_EM_RESTART': '1', 18:02:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:58 INFO - 'PWD': '/builds/slave/test', 18:02:58 INFO - 'SHELL': '/bin/bash', 18:02:58 INFO - 'SHLVL': '1', 18:02:58 INFO - 'TERM': 'linux', 18:02:58 INFO - 'TMOUT': '86400', 18:02:58 INFO - 'USER': 'cltbld', 18:02:58 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', 18:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:58 INFO - '_': '/tools/buildbot/bin/python'} 18:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:59 INFO - Downloading/unpacking jsonschema==2.5.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:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:03:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:00 INFO - Downloading functools32-3.2.3-2.tar.gz 18:03:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:03:00 INFO - warning: no files found matching '*.txt' 18:03:00 INFO - no previously-included directories found matching 'build' 18:03:00 INFO - no previously-included directories found matching 'dist' 18:03:00 INFO - no previously-included directories found matching '.git*' 18:03:00 INFO - Installing collected packages: jsonschema, functools32 18:03:00 INFO - Running setup.py install for functools32 18:03:00 INFO - warning: no files found matching '*.txt' 18:03:00 INFO - no previously-included directories found matching 'build' 18:03:00 INFO - no previously-included directories found matching 'dist' 18:03:00 INFO - no previously-included directories found matching '.git*' 18:03:00 INFO - Successfully installed jsonschema functools32 18:03:00 INFO - Cleaning up... 18:03:00 INFO - Return code: 0 18:03:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b37a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7eb0>, '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 0x1cd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd9240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', '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:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:03:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:03:00 INFO - Using env: {'DISPLAY': ':0', 18:03:00 INFO - 'HOME': '/home/cltbld', 18:03:00 INFO - 'LANG': 'en_US.UTF-8', 18:03:00 INFO - 'LANGUAGE': 'en_US:en', 18:03:00 INFO - 'LOGNAME': 'cltbld', 18:03:00 INFO - 'MAIL': '/var/mail/cltbld', 18:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:00 INFO - 'MOZ_NO_REMOTE': '1', 18:03:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:00 INFO - 'NO_EM_RESTART': '1', 18:03:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:00 INFO - 'PWD': '/builds/slave/test', 18:03:00 INFO - 'SHELL': '/bin/bash', 18:03:00 INFO - 'SHLVL': '1', 18:03:00 INFO - 'TERM': 'linux', 18:03:00 INFO - 'TMOUT': '86400', 18:03:00 INFO - 'USER': 'cltbld', 18:03:00 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', 18:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:00 INFO - '_': '/tools/buildbot/bin/python'} 18:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:03:00 INFO - Cleaning up... 18:03:00 INFO - Return code: 0 18:03:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b37a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7eb0>, '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 0x1cd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd9240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', '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:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:03:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:03:00 INFO - Using env: {'DISPLAY': ':0', 18:03:00 INFO - 'HOME': '/home/cltbld', 18:03:00 INFO - 'LANG': 'en_US.UTF-8', 18:03:00 INFO - 'LANGUAGE': 'en_US:en', 18:03:00 INFO - 'LOGNAME': 'cltbld', 18:03:00 INFO - 'MAIL': '/var/mail/cltbld', 18:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:00 INFO - 'MOZ_NO_REMOTE': '1', 18:03:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:00 INFO - 'NO_EM_RESTART': '1', 18:03:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:00 INFO - 'PWD': '/builds/slave/test', 18:03:00 INFO - 'SHELL': '/bin/bash', 18:03:00 INFO - 'SHLVL': '1', 18:03:00 INFO - 'TERM': 'linux', 18:03:00 INFO - 'TMOUT': '86400', 18:03:00 INFO - 'USER': 'cltbld', 18:03:00 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', 18:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:00 INFO - '_': '/tools/buildbot/bin/python'} 18:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:00 INFO - Downloading/unpacking blobuploader==1.2.4 18:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:01 INFO - Downloading blobuploader-1.2.4.tar.gz 18:03:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:03:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:03:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:02 INFO - Downloading docopt-0.6.1.tar.gz 18:03:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:03:02 INFO - Installing collected packages: blobuploader, requests, docopt 18:03:02 INFO - Running setup.py install for blobuploader 18:03:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:03:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:03:02 INFO - Running setup.py install for requests 18:03:03 INFO - Running setup.py install for docopt 18:03:03 INFO - Successfully installed blobuploader requests docopt 18:03:03 INFO - Cleaning up... 18:03:03 INFO - Return code: 0 18:03:03 INFO - Installing None 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', '--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 0x196b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b37a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7eb0>, '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 0x1cd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd9240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', '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', '--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:03 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: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': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', 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 - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:03 INFO - Running setup.py (path:/tmp/pip-fGK2pK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:03 INFO - Running setup.py (path:/tmp/pip-U35yew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:03 INFO - Running setup.py (path:/tmp/pip-V7gAKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:03 INFO - Running setup.py (path:/tmp/pip-VBUwXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:03 INFO - Running setup.py (path:/tmp/pip-nWPvQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:03 INFO - Running setup.py (path:/tmp/pip-tNNQEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:03 INFO - Running setup.py (path:/tmp/pip-HqjJyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:03 INFO - Running setup.py (path:/tmp/pip-G7_od0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:03 INFO - Running setup.py (path:/tmp/pip-8z2ns2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:04 INFO - Running setup.py (path:/tmp/pip-oDALYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:04 INFO - Running setup.py (path:/tmp/pip-d07uUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:04 INFO - Running setup.py (path:/tmp/pip-5DbmyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:04 INFO - Running setup.py (path:/tmp/pip-8fm267-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:04 INFO - Running setup.py (path:/tmp/pip-8iKYOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:04 INFO - Running setup.py (path:/tmp/pip-ledCaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:04 INFO - Running setup.py (path:/tmp/pip-qPzdjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:04 INFO - Running setup.py (path:/tmp/pip-RMWveV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:03:04 INFO - Running setup.py install for manifestparser 18:03:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:03:04 INFO - Running setup.py install for mozcrash 18:03:04 INFO - Running setup.py install for mozdebug 18:03:04 INFO - Running setup.py install for mozdevice 18:03:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Running setup.py install for mozfile 18:03:05 INFO - Running setup.py install for mozhttpd 18:03:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Running setup.py install for mozinfo 18:03:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Running setup.py install for mozInstall 18:03:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Running setup.py install for mozleak 18:03:05 INFO - Running setup.py install for mozlog 18:03:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Running setup.py install for moznetwork 18:03:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Running setup.py install for mozprocess 18:03:05 INFO - Running setup.py install for mozprofile 18:03:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:03:05 INFO - Running setup.py install for mozrunner 18:03:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:03:06 INFO - Running setup.py install for mozscreenshot 18:03:06 INFO - Running setup.py install for moztest 18:03:06 INFO - Running setup.py install for mozversion 18:03:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:03:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:03:06 INFO - Cleaning up... 18:03:06 INFO - Return code: 0 18:03:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:06 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:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:06 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:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:06 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 0x196b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b37a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7eb0>, '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 0x1cd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd9240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', '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:06 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:06 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:06 INFO - Using env: {'DISPLAY': ':0', 18:03:06 INFO - 'HOME': '/home/cltbld', 18:03:06 INFO - 'LANG': 'en_US.UTF-8', 18:03:06 INFO - 'LANGUAGE': 'en_US:en', 18:03:06 INFO - 'LOGNAME': 'cltbld', 18:03:06 INFO - 'MAIL': '/var/mail/cltbld', 18:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:06 INFO - 'MOZ_NO_REMOTE': '1', 18:03:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:06 INFO - 'NO_EM_RESTART': '1', 18:03:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:06 INFO - 'PWD': '/builds/slave/test', 18:03:06 INFO - 'SHELL': '/bin/bash', 18:03:06 INFO - 'SHLVL': '1', 18:03:06 INFO - 'TERM': 'linux', 18:03:06 INFO - 'TMOUT': '86400', 18:03:06 INFO - 'USER': 'cltbld', 18:03:06 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', 18:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:06 INFO - '_': '/tools/buildbot/bin/python'} 18:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:06 INFO - Running setup.py (path:/tmp/pip-2SU0FG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:06 INFO - Running setup.py (path:/tmp/pip-WF8aTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:06 INFO - Running setup.py (path:/tmp/pip-IWrpwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:06 INFO - Running setup.py (path:/tmp/pip-XhleuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:06 INFO - Running setup.py (path:/tmp/pip-WPXwy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:07 INFO - Running setup.py (path:/tmp/pip-3suasi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:07 INFO - Running setup.py (path:/tmp/pip-vFWmnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:07 INFO - Running setup.py (path:/tmp/pip-BOsyUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:07 INFO - Running setup.py (path:/tmp/pip-k24iaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:07 INFO - Running setup.py (path:/tmp/pip-VZkANS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:07 INFO - Running setup.py (path:/tmp/pip-ZmkIWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:07 INFO - Running setup.py (path:/tmp/pip-5YDuIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:07 INFO - Running setup.py (path:/tmp/pip-15wl5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:07 INFO - Running setup.py (path:/tmp/pip-2IOnUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:07 INFO - Running setup.py (path:/tmp/pip-H6VQfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:07 INFO - Running setup.py (path:/tmp/pip-aY4361-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:07 INFO - Running setup.py (path:/tmp/pip-JAoWHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:03:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:08 INFO - Downloading blessings-1.6.tar.gz 18:03:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:03:09 INFO - Installing collected packages: blessings 18:03:09 INFO - Running setup.py install for blessings 18:03:09 INFO - Successfully installed blessings 18:03:09 INFO - Cleaning up... 18:03:09 INFO - Return code: 0 18:03:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:03:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:03:09 INFO - Reading from file tmpfile_stdout 18:03:09 INFO - Current package versions: 18:03:09 INFO - argparse == 1.2.1 18:03:09 INFO - blessings == 1.6 18:03:09 INFO - blobuploader == 1.2.4 18:03:09 INFO - docopt == 0.6.1 18:03:09 INFO - functools32 == 3.2.3-2 18:03:09 INFO - jsonschema == 2.5.1 18:03:09 INFO - manifestparser == 1.1 18:03:09 INFO - mozInstall == 1.13 18:03:09 INFO - mozcrash == 1.0 18:03:09 INFO - mozdebug == 0.1 18:03:09 INFO - mozdevice == 0.51 18:03:09 INFO - mozfile == 1.2 18:03:09 INFO - mozhttpd == 0.7 18:03:09 INFO - mozinfo == 0.10 18:03:09 INFO - mozleak == 0.1 18:03:09 INFO - mozlog == 3.5 18:03:09 INFO - moznetwork == 0.27 18:03:09 INFO - mozprocess == 0.25 18:03:09 INFO - mozprofile == 0.28 18:03:09 INFO - mozrunner == 6.13 18:03:09 INFO - mozscreenshot == 0.1 18:03:09 INFO - mozsystemmonitor == 0.3 18:03:09 INFO - moztest == 0.8 18:03:09 INFO - mozversion == 1.4 18:03:09 INFO - psutil == 3.1.1 18:03:09 INFO - requests == 1.2.3 18:03:09 INFO - wsgiref == 0.1.2 18:03:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:09 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:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:09 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:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:09 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 0x196b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b37a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7eb0>, '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 0x1cd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd9240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', '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:09 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:09 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:09 INFO - Using env: {'DISPLAY': ':0', 18:03:09 INFO - 'HOME': '/home/cltbld', 18:03:09 INFO - 'LANG': 'en_US.UTF-8', 18:03:09 INFO - 'LANGUAGE': 'en_US:en', 18:03:09 INFO - 'LOGNAME': 'cltbld', 18:03:09 INFO - 'MAIL': '/var/mail/cltbld', 18:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:09 INFO - 'MOZ_NO_REMOTE': '1', 18:03:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:09 INFO - 'NO_EM_RESTART': '1', 18:03:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:09 INFO - 'PWD': '/builds/slave/test', 18:03:09 INFO - 'SHELL': '/bin/bash', 18:03:09 INFO - 'SHLVL': '1', 18:03:09 INFO - 'TERM': 'linux', 18:03:09 INFO - 'TMOUT': '86400', 18:03:09 INFO - 'USER': 'cltbld', 18:03:09 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', 18:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:09 INFO - '_': '/tools/buildbot/bin/python'} 18:03:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:03:09 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:10 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:03:10 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 18:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:03:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:03:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:03:11 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:11 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:11 INFO - Installing collected packages: marionette-driver, simplejson 18:03:11 INFO - Running setup.py install for marionette-driver 18:03:11 INFO - Running setup.py install for simplejson 18:03:11 INFO - building 'simplejson._speedups' extension 18:03:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:03:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:03:12 INFO - Successfully installed marionette-driver simplejson 18:03:12 INFO - Cleaning up... 18:03:12 INFO - Return code: 0 18:03:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b37a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd7eb0>, '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 0x1cd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd9240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', '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:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:03:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:03:12 INFO - Using env: {'DISPLAY': ':0', 18:03:12 INFO - 'HOME': '/home/cltbld', 18:03:12 INFO - 'LANG': 'en_US.UTF-8', 18:03:12 INFO - 'LANGUAGE': 'en_US:en', 18:03:12 INFO - 'LOGNAME': 'cltbld', 18:03:12 INFO - 'MAIL': '/var/mail/cltbld', 18:03:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:12 INFO - 'MOZ_NO_REMOTE': '1', 18:03:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:12 INFO - 'NO_EM_RESTART': '1', 18:03:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:12 INFO - 'PWD': '/builds/slave/test', 18:03:12 INFO - 'SHELL': '/bin/bash', 18:03:12 INFO - 'SHLVL': '1', 18:03:12 INFO - 'TERM': 'linux', 18:03:12 INFO - 'TMOUT': '86400', 18:03:12 INFO - 'USER': 'cltbld', 18:03:12 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', 18:03:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:12 INFO - '_': '/tools/buildbot/bin/python'} 18:03:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:03:12 INFO - Cleaning up... 18:03:12 INFO - Return code: 0 18:03:12 INFO - Running post-action listener: _resource_record_post_action 18:03:12 INFO - Running post-action listener: _start_resource_monitoring 18:03:12 INFO - Starting resource monitoring. 18:03:12 INFO - [mozharness: 2017-07-29 01:03:12.450052Z] Finished create-virtualenv step (success) 18:03:12 INFO - [mozharness: 2017-07-29 01:03:12.450574Z] Running install step. 18:03:12 INFO - Running pre-action listener: _resource_record_pre_action 18:03:12 INFO - Running main action method: install 18:03:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:03:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:03:12 INFO - Reading from file tmpfile_stdout 18:03:12 INFO - Detecting whether we're running mozinstall >=1.0... 18:03:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:03:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:03:12 INFO - Reading from file tmpfile_stdout 18:03:12 INFO - Output received: 18:03:12 INFO - Usage: mozinstall [options] installer 18:03:12 INFO - Options: 18:03:12 INFO - -h, --help show this help message and exit 18:03:12 INFO - -d DEST, --destination=DEST 18:03:12 INFO - Directory to install application into. [default: 18:03:12 INFO - "/builds/slave/test"] 18:03:12 INFO - --app=APP Application being installed. [default: firefox] 18:03:12 INFO - mkdir: /builds/slave/test/build/application 18:03:12 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:12 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:30 INFO - Reading from file tmpfile_stdout 18:03:30 INFO - Output received: 18:03:30 INFO - /builds/slave/test/build/application/firefox/firefox 18:03:30 INFO - Running post-action listener: _resource_record_post_action 18:03:30 INFO - [mozharness: 2017-07-29 01:03:30.810194Z] Finished install step (success) 18:03:30 INFO - [mozharness: 2017-07-29 01:03:30.810450Z] Running setup-mitmproxy step. 18:03:30 INFO - Running pre-action listener: _resource_record_pre_action 18:03:30 INFO - Running main action method: setup_mitmproxy 18:03:30 INFO - Skipping: mitmproxy is not required 18:03:30 INFO - Running post-action listener: _resource_record_post_action 18:03:30 INFO - [mozharness: 2017-07-29 01:03:30.811592Z] Finished setup-mitmproxy step (success) 18:03:30 INFO - [mozharness: 2017-07-29 01:03:30.811815Z] Running run-tests step. 18:03:30 INFO - Running pre-action listener: _resource_record_pre_action 18:03:30 INFO - Running pre-action listener: _set_gcov_prefix 18:03:30 INFO - Running main action method: run_tests 18:03:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:03:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:03:30 INFO - Python 2.7.3 18:03:30 INFO - Return code: 0 18:03:30 INFO - grabbing minidump binary from tooltool 18:03:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:30 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 0x1cd7eb0>, '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 0x1cd8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd9240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:03:30 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:30 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:30 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:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:03:30 INFO - Return code: 0 18:03:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:03:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:03:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:03:30 INFO - ENV: RUST_BACKTRACE is now 1 18:03:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:03:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:03:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-044 --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:30 INFO - Using env: {'DISPLAY': ':0', 18:03:30 INFO - 'HOME': '/home/cltbld', 18:03:30 INFO - 'LANG': 'en_US.UTF-8', 18:03:30 INFO - 'LANGUAGE': 'en_US:en', 18:03:30 INFO - 'LOGNAME': 'cltbld', 18:03:30 INFO - 'MAIL': '/var/mail/cltbld', 18:03:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:03:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:03:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:30 INFO - 'MOZ_NO_REMOTE': '1', 18:03:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:03:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:30 INFO - 'NO_EM_RESTART': '1', 18:03:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:30 INFO - 'PWD': '/builds/slave/test', 18:03:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:03:30 INFO - 'RUST_BACKTRACE': '1', 18:03:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:03:30 INFO - 'SHELL': '/bin/bash', 18:03:30 INFO - 'SHLVL': '1', 18:03:30 INFO - 'STYLO_FORCE_ENABLED': '1', 18:03:30 INFO - 'TERM': 'linux', 18:03:30 INFO - 'TMOUT': '86400', 18:03:30 INFO - 'USER': 'cltbld', 18:03:30 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230', 18:03:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:30 INFO - '_': '/tools/buildbot/bin/python'} 18:03:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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:31 INFO - mozversion application_buildid: 20170729001707 18:03:31 INFO - mozversion application_changeset: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 18:03:31 INFO - mozversion application_display_name: Nightly 18:03:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:03:31 INFO - mozversion application_name: Firefox 18:03:31 INFO - mozversion application_remotingname: firefox 18:03:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:03:31 INFO - mozversion application_vendor: Mozilla 18:03:31 INFO - mozversion application_version: 56.0a1 18:03:31 INFO - mozversion platform_buildid: 20170729001707 18:03:31 INFO - mozversion platform_changeset: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 18:03:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:03:31 INFO - mozversion platform_version: 56.0a1 18:03:31 INFO - using testdate: 1501290211 18:03:31 INFO - actual date: 1501290211 18:03:31 INFO - starting webserver on 'localhost:43064' 18:03:31 INFO - SUITE-START | Running 2 tests 18:03:31 INFO - TEST-START | dromaeo_css 18:03:31 INFO - Initialising browser for dromaeo_css test... 18:03:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43064/getInfo.html -profile /tmp/tmpJp_oPC/profile 18:03:31 INFO - TEST-INFO | started process 14342 (/builds/slave/test/build/application/firefox/firefox http://localhost:43064/getInfo.html) 18:03:37 INFO - TEST-INFO | 14342: exit 0 18:03:37 INFO - Browser initialized. 18:03:37 INFO - Running cycle 1/1 for dromaeo_css test... 18:03:37 INFO - TEST-INFO | started process 14566 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJp_oPC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:03:38 INFO - PID 14566 | 18:03:38 INFO - PID 14566 | (/builds/slave/test/build/application/firefox/firefox:14620): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:03:38 INFO - PID 14566 | 18:03:48 INFO - PID 14566 | RSS: Main: 173371392 18:03:48 INFO - PID 14566 | 18:06:47 INFO - PID 14566 | Cycle 1(1): loaded http://localhost:43064/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43064/tests/dromaeo/cssquery-ext.html) 18:06:49 INFO - PID 14566 | RSS: Main: 173162496 18:06:49 INFO - PID 14566 | 18:09:51 INFO - PID 14566 | Cycle 1(1): loaded http://localhost:43064/tests/dromaeo/cssquery-ext.html (next: http://localhost:43064/tests/dromaeo/cssquery-jquery.html) 18:09:52 INFO - PID 14566 | RSS: Main: 171245568 18:09:52 INFO - PID 14566 | 18:12:53 INFO - PID 14566 | Cycle 1(1): loaded http://localhost:43064/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43064/tests/dromaeo/cssquery-mootools.html) 18:12:54 INFO - PID 14566 | RSS: Main: 175104000 18:12:54 INFO - PID 14566 | 18:15:56 INFO - PID 14566 | Cycle 1(1): loaded http://localhost:43064/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43064/tests/dromaeo/cssquery-prototype.html) 18:15:57 INFO - PID 14566 | RSS: Main: 173232128 18:15:57 INFO - PID 14566 | 18:19:01 INFO - PID 14566 | Cycle 1(1): loaded http://localhost:43064/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43064/tests/dromaeo/cssquery-yui.html) 18:19:01 INFO - PID 14566 | RSS: Main: 174698496 18:19:01 INFO - PID 14566 | 18:22:03 INFO - PID 14566 | Cycle 1(1): loaded http://localhost:43064/tests/dromaeo/cssquery-yui.html (next: http://localhost:43064/tests/dromaeo/cssquery-dojo.html) 18:22:03 INFO - PID 14566 | RSS: Main: 177360896 18:22:03 INFO - PID 14566 | 18:22:03 INFO - PID 14566 | __start_tp_report 18:22:03 INFO - PID 14566 | _x_x_mozilla_page_load 18:22:03 INFO - PID 14566 | _x_x_mozilla_page_load_details 18:22:03 INFO - PID 14566 | |i|pagename|runs| 18:22:03 INFO - PID 14566 | |0;dojo.html;3144.05678296511;3153.542736303236;3171.207198200449;3175.2855607488304;3191.9361612767743;6131.785387511438;6291.811245662626;6309.621422714635;6332.683365831713;6345.936540634592;6158.168647232622;6220.06698995151;6288.9056664150075;6325.082862984865;6334.52491063171;5654.37801841797;5675.943240567593;5792.247007888968;5834.912476312858;5878.617889837147;5734.369219385864;5752.395998420168;5796.739146738407;5835.649861008332;5858.355580886099;5604.8318550443555;5699.57253206007;5711.029125048731;5799.4780469757925;5806.883862322772;5703.62926409782;5744.569157313218;5801.593888427812;5810.506106980912;5814.215080964058;5631.324241091054;5684.54523638108;5693.202951586781;5730.455606717355;5797.36229014808;6138.925688004581;6207.534434917399;6216.098665693449;6224.813255602339;6241.843953901143;6688.93311066888;6696.196456425211;6715.630640314781;6719.126513553256;6721.327867213288;6753.122094127781;6850.143732033496;6858.039874417585;6868.416184624313;6900.654967251618;6033.944059789563;6059.666718330534;6206.410391012847;6208.937910620926;6211.19254496917;6781.186257649015;6783.389494945479;6823.55646882961;6880.552764070319;6942.652867356612;6626.204855417284;6630.668466576652;6635.170603674541;6640.0703901453835;6640.468762498988;6751.987201919751;6752.62860542665;6753.223379311442;6753.932460675429;6762.661866906733;6672.266050734415;6675.732970681215;6685.699143538553;6685.866282674319;6703.128593282842;6747.865042699119;6755.22314933779;6804.829879253059;6808.11494505721;6808.5914845109455;5358.705271210121;5375.5162035417;5437.401885792559;5437.673739575638;5444.074507333763;4630.189716799547;4648.907021859543;4653.8371157010015;4655.138799322136;4655.998960223568;5876.7355469004;5877.177195192677;5941.37615550361;5957;5957.493613042853;6706.798796036029;6768.4585233182215;6783.626900520519;6806.965965170241;6886.552374095668;5845.210896528914;5845.883082338414;5856.8535786604825;5862;5866.266716660418;5104.438511763703;5902.675352855634;5925;5949.583529152917;5949.9107513387335;6456.354364563412;6539.607623542603;6545.869082618321;6636.203655561363;6638.402543771083;6587.077809106633;6758.290379510081;6786.728530858908;6842.110525631636;6860.931390686029;6528.771492997722;6533.118029066205;6545.443637290884;6546.443552298108;6568.047633093271;6542.34576542342;6614.768483103068;6618.338166183343;6645.634490102897;6651.467882569286;6739.326067393417;6780.118584583973;6802.761903333359;6803.4557235422035;6812.8978065328065;6196.628202307876;6542.934570654423;6567.540272180901;6576.539642224999;6607.636579988147;6586.50601204902;6591.209054913441;6606.339366063356;6611.173603299588;6624.701888414937;6382.20222472191;6392.0731493934045;6403.4877209824035;6493.448056915026;6499.707513161825;6565.606063636197;6578.440832529097;6579.440747536513;6643;6685.966570167118;6301.117843501822;6329.810105696836;6406.423421892052;6413.775517856852;6433.517486188648 18:22:03 INFO - PID 14566 | |1;ext.html;828.1635548096425;865.8225063861909;885.8804061451702;888.937774355795;1175.3124422213004;31417.21456963577;41562.7921860391;42414.7879260604;42462;42603.78698106513;42842.35736463955;43150.56849431505;43186;43193.56806431935;43298;7750.108737495176;7912.446128770988;7915.762527124196;7917.049954005527;7920.722774702886;7285.052943117387;7873.763787086396;7906.52560846351;7907.367410607167;7911.090224624184;7878.212178782133;7902.762917112495;7963.601819908981;7969.2030796920135;7970.800729981768;7855.450118491708;7907.367410607138;7927.246911543394;7927.801804954865;7936.484128531627;7706.460547761659;7731.188225236326;7751.457397982143;7793.415493837985;7812.453128281023;38387.42418863719;38854.61145388538;38863.222735545416;38896.02759931024;38897.222055558734;3542.079059444537;3560.9821950890077;3570.6429357064344;3599.964000359989;3601.441776524643;31937.36125277505;32008.359832803446;32020.519692204634;32076;32081;4743.596794272491;4763.833265835679;4832.73419961902;4854.12625727369;4898.53463920927;188922;190177;190634;191319;191696;49849.50150498521;49892.750536247084;50058.49941500611;50313;50432.49567504278;53587.73206133944;53712.194317085276;53904.46095539073;53912.73043634757;53996;16641;16657;16667.083310417802;16716.582085447717;16817.327306907588;10962.397068161328;11010.064144547643;13565.457381704819;13636.386362613706;13729.244891530861;12730.936345318214;14836.258187090601;14975.925120374546;15144.242787860563;15191.620209494851;5932.139839723302;6032.426919442646;6042.697865106815;6186.628802271878;6209.751609935552;965.6571916969515;984.2126298960802;991.9553620087113;1019.5513973851481;1024.3904876598413;22805.88597057004;22957;23775.405614859767;29951.400971980875;30705;1051.8527406163143;1105.5467258424007;1107.501624269066;1109.7336639206533;1121.8653761548503;12129.354757447425;12702.42839072244;12723.727627237202;16140.515784526482;16226.935334019236;237467;239506;239514;240906;240970;60205.698971505735;60525.69737151374;60902.69548652229;61361.38638613646;61447.385526144164;1487.620656732548;1532.3181184372847;1537.2621141851973;1540.276070246982;1549.9225038748018;27235.59146612763;27771.861140694975;28052.579211312255;28258.576121357786;28585.85707071451;53763.462365375846;55610.72194639001;55658.16512752231;55670;55704;219870;220727;220897;222008;222241;33318.50022249619;38112.8075911644;38583.61416385912;38881.80559097186;38897.222055559294;40663.79668101641;40738.79630601828;42072.3689144657;46324.76837615925;47512.52487475219;29994.55008174923;30697.53953690741;31749;32217.194570134998;32601.836990814892;32076.679233208302;32116;32182.67817321797;32391.704331826462;32408.675913240564;35902.820485897406;37252.813735931144;37297.81351093227;37308.81345593363;37321.81339093287;32114.678853211168;32562.511562327058;32905;33136.834315828266;33170.83414582912;23430.53138937246;23604.52790944206;23654.763452365944;23966.40083997914;24299 18:22:03 INFO - PID 14566 | |2;jquery.html;573.529705641374;578.1587789765891;580.7996241296756;581.933077696065;597.3578403216542;21877.562448751014;22950;22983.655245171314;23049.654255186186;23079.30762077136;18280.81719182808;19229.42311730647;22554.323370298876;24749.62875556868;24762.628560571608;6266.185395898527;6350.555461117724;6379.138816259792;6384.872302553946;6397.648129352907;5976.790812321569;6398.9040164397575;6503.837404064889;6511.837204069889;6512.511561632873;6431.967840160793;6432.42108210261;6433.38882806135;6435.195600549931;6443.5167362448;6422.03669449584;6424.2290925089055;6434.131392262078;6437.485001199892;6441.259255185667;5951.345352011275;5963.7912673056235;5966.612170208922;5983.611065280743;5985.610935289235;27263.318417039536;27952.580711289345;28396.574051389245;28435.146945591667;28883.277918052216;1851;1853.203122657257;1853.731208974691;1858.256697321069;1861.5532272254698;18499.815001849944;19140.808591914043;19146.80853191478;19153.04234788255;19170.424887253404;2341.180586794608;2346.5424242272766;2353.8940747666225;2403.447207142367;2405.7594240575804;131372;134731.999999999;135570.32214838863;136248.31875840656;136664.31667841697;14109.294535273195;14463.49377771773;14499.492517761828;14538.781918271234;14780.778288325684;21696.89151554232;22309.888450557966;22399.55200895973;22459.326220213094;22471.32586022422;10222.335548189343;10246.180305575535;10260.589576417009;10341.327813692236;10451.634192803216;9480.288978326653;9641.566129524188;9673.516324183904;9693.515324233902;9793.363431376938;9169.174774270348;9260.907390926139;9289.303302252358;9453.196478299422;9497.857532137023;2702.7567518923393;2768.806183567131;2812.4375124975086;2816.4507920955625;2823.2095013396283;913.3332667153015;925.0980294213223;948.4641177734558;963.889152747429;977.6578197630914;17750.822491775176;18287.45137645873;18376.632467350577;18607.255709771456;18978.33575824867;983.7835676151235;992.1467537917385;997.6707686463595;1017.531935309751;1023.1150055202345;9948.950255248677;10089.293749437616;10094.747631309276;10190.796184076276;10496.632617858339;454807;456548;458582;459130;464139;118657.40671296588;119411;121694.39152804179;121932.39033804952;122102;1183.988160118405;1202.3327053485225;1202.8917397434097;1208.4320369426355;1211.1461419699092;15454.459093931659;15967.760483592523;16170.919145404669;16271.186440678155;16599.751003734713;44628.661140165845;50436.24345634745;51569.74215128901;53879;54762.45237547573;63598;64242;64765.352346475935;64867.67566162139;65570;14042.227677477427;14115.858841411455;14137.858621413654;14167.64580885486;14284.357203926056;21050.894745526173;21869;22219;22395.77604223937;22399.552008959403;17481.21334539974;17558.473245802645;17654.558636034202;17730.290788368322;17838.19728112212;15033.849661503245;15359.769603456181;15410.614734631275;15671;15711.371545138523;17599.912000439916;17752.2011509485;18016.2793488259;18048.90975545114;18073.548161296072;14063.859361406665;14096.929515352358;14104.08323458972;14125.646858828612;14136.78794818058;17362.045087520306;17471.56321091983;17593.648127037133;18498.537536561693;18867.15097820574 18:22:03 INFO - PID 14566 | |3;mootools.html;1338.310769953474;1409.2671810658458;1411.5976946570229;1413.060314890597;1425.6079578116014;13068.477260909563;13694.383752731126;13730.38213280401;13757;13777.793333100011;14282.143071415723;14588.12471251723;14610.926945365258;14612.196329201915;14636.707265854677;2594.740525947409;2638.2349118755537;2688.2876037849987;2692.1250593557074;2720.102366219143;2670.4792565449743;2695.514807334679;2698.811083224175;2699.095802906019;2700.932476688079;2640.0627777139125;2650.8144429889917;2670.5460071787747;2672.812903096784;2713.74219449152;2601.219634109769;2608.1914606472055;2616.110522422376;2625.737426257378;2670.8531030793297;2558.1174494799266;2610.778083862874;2632.5919482480244;2645.2989957661234;2646.087099950514;13864.861351386458;13953.093048951887;14073.225972571505;14093.365798539091;14093.577192684235;7413.2957369049855;7679.731209407644;7699.422543309274;7727.1886451922865;7731.072271327421;4851.417829860404;4993.253710459233;5052.368453943257;5126.641135120543;5143.639945203838;1727.4990252826665;1729.8616110711114;1743.7297218931085;1794.1926133240095;1797.343969451154;22367.217147399762;22511.77488225113;22586.88706556473;22598.096076156933;22621.999999999836;6224.813255602339;6443.838904027343;6525.184351956005;6586;6589.538732288694;15781;15785.526434206991;15786.21068946548;15831.208439577975;15837.524874253791;7146.785596432115;7423.851522969619;7443.516171448839;7444.404447644176;7448.515846470071;5533.612647114744;6189.81430557084;6901.309869013058;6914.9308506915295;7135.89296160558;4927.187014142698;5031.874203144877;5042.669894931876;5055.797768089235;5083.567896728746;1414.3776738235144;1430.6280367104628;1437.381925771928;1441.070509521353;1466.0836976889443;826.2646244842095;840.7982084299724;854.5214679779343;859.1322764008396;878.5914549734404;6503.674816259168;6779.389854913082;6872.003559483846;6874.9656251719425;7039;677.4648028057877;700.0863872646246;702.3398005874461;709.7870638808337;720.3840716187584;4108.096218831853;4188.183304255641;4267.637250833652;4663.953360466352;4699.95300047002;18013.549661258574;18219.180136893872;18889.338873139375;18901.24395024211;19039.904800476186;18424.447266582025;18697.81302186988;19158.904205478884;19354.129064191864;19355.80644193568;10620.575176992988;10668;10672.30630009047;10719.035286824379;10814.35113893169;7207.171175314906;7219.772638651547;7456.32893039629;7464;7613.08642962848;15815.762763558785;16293.266802993656;16313.67372652564;16421.835781642032;16515;15085.24573771329;15922.60193495172;15965.600859978593;16918.06950617728;17181.484555463358;10070.798584028133;10104.949475252577;10187.08316251541;10207.081362677394;10211.387316761018;12712.74574508523;12719.046071544857;12793.104482686123;12853.485860565472;13231.338433078115;10548.841767373342;10732.356058636507;10769.784604307713;10895.891041089488;10952.39761813076;9566.473843938651;10085.394876307446;10250.743731406475;10324.587016519556;10580.84128738085;8314.667413303641;8526.658933642833;8527.445716028438;8529.360297977553;8532.70135545253;6292.370762923671;6332.208473940757;6544.411003009752;6606.966965165144;6665.633390163587;6089.391060893875;6391.48868090561;6393.93606063952;6425.710843011982;6462.935370646422 18:22:03 INFO - PID 14566 | |4;prototype.html;490.38211853065127;497.9950200497996;518.9455107213743;519.0242344392542;533.4558750074924;6153.476954458871;6163.845903852406;6189.350118237588;6200.10098535712;6238.251409830821;5884.205632239647;5946.881062378749;6026.758929642809;6072.605280656754;6123.63258204508;4862.197737373329;4869.078552868286;4923.458419573844;4938.2345736410825;4938.456769755342;4719.598834099105;4868.391451068616;4885.535874091974;4924.162892308316;4940.357753492041;4881.731504767236;4882.121218180726;4920.409550853902;4920.581750551208;4951.579115775164;4820.228763397857;4858.271259311096;4894.67502936106;4915.459299477055;4920.852374428773;4352.325389564622;4367.519572846984;4392.297232442826;4396.692231543793;4422;5893.941060589382;5958.672273024982;5969.761209551612;5989.371116032841;6045.274567051938;5579.879645734635;5605.523530499912;5613.494785469286;5629.902169077031;5673.23411339468;6311.779087731953;6370.777022804226;6388.808335749888;6520.543561950665;6527.902081468782;4753.78607962639;4805.6155507559315;4812.326274321597;4834.226523756217;4858.246971719389;6602.613451175259;6772.8984065239065;6804.761833335859;6823.727050917957;6836.794896153124;4883.389576302962;4884.48712885142;4884.926726099111;4909.828156014522;4943.629227807929;4912.1649319615035;4994.850154495371;4995.500449954975;5020.799168033238;5027.91341593242;5579.302587176603;5600.60795744294;5661.622118710152;5665.668567886543;5701.4298570142655;6481.837954051092;6497.772577959749;6515.967420162869;6550.639714815731;6573.605583664996;1658.8092369377675;1663.9251233694513;1700.540853969446;1729.7751292331916;1747.9126043697763;1202.6512311429763;1218.8598311194326;1226.72420682491;1234.5555599983998;1238.73986462842;5689.236336735626;5718.370979192286;5734.713264336767;5746.166805813134;5762.942370576325;6510.804675859732;6550.508711846631;6629.303923088103;6654.168228971379;6696.966515167393;5749.453801888814;5757.683327417032;5769.307683077974;5808.154004537746;5810.88378232433;3534.6642069003406;3553.9644603553634;3565.16218688608;3581.0966047008887;3582.2119133790525;6380.521460890452;6464.450521705614;6470.579412338183;6492.67536623167;6579.835504112435;6851.143607049119;6886.931130688729;6920.861582768417;6937.7273272312395;6953.304669533006;6468.482521398183;6484.935150648527;6498.870022599522;6516.609003459807;6568.67156642166;6665.7333706653135;6686.966565167143;6723.7646682365885;6746.460283177432;6758.628275444898;6551.901721474277;6600.174978127734;6608.306127856506;6630.30381809903;6680.5991640501725;6323.494120470444;6351.872962540816;6402.647854368054;6443.548951573345;6448.613083215022;4933.975330123327;4960.255961605788;4960.47914968923;4979.576735977483;4996.151423973008;6706.530542862148;6770.593764374154;6776.4240039595215;6799.89800153008;6817.5909445433435;6493.123428337303;6495.1556297681;6543.574667646588;6565.704543295467;6569.4744420447205;6195.101710251898;6464.256610489854;6483.4164925156965;6496.350364963466;6509.837254068686;6108.5723999319625;6214.0483270446175;6313.2424109107205;6342.619442833445;6360.681965901594;6393.264774550986;6409.839754006187;6463.57986730861;6490.8052758417325;6547.934520654732;6223.502119830312;6231.2522497300615;6269.31037585856;6305.621662700252;6359.650219237987 18:22:03 INFO - PID 14566 | |5;yui.html;892.7634176943111;906.7098528470892;911.990880091199;913.2465715784475;921.5898924978385;17732.734008989875;18873.811261887393;19025;19049.238030478766;20441.284555040576;20366.287179948704;21217.257395991142;21435.892820535915;21596.2441314554;21628.8918555407;1014.4521958142594;1030.2479190191166;1031.0823367203195;1038.4548112241096;1038.7558923652934;1039.1842403713088;1042.0569384706853;1042.5933885784511;1042.6298663974255;1042.9843552346722;1018.551837191637;1074.4466599701161;1076.0745758647556;1077.709018564987;1087.836824476327;1068.9412082335468;1069.7272195590153;1069.7967386196597;1070.3684825952687;1071.587438836046;780.4224873593556;781.4998401023349;781.9687212511493;782.3936449251819;784.2079499705281;18685.81314186868;19003.524911877314;19063.523411914673;19154.329598464126;19205.711914321295;19214.615707685767;19232.423027309203;19552.31566895152;19938.302159424496;19974.10116544759;14648.707025859423;14682.192479413628;14872.405103795834;14907.031042982171;14913.776293355608;3413.112590726429;3491.8952431427097;3491.9524142757073;3492.161881148506;3501.369753444379;121897.39051304775;126427;129066;130958;130997;15068.54794356171;15526.068435893883;15526.844731552539;15542.378304867678;15603.921980390025;28483.572746408405;28760.424791504473;28768.71231287702;28788.136355909355;28843;7694.461387702807;7864.842703145905;7868.134505204423;7945.728683410626;7953.403494737918;4063.6749060075713;4118.814653340607;4138.193052354822;4149.751014939114;4153.7300075494995;4725.5510726480925;4819.542143496362;4836.371271734652;4848.587870031016;4876.414830220397;871.904090550039;896.1173244354305;897.4077109107957;898.7079199260266;901.4185850126763;625.5683578331028;644.0789670770749;658.2758965138405;670.6009924095156;674.6188403552076;10812.243142980073;11709.473073711704;11955.820662690066;12194.51221951112;12375.876241237653;648.2965981909587;657.2769953051605;676.8984652302096;678.2911857109333;703.2088899987514;5216.321878155892;5264.6314757966575;5339.3058902342445;5379.973100134474;5441.564674826004;93170;95805.52097239469;98794.5060274694;99713;99789.00210997797;85442;90646.09353906376;90906.54546727224;91576;92085;74049;76105.6194719034;77265.2273477258;77706.6114669423;79187;10062.849057264;10617.681469555926;10641.467926603484;10760.354378736987;10833.404162770808;35236.64763352334;35744.28511429809;36087.81956090308;37310;37964;56327;58907.705461474136;63552.364476356495;68210;68565;17713.645727085644;18110.456686299432;18224.45326640203;18251.54371140732;18286.63426731431;20172.092255848816;20405.979701015185;20507;20591;20791.376258712262;18785.624287514445;18982;19158.71261931044;19169.712454313474;19408.126634301207;18223.81776182221;18330.725039124158;18417.631647367245;18532.166052527937;18954.810451895304;17855.196516157062;18155.092245387943;18266.908665456587;18627.627447450704;18844.34044808425;12550.246985180918;12588.937055314665;13218.272994985367;13312.400941957441;13462.528811491551;13742.725145497234;13829;14118;14852.183129927958;14897.47858824936 18:22:03 INFO - PID 14566 | __end_tp_report 18:22:03 INFO - PID 14566 | __start_cc_report 18:22:03 INFO - PID 14566 | _x_x_mozilla_cycle_collect,359 18:22:03 INFO - PID 14566 | __end_cc_report 18:22:03 INFO - PID 14566 | __startTimestamp1501291323737__endTimestamp 18:22:03 INFO - PID 14566 | 18:22:03 INFO - PID 14566 | ------- Summary: start ------- 18:22:03 INFO - PID 14566 | Number of tests: 6 18:22:03 INFO - PID 14566 | 18:22:03 INFO - PID 14566 | [#0] dojo.html Cycles:165 Average:6190.42 Median:6413.78 stddev:739.55 (11.5%) stddev-sans-first:702.14 18:22:03 INFO - PID 14566 | Values: 3144.1 3153.5 3171.2 3175.3 3191.9 6131.8 6291.8 6309.6 6332.7 6345.9 6158.2 6220.1 6288.9 6325.1 6334.5 5654.4 5675.9 5792.2 5834.9 5878.6 5734.4 5752.4 5796.7 5835.6 5858.4 5604.8 5699.6 5711.0 5799.5 5806.9 5703.6 5744.6 5801.6 5810.5 5814.2 5631.3 5684.5 5693.2 5730.5 5797.4 6138.9 6207.5 6216.1 6224.8 6241.8 6688.9 6696.2 6715.6 6719.1 6721.3 6753.1 6850.1 6858.0 6868.4 6900.7 6033.9 6059.7 6206.4 6208.9 6211.2 6781.2 6783.4 6823.6 6880.6 6942.7 6626.2 6630.7 6635.2 6640.1 6640.5 6752.0 6752.6 6753.2 6753.9 6762.7 6672.3 6675.7 6685.7 6685.9 6703.1 6747.9 6755.2 6804.8 6808.1 6808.6 5358.7 5375.5 5437.4 5437.7 5444.1 4630.2 4648.9 4653.8 4655.1 4656.0 5876.7 5877.2 5941.4 5957.0 5957.5 6706.8 6768.5 6783.6 6807.0 6886.6 5845.2 5845.9 5856.9 5862.0 5866.3 5104.4 5902.7 5925.0 5949.6 5949.9 6456.4 6539.6 6545.9 6636.2 6638.4 6587.1 6758.3 6786.7 6842.1 6860.9 6528.8 6533.1 6545.4 6546.4 6568.0 6542.3 6614.8 6618.3 6645.6 6651.5 6739.3 6780.1 6802.8 6803.5 6812.9 6196.6 6542.9 6567.5 6576.5 6607.6 6586.5 6591.2 6606.3 6611.2 6624.7 6382.2 6392.1 6403.5 6493.4 6499.7 6565.6 6578.4 6579.4 6643.0 6686.0 6301.1 6329.8 6406.4 6413.8 6433.5 18:22:03 INFO - PID 14566 | 18:22:03 INFO - PID 14566 | [#1] ext.html Cycles:180 Average:39832.05 Median:20427.13 stddev:56627.25 (277.2%) stddev-sans-first:56710.37 18:22:03 INFO - PID 14566 | Values: 828.2 865.8 885.9 888.9 1175.3 31417.2 41562.8 42414.8 42462.0 42603.8 42842.4 43150.6 43186.0 43193.6 43298.0 7750.1 7912.4 7915.8 7917.0 7920.7 7285.1 7873.8 7906.5 7907.4 7911.1 7878.2 7902.8 7963.6 7969.2 7970.8 7855.5 7907.4 7927.2 7927.8 7936.5 7706.5 7731.2 7751.5 7793.4 7812.5 38387.4 38854.6 38863.2 38896.0 38897.2 3542.1 3561.0 3570.6 3600.0 3601.4 31937.4 32008.4 32020.5 32076.0 32081.0 4743.6 4763.8 4832.7 4854.1 4898.5 188922.0 190177.0 190634.0 191319.0 191696.0 49849.5 49892.8 50058.5 50313.0 50432.5 53587.7 53712.2 53904.5 53912.7 53996.0 16641.0 16657.0 16667.1 16716.6 16817.3 10962.4 11010.1 13565.5 13636.4 13729.2 12730.9 14836.3 14975.9 15144.2 15191.6 5932.1 6032.4 6042.7 6186.6 6209.8 965.7 984.2 992.0 1019.6 1024.4 22805.9 22957.0 23775.4 29951.4 30705.0 1051.9 1105.5 1107.5 1109.7 1121.9 12129.4 12702.4 12723.7 16140.5 16226.9 237467.0 239506.0 239514.0 240906.0 240970.0 60205.7 60525.7 60902.7 61361.4 61447.4 1487.6 1532.3 1537.3 1540.3 1549.9 27235.6 27771.9 28052.6 28258.6 28585.9 53763.5 55610.7 55658.2 55670.0 55704.0 219870.0 220727.0 220897.0 222008.0 222241.0 33318.5 38112.8 38583.6 38881.8 38897.2 40663.8 40738.8 42072.4 46324.8 47512.5 29994.6 30697.5 31749.0 32217.2 32601.8 32076.7 32116.0 32182.7 32391.7 32408.7 35902.8 37252.8 37297.8 37308.8 37321.8 32114.7 32562.5 32905.0 33136.8 33170.8 23430.5 23604.5 23654.8 23966.4 24299.0 18:22:03 INFO - PID 14566 | 18:22:03 INFO - PID 14566 | [#2] jquery.html Cycles:180 Average:33169.35 Median:22399.55 stddev:77764.31 (347.2%) stddev-sans-first:77943.95 18:22:03 INFO - PID 14566 | Values: 573.5 578.2 580.8 581.9 597.4 21877.6 22950.0 22983.7 23049.7 23079.3 18280.8 19229.4 22554.3 24749.6 24762.6 6266.2 6350.6 6379.1 6384.9 6397.6 5976.8 6398.9 6503.8 6511.8 6512.5 6432.0 6432.4 6433.4 6435.2 6443.5 6422.0 6424.2 6434.1 6437.5 6441.3 5951.3 5963.8 5966.6 5983.6 5985.6 27263.3 27952.6 28396.6 28435.1 28883.3 1851.0 1853.2 1853.7 1858.3 1861.6 18499.8 19140.8 19146.8 19153.0 19170.4 2341.2 2346.5 2353.9 2403.4 2405.8 131372.0 134732.0 135570.3 136248.3 136664.3 14109.3 14463.5 14499.5 14538.8 14780.8 21696.9 22309.9 22399.6 22459.3 22471.3 10222.3 10246.2 10260.6 10341.3 10451.6 9480.3 9641.6 9673.5 9693.5 9793.4 9169.2 9260.9 9289.3 9453.2 9497.9 2702.8 2768.8 2812.4 2816.5 2823.2 913.3 925.1 948.5 963.9 977.7 17750.8 18287.5 18376.6 18607.3 18978.3 983.8 992.1 997.7 1017.5 1023.1 9949.0 10089.3 10094.7 10190.8 10496.6 454807.0 456548.0 458582.0 459130.0 464139.0 118657.4 119411.0 121694.4 121932.4 122102.0 1184.0 1202.3 1202.9 1208.4 1211.1 15454.5 15967.8 16170.9 16271.2 16599.8 44628.7 50436.2 51569.7 53879.0 54762.5 63598.0 64242.0 64765.4 64867.7 65570.0 14042.2 14115.9 14137.9 14167.6 14284.4 21050.9 21869.0 22219.0 22395.8 22399.6 17481.2 17558.5 17654.6 17730.3 17838.2 15033.8 15359.8 15410.6 15671.0 15711.4 17599.9 17752.2 18016.3 18048.9 18073.5 14063.9 14096.9 14104.1 14125.6 14136.8 17362.0 17471.6 17593.6 18498.5 18867.2 18:22:03 INFO - PID 14566 | 18:22:03 INFO - PID 14566 | [#3] mootools.html Cycles:180 Average:8408.54 Median:2629.16 stddev:5817.21 (221.3%) stddev-sans-first:5809.27 18:22:03 INFO - PID 14566 | Values: 1338.3 1409.3 1411.6 1413.1 1425.6 13068.5 13694.4 13730.4 13757.0 13777.8 14282.1 14588.1 14610.9 14612.2 14636.7 2594.7 2638.2 2688.3 2692.1 2720.1 2670.5 2695.5 2698.8 2699.1 2700.9 2640.1 2650.8 2670.5 2672.8 2713.7 2601.2 2608.2 2616.1 2625.7 2670.9 2558.1 2610.8 2632.6 2645.3 2646.1 13864.9 13953.1 14073.2 14093.4 14093.6 7413.3 7679.7 7699.4 7727.2 7731.1 4851.4 4993.3 5052.4 5126.6 5143.6 1727.5 1729.9 1743.7 1794.2 1797.3 22367.2 22511.8 22586.9 22598.1 22622.0 6224.8 6443.8 6525.2 6586.0 6589.5 15781.0 15785.5 15786.2 15831.2 15837.5 7146.8 7423.9 7443.5 7444.4 7448.5 5533.6 6189.8 6901.3 6914.9 7135.9 4927.2 5031.9 5042.7 5055.8 5083.6 1414.4 1430.6 1437.4 1441.1 1466.1 826.3 840.8 854.5 859.1 878.6 6503.7 6779.4 6872.0 6875.0 7039.0 677.5 700.1 702.3 709.8 720.4 4108.1 4188.2 4267.6 4664.0 4700.0 18013.5 18219.2 18889.3 18901.2 19039.9 18424.4 18697.8 19158.9 19354.1 19355.8 10620.6 10668.0 10672.3 10719.0 10814.4 7207.2 7219.8 7456.3 7464.0 7613.1 15815.8 16293.3 16313.7 16421.8 16515.0 15085.2 15922.6 15965.6 16918.1 17181.5 10070.8 10104.9 10187.1 10207.1 10211.4 12712.7 12719.0 12793.1 12853.5 13231.3 10548.8 10732.4 10769.8 10895.9 10952.4 9566.5 10085.4 10250.7 10324.6 10580.8 8314.7 8526.7 8527.4 8529.4 8532.7 6292.4 6332.2 6544.4 6607.0 6665.6 6089.4 6391.5 6393.9 6425.7 6462.9 18:22:03 INFO - PID 14566 | 18:22:03 INFO - PID 14566 | [#4] prototype.html Cycles:180 Average:5451.52 Median:6008.07 stddev:1536.10 (25.6%) stddev-sans-first:1494.59 18:22:03 INFO - PID 14566 | Values: 490.4 498.0 518.9 519.0 533.5 6153.5 6163.8 6189.4 6200.1 6238.3 5884.2 5946.9 6026.8 6072.6 6123.6 4862.2 4869.1 4923.5 4938.2 4938.5 4719.6 4868.4 4885.5 4924.2 4940.4 4881.7 4882.1 4920.4 4920.6 4951.6 4820.2 4858.3 4894.7 4915.5 4920.9 4352.3 4367.5 4392.3 4396.7 4422.0 5893.9 5958.7 5969.8 5989.4 6045.3 5579.9 5605.5 5613.5 5629.9 5673.2 6311.8 6370.8 6388.8 6520.5 6527.9 4753.8 4805.6 4812.3 4834.2 4858.2 6602.6 6772.9 6804.8 6823.7 6836.8 4883.4 4884.5 4884.9 4909.8 4943.6 4912.2 4994.9 4995.5 5020.8 5027.9 5579.3 5600.6 5661.6 5665.7 5701.4 6481.8 6497.8 6516.0 6550.6 6573.6 1658.8 1663.9 1700.5 1729.8 1747.9 1202.7 1218.9 1226.7 1234.6 1238.7 5689.2 5718.4 5734.7 5746.2 5762.9 6510.8 6550.5 6629.3 6654.2 6697.0 5749.5 5757.7 5769.3 5808.2 5810.9 3534.7 3554.0 3565.2 3581.1 3582.2 6380.5 6464.5 6470.6 6492.7 6579.8 6851.1 6886.9 6920.9 6937.7 6953.3 6468.5 6484.9 6498.9 6516.6 6568.7 6665.7 6687.0 6723.8 6746.5 6758.6 6551.9 6600.2 6608.3 6630.3 6680.6 6323.5 6351.9 6402.6 6443.5 6448.6 4934.0 4960.3 4960.5 4979.6 4996.2 6706.5 6770.6 6776.4 6799.9 6817.6 6493.1 6495.2 6543.6 6565.7 6569.5 6195.1 6464.3 6483.4 6496.4 6509.8 6108.6 6214.0 6313.2 6342.6 6360.7 6393.3 6409.8 6463.6 6490.8 6547.9 6223.5 6231.3 6269.3 6305.6 6359.7 18:22:03 INFO - PID 14566 | 18:22:03 INFO - PID 14566 | [#5] yui.html Cycles:180 Average:22437.87 Median:20386.13 stddev:30144.72 (147.9%) stddev-sans-first:30185.87 18:22:03 INFO - PID 14566 | Values: 892.8 906.7 912.0 913.2 921.6 17732.7 18873.8 19025.0 19049.2 20441.3 20366.3 21217.3 21435.9 21596.2 21628.9 1014.5 1030.2 1031.1 1038.5 1038.8 1039.2 1042.1 1042.6 1042.6 1043.0 1018.6 1074.4 1076.1 1077.7 1087.8 1068.9 1069.7 1069.8 1070.4 1071.6 780.4 781.5 782.0 782.4 784.2 18685.8 19003.5 19063.5 19154.3 19205.7 19214.6 19232.4 19552.3 19938.3 19974.1 14648.7 14682.2 14872.4 14907.0 14913.8 3413.1 3491.9 3492.0 3492.2 3501.4 121897.4 126427.0 129066.0 130958.0 130997.0 15068.5 15526.1 15526.8 15542.4 15603.9 28483.6 28760.4 28768.7 28788.1 28843.0 7694.5 7864.8 7868.1 7945.7 7953.4 4063.7 4118.8 4138.2 4149.8 4153.7 4725.6 4819.5 4836.4 4848.6 4876.4 871.9 896.1 897.4 898.7 901.4 625.6 644.1 658.3 670.6 674.6 10812.2 11709.5 11955.8 12194.5 12375.9 648.3 657.3 676.9 678.3 703.2 5216.3 5264.6 5339.3 5380.0 5441.6 93170.0 95805.5 98794.5 99713.0 99789.0 85442.0 90646.1 90906.5 91576.0 92085.0 74049.0 76105.6 77265.2 77706.6 79187.0 10062.8 10617.7 10641.5 10760.4 10833.4 35236.6 35744.3 36087.8 37310.0 37964.0 56327.0 58907.7 63552.4 68210.0 68565.0 17713.6 18110.5 18224.5 18251.5 18286.6 20172.1 20406.0 20507.0 20591.0 20791.4 18785.6 18982.0 19158.7 19169.7 19408.1 18223.8 18330.7 18417.6 18532.2 18954.8 17855.2 18155.1 18266.9 18627.6 18844.3 12550.2 12588.9 13218.3 13312.4 13462.5 13742.7 13829.0 14118.0 14852.2 14897.5 18:22:03 INFO - PID 14566 | -------- Summary: end -------- 18:22:03 INFO - PID 14566 | 18:22:04 INFO - TEST-INFO | 14566: exit 0 18:22:04 INFO - TEST-OK | dromaeo_css | took 1113257ms 18:22:04 INFO - TEST-START | kraken 18:22:04 INFO - Initialising browser for kraken test... 18:22:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43064/getInfo.html -profile /tmp/tmp1cTxfb/profile 18:22:04 INFO - TEST-INFO | started process 14943 (/builds/slave/test/build/application/firefox/firefox http://localhost:43064/getInfo.html) 18:22:10 INFO - TEST-INFO | 14943: exit 0 18:22:10 INFO - Browser initialized. 18:22:10 INFO - Running cycle 1/1 for kraken test... 18:22:10 INFO - TEST-INFO | started process 15171 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1cTxfb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:22:11 INFO - PID 15171 | 18:22:11 INFO - PID 15171 | (/builds/slave/test/build/application/firefox/firefox:15225): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:22:11 INFO - PID 15171 | 18:22:21 INFO - PID 15171 | RSS: Main: 173608960 18:22:21 INFO - PID 15171 | 18:22:24 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43064/tests/kraken/driver.html?testName=audio-beat-detection) 18:22:25 INFO - PID 15171 | RSS: Main: 179019776 18:22:25 INFO - PID 15171 | 18:22:28 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43064/tests/kraken/driver.html?testName=audio-dft) 18:22:28 INFO - PID 15171 | RSS: Main: 179023872 18:22:28 INFO - PID 15171 | 18:22:31 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43064/tests/kraken/driver.html?testName=audio-fft) 18:22:32 INFO - PID 15171 | RSS: Main: 173879296 18:22:32 INFO - PID 15171 | 18:22:34 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43064/tests/kraken/driver.html?testName=audio-oscillator) 18:22:35 INFO - PID 15171 | RSS: Main: 173850624 18:22:35 INFO - PID 15171 | 18:22:37 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43064/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:22:38 INFO - PID 15171 | RSS: Main: 174501888 18:22:38 INFO - PID 15171 | 18:22:41 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43064/tests/kraken/driver.html?testName=imaging-darkroom) 18:22:42 INFO - PID 15171 | RSS: Main: 176984064 18:22:42 INFO - PID 15171 | 18:22:45 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43064/tests/kraken/driver.html?testName=imaging-desaturate) 18:22:46 INFO - PID 15171 | RSS: Main: 176766976 18:22:46 INFO - PID 15171 | 18:22:49 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43064/tests/kraken/driver.html?testName=json-parse-financial) 18:22:49 INFO - PID 15171 | RSS: Main: 177594368 18:22:49 INFO - PID 15171 | 18:22:51 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43064/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:22:52 INFO - PID 15171 | RSS: Main: 177852416 18:22:52 INFO - PID 15171 | 18:22:53 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43064/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:22:54 INFO - PID 15171 | RSS: Main: 174931968 18:22:54 INFO - PID 15171 | 18:22:56 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43064/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:22:57 INFO - PID 15171 | RSS: Main: 175079424 18:22:57 INFO - PID 15171 | 18:23:00 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43064/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:23:01 INFO - PID 15171 | RSS: Main: 175476736 18:23:01 INFO - PID 15171 | 18:23:04 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43064/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:23:04 INFO - PID 15171 | RSS: Main: 175304704 18:23:04 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | Cycle 1(1): loaded http://localhost:43064/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43064/tests/kraken/driver.html?testName=ai-astar) 18:23:06 INFO - PID 15171 | RSS: Main: 176918528 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | __start_tp_report 18:23:06 INFO - PID 15171 | _x_x_mozilla_page_load 18:23:06 INFO - PID 15171 | _x_x_mozilla_page_load_details 18:23:06 INFO - PID 15171 | |i|pagename|runs| 18:23:06 INFO - PID 15171 | |0;ai-astar;99;119;109;108;120;111;115;101;107;107 18:23:06 INFO - PID 15171 | |1;audio-beat-detection;156;123;155;190;121;154;156;120;180;120 18:23:06 INFO - PID 15171 | |2;audio-dft;163;163;163;164;169;166;169;161;161;164 18:23:06 INFO - PID 15171 | |3;audio-fft;74;82;82;199;75;77;76;88;98;79 18:23:06 INFO - PID 15171 | |4;audio-oscillator;79;82;135;80;79;78;138;80;82;124 18:23:06 INFO - PID 15171 | |5;imaging-gaussian-blur;114;114;115;113;115;114;115;120;116;124 18:23:06 INFO - PID 15171 | |6;imaging-darkroom;153;150;150;149;152;152;151;150;152;153 18:23:06 INFO - PID 15171 | |7;imaging-desaturate;94;94;95;99;97;94;93;94;94;93 18:23:06 INFO - PID 15171 | |8;json-parse-financial;58;57;60;59;57;60;57;57;62;60 18:23:06 INFO - PID 15171 | |9;json-stringify-tinderbox;63;60;54;60;54;59;54;55;54;54 18:23:06 INFO - PID 15171 | |10;stanford-crypto-aes;80;88;84;81;80;80;81;80;82;83 18:23:06 INFO - PID 15171 | |11;stanford-crypto-ccm;143;138;131;128;142;135;129;127;126;171 18:23:06 INFO - PID 15171 | |12;stanford-crypto-pbkdf2;187;185;181;183;184;186;186;186;181;186 18:23:06 INFO - PID 15171 | |13;stanford-crypto-sha256-iterative;69;71;71;73;68;70;71;73;69;70 18:23:06 INFO - PID 15171 | __end_tp_report 18:23:06 INFO - PID 15171 | __start_cc_report 18:23:06 INFO - PID 15171 | _x_x_mozilla_cycle_collect,933 18:23:06 INFO - PID 15171 | __end_cc_report 18:23:06 INFO - PID 15171 | __startTimestamp1501291386954__endTimestamp 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | ------- Summary: start ------- 18:23:06 INFO - PID 15171 | Number of tests: 14 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#0] ai-astar Cycles:10 Average:109.60 Median:113.00 stddev:6.92 (6.1%) stddev-sans-first:6.18 18:23:06 INFO - PID 15171 | Values: 99.0 119.0 109.0 108.0 120.0 111.0 115.0 101.0 107.0 107.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#1] audio-beat-detection Cycles:10 Average:147.50 Median:155.50 stddev:25.65 (16.5%) stddev-sans-first:27.02 18:23:06 INFO - PID 15171 | Values: 156.0 123.0 155.0 190.0 121.0 154.0 156.0 120.0 180.0 120.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.00 stddev:2.87 (1.7%) stddev-sans-first:3.00 18:23:06 INFO - PID 15171 | Values: 163.0 163.0 163.0 164.0 169.0 166.0 169.0 161.0 161.0 164.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#3] audio-fft Cycles:10 Average:93.00 Median:80.50 stddev:37.94 (47.1%) stddev-sans-first:39.61 18:23:06 INFO - PID 15171 | Values: 74.0 82.0 82.0 199.0 75.0 77.0 76.0 88.0 98.0 79.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:79.50 stddev:25.55 (32.1%) stddev-sans-first:26.37 18:23:06 INFO - PID 15171 | Values: 79.0 82.0 135.0 80.0 79.0 78.0 138.0 80.0 82.0 124.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#5] imaging-gaussian-blur Cycles:10 Average:116.00 Median:115.00 stddev:3.40 (3.0%) stddev-sans-first:3.53 18:23:06 INFO - PID 15171 | Values: 114.0 114.0 115.0 113.0 115.0 114.0 115.0 120.0 116.0 124.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#6] imaging-darkroom Cycles:10 Average:151.20 Median:152.00 stddev:1.40 (0.9%) stddev-sans-first:1.32 18:23:06 INFO - PID 15171 | Values: 153.0 150.0 150.0 149.0 152.0 152.0 151.0 150.0 152.0 153.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.00 stddev:1.89 (2.0%) stddev-sans-first:1.99 18:23:06 INFO - PID 15171 | Values: 94.0 94.0 95.0 99.0 97.0 94.0 93.0 94.0 94.0 93.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:59.50 stddev:1.77 (3.0%) stddev-sans-first:1.86 18:23:06 INFO - PID 15171 | Values: 58.0 57.0 60.0 59.0 57.0 60.0 57.0 57.0 62.0 60.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#9] json-stringify-tinderbox Cycles:10 Average:56.70 Median:57.00 stddev:3.43 (6.0%) stddev-sans-first:2.78 18:23:06 INFO - PID 15171 | Values: 63.0 60.0 54.0 60.0 54.0 59.0 54.0 55.0 54.0 54.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:81.50 stddev:2.56 (3.1%) stddev-sans-first:2.62 18:23:06 INFO - PID 15171 | Values: 80.0 88.0 84.0 81.0 80.0 80.0 81.0 80.0 82.0 83.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#11] stanford-crypto-ccm Cycles:10 Average:137.00 Median:136.50 stddev:13.43 (9.8%) stddev-sans-first:14.07 18:23:06 INFO - PID 15171 | Values: 143.0 138.0 131.0 128.0 142.0 135.0 129.0 127.0 126.0 171.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.50 Median:186.00 stddev:2.17 (1.2%) stddev-sans-first:2.11 18:23:06 INFO - PID 15171 | Values: 187.0 185.0 181.0 183.0 184.0 186.0 186.0 186.0 181.0 186.0 18:23:06 INFO - PID 15171 | 18:23:06 INFO - PID 15171 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:71.00 stddev:1.65 (2.3%) stddev-sans-first:1.66 18:23:06 INFO - PID 15171 | Values: 69.0 71.0 71.0 73.0 68.0 70.0 71.0 73.0 69.0 70.0 18:23:06 INFO - PID 15171 | -------- Summary: end -------- 18:23:06 INFO - PID 15171 | 18:23:07 INFO - PID 15171 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:23:07 INFO - TEST-INFO | 15171: exit 0 18:23:07 INFO - TEST-OK | kraken | took 63260ms 18:23:07 INFO - SUITE-END | took 1176s 18:23:08 INFO - Completed test suite (00:19:36) 18:23:08 INFO - javascript benchmark 18:23:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8366.032042411913, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.05678296511, 3153.542736303236, 3171.207198200449, 3175.2855607488304, 3191.9361612767743, 6131.785387511438, 6291.811245662626, 6309.621422714635, 6332.683365831713, 6345.936540634592, 6158.168647232622, 6220.06698995151, 6288.9056664150075, 6325.082862984865, 6334.52491063171, 5654.37801841797, 5675.943240567593, 5792.247007888968, 5834.912476312858, 5878.617889837147, 5734.369219385864, 5752.395998420168, 5796.739146738407, 5835.649861008332, 5858.355580886099, 5604.8318550443555, 5699.57253206007, 5711.029125048731, 5799.4780469757925, 5806.883862322772, 5703.62926409782, 5744.569157313218, 5801.593888427812, 5810.506106980912, 5814.215080964058, 5631.324241091054, 5684.54523638108, 5693.202951586781, 5730.455606717355, 5797.36229014808, 6138.925688004581, 6207.534434917399, 6216.098665693449, 6224.813255602339, 6241.843953901143, 6688.93311066888, 6696.196456425211, 6715.630640314781, 6719.126513553256, 6721.327867213288, 6753.122094127781, 6850.143732033496, 6858.039874417585, 6868.416184624313, 6900.654967251618, 6033.944059789563, 6059.666718330534, 6206.410391012847, 6208.937910620926, 6211.19254496917, 6781.186257649015, 6783.389494945479, 6823.55646882961, 6880.552764070319, 6942.652867356612, 6626.204855417284, 6630.668466576652, 6635.170603674541, 6640.0703901453835, 6640.468762498988, 6751.987201919751, 6752.62860542665, 6753.223379311442, 6753.932460675429, 6762.661866906733, 6672.266050734415, 6675.732970681215, 6685.699143538553, 6685.866282674319, 6703.128593282842, 6747.865042699119, 6755.22314933779, 6804.829879253059, 6808.11494505721, 6808.5914845109455, 5358.705271210121, 5375.5162035417, 5437.401885792559, 5437.673739575638, 5444.074507333763, 4630.189716799547, 4648.907021859543, 4653.8371157010015, 4655.138799322136, 4655.998960223568, 5876.7355469004, 5877.177195192677, 5941.37615550361, 5957.0, 5957.493613042853, 6706.798796036029, 6768.4585233182215, 6783.626900520519, 6806.965965170241, 6886.552374095668, 5845.210896528914, 5845.883082338414, 5856.8535786604825, 5862.0, 5866.266716660418, 5104.438511763703, 5902.675352855634, 5925.0, 5949.583529152917, 5949.9107513387335, 6456.354364563412, 6539.607623542603, 6545.869082618321, 6636.203655561363, 6638.402543771083, 6587.077809106633, 6758.290379510081, 6786.728530858908, 6842.110525631636, 6860.931390686029, 6528.771492997722, 6533.118029066205, 6545.443637290884, 6546.443552298108, 6568.047633093271, 6542.34576542342, 6614.768483103068, 6618.338166183343, 6645.634490102897, 6651.467882569286, 6739.326067393417, 6780.118584583973, 6802.761903333359, 6803.4557235422035, 6812.8978065328065, 6196.628202307876, 6542.934570654423, 6567.540272180901, 6576.539642224999, 6607.636579988147, 6586.50601204902, 6591.209054913441, 6606.339366063356, 6611.173603299588, 6624.701888414937, 6382.20222472191, 6392.0731493934045, 6403.4877209824035, 6493.448056915026, 6499.707513161825, 6565.606063636197, 6578.440832529097, 6579.440747536513, 6643.0, 6685.966570167118, 6301.117843501822, 6329.810105696836, 6406.423421892052, 6413.775517856852, 6433.517486188648], "value": 6134.640343883819, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.1635548096425, 865.8225063861909, 885.8804061451702, 888.937774355795, 1175.3124422213004, 31417.21456963577, 41562.7921860391, 42414.7879260604, 42462.0, 42603.78698106513, 42842.35736463955, 43150.56849431505, 43186.0, 43193.56806431935, 43298.0, 7750.108737495176, 7912.446128770988, 7915.762527124196, 7917.049954005527, 7920.722774702886, 7285.052943117387, 7873.763787086396, 7906.52560846351, 7907.367410607167, 7911.090224624184, 7878.212178782133, 7902.762917112495, 7963.601819908981, 7969.2030796920135, 7970.800729981768, 7855.450118491708, 7907.367410607138, 7927.246911543394, 7927.801804954865, 7936.484128531627, 7706.460547761659, 7731.188225236326, 7751.457397982143, 7793.415493837985, 7812.453128281023, 38387.42418863719, 38854.61145388538, 38863.222735545416, 38896.02759931024, 38897.222055558734, 3542.079059444537, 3560.9821950890077, 3570.6429357064344, 3599.964000359989, 3601.441776524643, 31937.36125277505, 32008.359832803446, 32020.519692204634, 32076.0, 32081.0, 4743.596794272491, 4763.833265835679, 4832.73419961902, 4854.12625727369, 4898.53463920927, 188922.0, 190177.0, 190634.0, 191319.0, 191696.0, 49849.50150498521, 49892.750536247084, 50058.49941500611, 50313.0, 50432.49567504278, 53587.73206133944, 53712.194317085276, 53904.46095539073, 53912.73043634757, 53996.0, 16641.0, 16657.0, 16667.083310417802, 16716.582085447717, 16817.327306907588, 10962.397068161328, 11010.064144547643, 13565.457381704819, 13636.386362613706, 13729.244891530861, 12730.936345318214, 14836.258187090601, 14975.925120374546, 15144.242787860563, 15191.620209494851, 5932.139839723302, 6032.426919442646, 6042.697865106815, 6186.628802271878, 6209.751609935552, 965.6571916969515, 984.2126298960802, 991.9553620087113, 1019.5513973851481, 1024.3904876598413, 22805.88597057004, 22957.0, 23775.405614859767, 29951.400971980875, 30705.0, 1051.8527406163143, 1105.5467258424007, 1107.501624269066, 1109.7336639206533, 1121.8653761548503, 12129.354757447425, 12702.42839072244, 12723.727627237202, 16140.515784526482, 16226.935334019236, 237467.0, 239506.0, 239514.0, 240906.0, 240970.0, 60205.698971505735, 60525.69737151374, 60902.69548652229, 61361.38638613646, 61447.385526144164, 1487.620656732548, 1532.3181184372847, 1537.2621141851973, 1540.276070246982, 1549.9225038748018, 27235.59146612763, 27771.861140694975, 28052.579211312255, 28258.576121357786, 28585.85707071451, 53763.462365375846, 55610.72194639001, 55658.16512752231, 55670.0, 55704.0, 219870.0, 220727.0, 220897.0, 222008.0, 222241.0, 33318.50022249619, 38112.8075911644, 38583.61416385912, 38881.80559097186, 38897.222055559294, 40663.79668101641, 40738.79630601828, 42072.3689144657, 46324.76837615925, 47512.52487475219, 29994.55008174923, 30697.53953690741, 31749.0, 32217.194570134998, 32601.836990814892, 32076.679233208302, 32116.0, 32182.67817321797, 32391.704331826462, 32408.675913240564, 35902.820485897406, 37252.813735931144, 37297.81351093227, 37308.81345593363, 37321.81339093287, 32114.678853211168, 32562.511562327058, 32905.0, 33136.834315828266, 33170.83414582912, 23430.53138937246, 23604.52790944206, 23654.763452365944, 23966.40083997914, 24299.0], "value": 17962.206094599638, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.529705641374, 578.1587789765891, 580.7996241296756, 581.933077696065, 597.3578403216542, 21877.562448751014, 22950.0, 22983.655245171314, 23049.654255186186, 23079.30762077136, 18280.81719182808, 19229.42311730647, 22554.323370298876, 24749.62875556868, 24762.628560571608, 6266.185395898527, 6350.555461117724, 6379.138816259792, 6384.872302553946, 6397.648129352907, 5976.790812321569, 6398.9040164397575, 6503.837404064889, 6511.837204069889, 6512.511561632873, 6431.967840160793, 6432.42108210261, 6433.38882806135, 6435.195600549931, 6443.5167362448, 6422.03669449584, 6424.2290925089055, 6434.131392262078, 6437.485001199892, 6441.259255185667, 5951.345352011275, 5963.7912673056235, 5966.612170208922, 5983.611065280743, 5985.610935289235, 27263.318417039536, 27952.580711289345, 28396.574051389245, 28435.146945591667, 28883.277918052216, 1851.0, 1853.203122657257, 1853.731208974691, 1858.256697321069, 1861.5532272254698, 18499.815001849944, 19140.808591914043, 19146.80853191478, 19153.04234788255, 19170.424887253404, 2341.180586794608, 2346.5424242272766, 2353.8940747666225, 2403.447207142367, 2405.7594240575804, 131372.0, 134731.999999999, 135570.32214838863, 136248.31875840656, 136664.31667841697, 14109.294535273195, 14463.49377771773, 14499.492517761828, 14538.781918271234, 14780.778288325684, 21696.89151554232, 22309.888450557966, 22399.55200895973, 22459.326220213094, 22471.32586022422, 10222.335548189343, 10246.180305575535, 10260.589576417009, 10341.327813692236, 10451.634192803216, 9480.288978326653, 9641.566129524188, 9673.516324183904, 9693.515324233902, 9793.363431376938, 9169.174774270348, 9260.907390926139, 9289.303302252358, 9453.196478299422, 9497.857532137023, 2702.7567518923393, 2768.806183567131, 2812.4375124975086, 2816.4507920955625, 2823.2095013396283, 913.3332667153015, 925.0980294213223, 948.4641177734558, 963.889152747429, 977.6578197630914, 17750.822491775176, 18287.45137645873, 18376.632467350577, 18607.255709771456, 18978.33575824867, 983.7835676151235, 992.1467537917385, 997.6707686463595, 1017.531935309751, 1023.1150055202345, 9948.950255248677, 10089.293749437616, 10094.747631309276, 10190.796184076276, 10496.632617858339, 454807.0, 456548.0, 458582.0, 459130.0, 464139.0, 118657.40671296588, 119411.0, 121694.39152804179, 121932.39033804952, 122102.0, 1183.988160118405, 1202.3327053485225, 1202.8917397434097, 1208.4320369426355, 1211.1461419699092, 15454.459093931659, 15967.760483592523, 16170.919145404669, 16271.186440678155, 16599.751003734713, 44628.661140165845, 50436.24345634745, 51569.74215128901, 53879.0, 54762.45237547573, 63598.0, 64242.0, 64765.352346475935, 64867.67566162139, 65570.0, 14042.227677477427, 14115.858841411455, 14137.858621413654, 14167.64580885486, 14284.357203926056, 21050.894745526173, 21869.0, 22219.0, 22395.77604223937, 22399.552008959403, 17481.21334539974, 17558.473245802645, 17654.558636034202, 17730.290788368322, 17838.19728112212, 15033.849661503245, 15359.769603456181, 15410.614734631275, 15671.0, 15711.371545138523, 17599.912000439916, 17752.2011509485, 18016.2793488259, 18048.90975545114, 18073.548161296072, 14063.859361406665, 14096.929515352358, 14104.08323458972, 14125.646858828612, 14136.78794818058, 17362.045087520306, 17471.56321091983, 17593.648127037133, 18498.537536561693, 18867.15097820574], "value": 11564.224394343591, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.310769953474, 1409.2671810658458, 1411.5976946570229, 1413.060314890597, 1425.6079578116014, 13068.477260909563, 13694.383752731126, 13730.38213280401, 13757.0, 13777.793333100011, 14282.143071415723, 14588.12471251723, 14610.926945365258, 14612.196329201915, 14636.707265854677, 2594.740525947409, 2638.2349118755537, 2688.2876037849987, 2692.1250593557074, 2720.102366219143, 2670.4792565449743, 2695.514807334679, 2698.811083224175, 2699.095802906019, 2700.932476688079, 2640.0627777139125, 2650.8144429889917, 2670.5460071787747, 2672.812903096784, 2713.74219449152, 2601.219634109769, 2608.1914606472055, 2616.110522422376, 2625.737426257378, 2670.8531030793297, 2558.1174494799266, 2610.778083862874, 2632.5919482480244, 2645.2989957661234, 2646.087099950514, 13864.861351386458, 13953.093048951887, 14073.225972571505, 14093.365798539091, 14093.577192684235, 7413.2957369049855, 7679.731209407644, 7699.422543309274, 7727.1886451922865, 7731.072271327421, 4851.417829860404, 4993.253710459233, 5052.368453943257, 5126.641135120543, 5143.639945203838, 1727.4990252826665, 1729.8616110711114, 1743.7297218931085, 1794.1926133240095, 1797.343969451154, 22367.217147399762, 22511.77488225113, 22586.88706556473, 22598.096076156933, 22621.999999999836, 6224.813255602339, 6443.838904027343, 6525.184351956005, 6586.0, 6589.538732288694, 15781.0, 15785.526434206991, 15786.21068946548, 15831.208439577975, 15837.524874253791, 7146.785596432115, 7423.851522969619, 7443.516171448839, 7444.404447644176, 7448.515846470071, 5533.612647114744, 6189.81430557084, 6901.309869013058, 6914.9308506915295, 7135.89296160558, 4927.187014142698, 5031.874203144877, 5042.669894931876, 5055.797768089235, 5083.567896728746, 1414.3776738235144, 1430.6280367104628, 1437.381925771928, 1441.070509521353, 1466.0836976889443, 826.2646244842095, 840.7982084299724, 854.5214679779343, 859.1322764008396, 878.5914549734404, 6503.674816259168, 6779.389854913082, 6872.003559483846, 6874.9656251719425, 7039.0, 677.4648028057877, 700.0863872646246, 702.3398005874461, 709.7870638808337, 720.3840716187584, 4108.096218831853, 4188.183304255641, 4267.637250833652, 4663.953360466352, 4699.95300047002, 18013.549661258574, 18219.180136893872, 18889.338873139375, 18901.24395024211, 19039.904800476186, 18424.447266582025, 18697.81302186988, 19158.904205478884, 19354.129064191864, 19355.80644193568, 10620.575176992988, 10668.0, 10672.30630009047, 10719.035286824379, 10814.35113893169, 7207.171175314906, 7219.772638651547, 7456.32893039629, 7464.0, 7613.08642962848, 15815.762763558785, 16293.266802993656, 16313.67372652564, 16421.835781642032, 16515.0, 15085.24573771329, 15922.60193495172, 15965.600859978593, 16918.06950617728, 17181.484555463358, 10070.798584028133, 10104.949475252577, 10187.08316251541, 10207.081362677394, 10211.387316761018, 12712.74574508523, 12719.046071544857, 12793.104482686123, 12853.485860565472, 13231.338433078115, 10548.841767373342, 10732.356058636507, 10769.784604307713, 10895.891041089488, 10952.39761813076, 9566.473843938651, 10085.394876307446, 10250.743731406475, 10324.587016519556, 10580.84128738085, 8314.667413303641, 8526.658933642833, 8527.445716028438, 8529.360297977553, 8532.70135545253, 6292.370762923671, 6332.208473940757, 6544.411003009752, 6606.966965165144, 6665.633390163587, 6089.391060893875, 6391.48868090561, 6393.93606063952, 6425.710843011982, 6462.935370646422], "value": 6097.671882721629, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.38211853065127, 497.9950200497996, 518.9455107213743, 519.0242344392542, 533.4558750074924, 6153.476954458871, 6163.845903852406, 6189.350118237588, 6200.10098535712, 6238.251409830821, 5884.205632239647, 5946.881062378749, 6026.758929642809, 6072.605280656754, 6123.63258204508, 4862.197737373329, 4869.078552868286, 4923.458419573844, 4938.2345736410825, 4938.456769755342, 4719.598834099105, 4868.391451068616, 4885.535874091974, 4924.162892308316, 4940.357753492041, 4881.731504767236, 4882.121218180726, 4920.409550853902, 4920.581750551208, 4951.579115775164, 4820.228763397857, 4858.271259311096, 4894.67502936106, 4915.459299477055, 4920.852374428773, 4352.325389564622, 4367.519572846984, 4392.297232442826, 4396.692231543793, 4422.0, 5893.941060589382, 5958.672273024982, 5969.761209551612, 5989.371116032841, 6045.274567051938, 5579.879645734635, 5605.523530499912, 5613.494785469286, 5629.902169077031, 5673.23411339468, 6311.779087731953, 6370.777022804226, 6388.808335749888, 6520.543561950665, 6527.902081468782, 4753.78607962639, 4805.6155507559315, 4812.326274321597, 4834.226523756217, 4858.246971719389, 6602.613451175259, 6772.8984065239065, 6804.761833335859, 6823.727050917957, 6836.794896153124, 4883.389576302962, 4884.48712885142, 4884.926726099111, 4909.828156014522, 4943.629227807929, 4912.1649319615035, 4994.850154495371, 4995.500449954975, 5020.799168033238, 5027.91341593242, 5579.302587176603, 5600.60795744294, 5661.622118710152, 5665.668567886543, 5701.4298570142655, 6481.837954051092, 6497.772577959749, 6515.967420162869, 6550.639714815731, 6573.605583664996, 1658.8092369377675, 1663.9251233694513, 1700.540853969446, 1729.7751292331916, 1747.9126043697763, 1202.6512311429763, 1218.8598311194326, 1226.72420682491, 1234.5555599983998, 1238.73986462842, 5689.236336735626, 5718.370979192286, 5734.713264336767, 5746.166805813134, 5762.942370576325, 6510.804675859732, 6550.508711846631, 6629.303923088103, 6654.168228971379, 6696.966515167393, 5749.453801888814, 5757.683327417032, 5769.307683077974, 5808.154004537746, 5810.88378232433, 3534.6642069003406, 3553.9644603553634, 3565.16218688608, 3581.0966047008887, 3582.2119133790525, 6380.521460890452, 6464.450521705614, 6470.579412338183, 6492.67536623167, 6579.835504112435, 6851.143607049119, 6886.931130688729, 6920.861582768417, 6937.7273272312395, 6953.304669533006, 6468.482521398183, 6484.935150648527, 6498.870022599522, 6516.609003459807, 6568.67156642166, 6665.7333706653135, 6686.966565167143, 6723.7646682365885, 6746.460283177432, 6758.628275444898, 6551.901721474277, 6600.174978127734, 6608.306127856506, 6630.30381809903, 6680.5991640501725, 6323.494120470444, 6351.872962540816, 6402.647854368054, 6443.548951573345, 6448.613083215022, 4933.975330123327, 4960.255961605788, 4960.47914968923, 4979.576735977483, 4996.151423973008, 6706.530542862148, 6770.593764374154, 6776.4240039595215, 6799.89800153008, 6817.5909445433435, 6493.123428337303, 6495.1556297681, 6543.574667646588, 6565.704543295467, 6569.4744420447205, 6195.101710251898, 6464.256610489854, 6483.4164925156965, 6496.350364963466, 6509.837254068686, 6108.5723999319625, 6214.0483270446175, 6313.2424109107205, 6342.619442833445, 6360.681965901594, 6393.264774550986, 6409.839754006187, 6463.57986730861, 6490.8052758417325, 6547.934520654732, 6223.502119830312, 6231.2522497300615, 6269.31037585856, 6305.621662700252, 6359.650219237987], "value": 5000.654617269429, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.7634176943111, 906.7098528470892, 911.990880091199, 913.2465715784475, 921.5898924978385, 17732.734008989875, 18873.811261887393, 19025.0, 19049.238030478766, 20441.284555040576, 20366.287179948704, 21217.257395991142, 21435.892820535915, 21596.2441314554, 21628.8918555407, 1014.4521958142594, 1030.2479190191166, 1031.0823367203195, 1038.4548112241096, 1038.7558923652934, 1039.1842403713088, 1042.0569384706853, 1042.5933885784511, 1042.6298663974255, 1042.9843552346722, 1018.551837191637, 1074.4466599701161, 1076.0745758647556, 1077.709018564987, 1087.836824476327, 1068.9412082335468, 1069.7272195590153, 1069.7967386196597, 1070.3684825952687, 1071.587438836046, 780.4224873593556, 781.4998401023349, 781.9687212511493, 782.3936449251819, 784.2079499705281, 18685.81314186868, 19003.524911877314, 19063.523411914673, 19154.329598464126, 19205.711914321295, 19214.615707685767, 19232.423027309203, 19552.31566895152, 19938.302159424496, 19974.10116544759, 14648.707025859423, 14682.192479413628, 14872.405103795834, 14907.031042982171, 14913.776293355608, 3413.112590726429, 3491.8952431427097, 3491.9524142757073, 3492.161881148506, 3501.369753444379, 121897.39051304775, 126427.0, 129066.0, 130958.0, 130997.0, 15068.54794356171, 15526.068435893883, 15526.844731552539, 15542.378304867678, 15603.921980390025, 28483.572746408405, 28760.424791504473, 28768.71231287702, 28788.136355909355, 28843.0, 7694.461387702807, 7864.842703145905, 7868.134505204423, 7945.728683410626, 7953.403494737918, 4063.6749060075713, 4118.814653340607, 4138.193052354822, 4149.751014939114, 4153.7300075494995, 4725.5510726480925, 4819.542143496362, 4836.371271734652, 4848.587870031016, 4876.414830220397, 871.904090550039, 896.1173244354305, 897.4077109107957, 898.7079199260266, 901.4185850126763, 625.5683578331028, 644.0789670770749, 658.2758965138405, 670.6009924095156, 674.6188403552076, 10812.243142980073, 11709.473073711704, 11955.820662690066, 12194.51221951112, 12375.876241237653, 648.2965981909587, 657.2769953051605, 676.8984652302096, 678.2911857109333, 703.2088899987514, 5216.321878155892, 5264.6314757966575, 5339.3058902342445, 5379.973100134474, 5441.564674826004, 93170.0, 95805.52097239469, 98794.5060274694, 99713.0, 99789.00210997797, 85442.0, 90646.09353906376, 90906.54546727224, 91576.0, 92085.0, 74049.0, 76105.6194719034, 77265.2273477258, 77706.6114669423, 79187.0, 10062.849057264, 10617.681469555926, 10641.467926603484, 10760.354378736987, 10833.404162770808, 35236.64763352334, 35744.28511429809, 36087.81956090308, 37310.0, 37964.0, 56327.0, 58907.705461474136, 63552.364476356495, 68210.0, 68565.0, 17713.645727085644, 18110.456686299432, 18224.45326640203, 18251.54371140732, 18286.63426731431, 20172.092255848816, 20405.979701015185, 20507.0, 20591.0, 20791.376258712262, 18785.624287514445, 18982.0, 19158.71261931044, 19169.712454313474, 19408.126634301207, 18223.81776182221, 18330.725039124158, 18417.631647367245, 18532.166052527937, 18954.810451895304, 17855.196516157062, 18155.092245387943, 18266.908665456587, 18627.627447450704, 18844.34044808425, 12550.246985180918, 12588.937055314665, 13218.272994985367, 13312.400941957441, 13462.528811491551, 13742.725145497234, 13829.0, 14118.0, 14852.183129927958, 14897.47858824936], "value": 8823.331510555705, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1561.3000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 119.0, 109.0, 108.0, 120.0, 111.0, 115.0, 101.0, 107.0, 107.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 123.0, 155.0, 190.0, 121.0, 154.0, 156.0, 120.0, 180.0, 120.0], "value": 147.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 164.0, 169.0, 166.0, 169.0, 161.0, 161.0, 164.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 82.0, 199.0, 75.0, 77.0, 76.0, 88.0, 98.0, 79.0], "value": 93.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 135.0, 80.0, 79.0, 78.0, 138.0, 80.0, 82.0, 124.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 113.0, 115.0, 114.0, 115.0, 120.0, 116.0, 124.0], "value": 116.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 150.0, 150.0, 149.0, 152.0, 152.0, 151.0, 150.0, 152.0, 153.0], "value": 151.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 99.0, 97.0, 94.0, 93.0, 94.0, 94.0, 93.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 60.0, 59.0, 57.0, 60.0, 57.0, 57.0, 62.0, 60.0], "value": 58.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 54.0, 60.0, 54.0, 59.0, 54.0, 55.0, 54.0, 54.0], "value": 56.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 88.0, 84.0, 81.0, 80.0, 80.0, 81.0, 80.0, 82.0, 83.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 138.0, 131.0, 128.0, 142.0, 135.0, 129.0, 127.0, 126.0, 171.0], "value": 137.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 181.0, 183.0, 184.0, 186.0, 186.0, 186.0, 181.0, 186.0], "value": 184.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 71.0, 73.0, 68.0, 70.0, 71.0, 73.0, 69.0, 70.0], "value": 70.5, "unit": "score"}]}]} 18:23:08 INFO - Return code: 0 18:23:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:23:08 INFO - # TBPL SUCCESS # 18:23:08 INFO - Running post-action listener: _package_coverage_data 18:23:08 INFO - Running post-action listener: _resource_record_post_action 18:23:08 INFO - [mozharness: 2017-07-29 01:23:08.239029Z] Finished run-tests step (success) 18:23:08 INFO - Running post-run listener: _resource_record_post_run 18:23:08 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 401408; Write bytes: 440754176; Read time: 264; Write time: 325128 18:23:08 INFO - TinderboxPrint: CPU usage
13.1% 18:23:08 INFO - TinderboxPrint: I/O read bytes / time
401,408 / 264 18:23:08 INFO - TinderboxPrint: I/O write bytes / time
440,754,176 / 325,128 18:23:08 INFO - TinderboxPrint: CPU idle
8,272.9 (86.8%) 18:23:08 INFO - TinderboxPrint: CPU user
1,202.7 (12.6%) 18:23:08 INFO - TinderboxPrint: Swap in / out
0 / 0 18:23:08 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 115408896; Read time: 120; Write time: 106764 18:23:08 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:08 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 393216; Write bytes: 325345280; Read time: 136; Write time: 218364 18:23:08 INFO - Running post-run listener: _upload_blobber_files 18:23:08 INFO - Blob upload gear active. 18:23:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:23:08 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:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:23:09 INFO - (blobuploader) - INFO - Open directory for files ... 18:23:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:23:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:23:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:10 INFO - (blobuploader) - INFO - Done attempting. 18:23:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:23:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:23:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:14 INFO - (blobuploader) - INFO - Done attempting. 18:23:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:23:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:23:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:15 INFO - (blobuploader) - INFO - Done attempting. 18:23:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:23:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:23:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:16 INFO - (blobuploader) - INFO - Done attempting. 18:23:16 INFO - (blobuploader) - INFO - Iteration through files over. 18:23:16 INFO - Return code: 0 18:23:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:23:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:23:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42f0a4e82176eab91678a162ea0666597c11a2c8ac8427f03c74b14e14c2d9bb8e24c24da3870d1d22d8123bf93204ba2ce28153a977be7902cbc2369be71e71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff4ad6ed91b1bd33b99904eb9cb9735f51382d8163edc4103afbfa11621703b7e18228eeb3f60b25155ee52258fd5524091075e55bd140ede7124ae33b7cf530", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/006d57fde7a4b6c3567a00d5f1f8cc92779e7870a7550c7c019be2ac10cee40a05b5c098865ce8e34a80e41344e9faf8a9fa2ccbfd179c2585b52a3d7c51b74c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4d440681594000c4bea2e49ce4a9d8a32a6f069ce53918ad7a2cdbc94f32069d0c362e6a6165a88268824107c06a5e68921d10916c10ec05a5c8c954ad31ac6"} 18:23:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:23:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:23:16 INFO - Contents: 18:23:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42f0a4e82176eab91678a162ea0666597c11a2c8ac8427f03c74b14e14c2d9bb8e24c24da3870d1d22d8123bf93204ba2ce28153a977be7902cbc2369be71e71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff4ad6ed91b1bd33b99904eb9cb9735f51382d8163edc4103afbfa11621703b7e18228eeb3f60b25155ee52258fd5524091075e55bd140ede7124ae33b7cf530", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/006d57fde7a4b6c3567a00d5f1f8cc92779e7870a7550c7c019be2ac10cee40a05b5c098865ce8e34a80e41344e9faf8a9fa2ccbfd179c2585b52a3d7c51b74c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4d440681594000c4bea2e49ce4a9d8a32a6f069ce53918ad7a2cdbc94f32069d0c362e6a6165a88268824107c06a5e68921d10916c10ec05a5c8c954ad31ac6"} 18:23:16 INFO - Running post-run listener: copy_logs_to_upload_dir 18:23:16 INFO - Copying logs to upload dir... 18:23:16 INFO - mkdir: /builds/slave/test/build/upload/logs 18:23:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.939608 ========= master_lag: 0.22 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-07-28 18:23:16.394232) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:16.398414) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230 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/sha512/42f0a4e82176eab91678a162ea0666597c11a2c8ac8427f03c74b14e14c2d9bb8e24c24da3870d1d22d8123bf93204ba2ce28153a977be7902cbc2369be71e71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff4ad6ed91b1bd33b99904eb9cb9735f51382d8163edc4103afbfa11621703b7e18228eeb3f60b25155ee52258fd5524091075e55bd140ede7124ae33b7cf530", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/006d57fde7a4b6c3567a00d5f1f8cc92779e7870a7550c7c019be2ac10cee40a05b5c098865ce8e34a80e41344e9faf8a9fa2ccbfd179c2585b52a3d7c51b74c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4d440681594000c4bea2e49ce4a9d8a32a6f069ce53918ad7a2cdbc94f32069d0c362e6a6165a88268824107c06a5e68921d10916c10ec05a5c8c954ad31ac6"} build_url:https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011232 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/sha512/42f0a4e82176eab91678a162ea0666597c11a2c8ac8427f03c74b14e14c2d9bb8e24c24da3870d1d22d8123bf93204ba2ce28153a977be7902cbc2369be71e71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff4ad6ed91b1bd33b99904eb9cb9735f51382d8163edc4103afbfa11621703b7e18228eeb3f60b25155ee52258fd5524091075e55bd140ede7124ae33b7cf530", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/006d57fde7a4b6c3567a00d5f1f8cc92779e7870a7550c7c019be2ac10cee40a05b5c098865ce8e34a80e41344e9faf8a9fa2ccbfd179c2585b52a3d7c51b74c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f4d440681594000c4bea2e49ce4a9d8a32a6f069ce53918ad7a2cdbc94f32069d0c362e6a6165a88268824107c06a5e68921d10916c10ec05a5c8c954ad31ac6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:16.430747) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:16.431538) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1501283052.724450-81379230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:16.506342) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-28 18:23:16.506667) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-28 18:23:16.506949) ========= ========= Total master_lag: 0.41 =========