builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-049 starttime: 1502971256.92 results: success (0) revision: 04bee69b3274bd8d5cf52d54a0a5cc14dbe8693a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:56.921939) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:56.922358) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:56.922638) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:56.934594) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:56.934888) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-17 05:00:57-- 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% 42.9M=0s 2017-08-17 05:00:57 (42.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116033 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:57.066744) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:57.067075) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020272 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:57.116376) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:57.117551) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 04bee69b3274bd8d5cf52d54a0a5cc14dbe8693a --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 04bee69b3274bd8d5cf52d54a0a5cc14dbe8693a --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-17 05:00:57,349 truncating revision to first 12 chars 2017-08-17 05:00:57,349 Setting DEBUG logging. 2017-08-17 05:00:57,349 attempt 1/10 2017-08-17 05:00:57,349 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/04bee69b3274?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-17 05:00:57,808 unpacking tar archive at: mozilla-central-04bee69b3274/testing/mozharness/ program finished with exit code 0 elapsedTime=0.767138 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:57.899207) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:57.899521) ========= script_repo_revision: 04bee69b3274bd8d5cf52d54a0a5cc14dbe8693a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:57.902320) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:57.902587) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-17 05:00:57.914352) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-17 05:00:57.914638) ========= /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=db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:00:58 INFO - MultiFileLogger online at 20170817 05:00:58 in /builds/slave/test 05:00:58 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 05:00:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:00:58 INFO - {'append_to_log': False, 05:00:58 INFO - 'base_work_dir': '/builds/slave/test', 05:00:58 INFO - 'blob_upload_branch': 'Firefox', 05:00:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:00:58 INFO - 'branch': 'Firefox', 05:00:58 INFO - 'buildbot_json_path': 'buildprops.json', 05:00:58 INFO - 'code_coverage': False, 05:00:58 INFO - 'config_files': ('talos/linux_config.py',), 05:00:58 INFO - 'default_actions': ('clobber', 05:00:58 INFO - 'read-buildbot-config', 05:00:58 INFO - 'download-and-extract', 05:00:58 INFO - 'populate-webroot', 05:00:58 INFO - 'create-virtualenv', 05:00:58 INFO - 'install', 05:00:58 INFO - 'setup-mitmproxy', 05:00:58 INFO - 'run-tests'), 05:00:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:00:58 INFO - 'disable_ccov_upload': False, 05:00:58 INFO - 'download_minidump_stackwalk': True, 05:00:58 INFO - 'download_symbols': 'ondemand', 05:00:58 INFO - 'enable_stylo': False, 05:00:58 INFO - 'enable_webrender': False, 05:00:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:00:58 INFO - 'tooltool.py': '/tools/tooltool.py', 05:00:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:00:58 INFO - '/tools/misc-python/virtualenv.py')}, 05:00:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:00:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:00:58 INFO - 'gecko_profile': False, 05:00:58 INFO - 'gecko_profile_interval': 0, 05:00:58 INFO - 'installer_path': 'installer.exe', 05:00:58 INFO - 'log_level': 'info', 05:00:58 INFO - 'log_name': 'talos', 05:00:58 INFO - 'log_to_console': True, 05:00:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:00:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:00:58 INFO - 'opt_config_files': (), 05:00:58 INFO - 'pip_index': False, 05:00:58 INFO - 'suite': 'dromaeojs-e10s', 05:00:58 INFO - 'system_bits': '32', 05:00:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:00:58 INFO - 'title': 'talos-linux64-ix-049', 05:00:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:00:58 INFO - 'use_talos_json': True, 05:00:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:00:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:00:58 INFO - 'work_dir': 'build'} 05:00:58 INFO - [mozharness: 2017-08-17 12:00:58.253910Z] Running clobber step. 05:00:58 INFO - Running pre-action listener: _resource_record_pre_action 05:00:58 INFO - Running main action method: clobber 05:00:58 INFO - rmtree: /builds/slave/test/build 05:00:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:00:59 INFO - Running post-action listener: _resource_record_post_action 05:00:59 INFO - [mozharness: 2017-08-17 12:00:59.400721Z] Finished clobber step (success) 05:00:59 INFO - [mozharness: 2017-08-17 12:00:59.400823Z] Running read-buildbot-config step. 05:00:59 INFO - Running pre-action listener: _resource_record_pre_action 05:00:59 INFO - Running main action method: read_buildbot_config 05:00:59 INFO - Using buildbot properties: 05:00:59 INFO - { 05:00:59 INFO - "project": "", 05:00:59 INFO - "product": "firefox", 05:00:59 INFO - "who": "cbook@mozilla.com", 05:00:59 INFO - "installer_path": "public/build/target.tar.bz2", 05:00:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 05:00:59 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:00:59 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 05:00:59 INFO - "stage_platform": "linux64-stylo", 05:00:59 INFO - "basedir": "/builds/slave/test", 05:00:59 INFO - "buildnumber": 37, 05:00:59 INFO - "platform": "ubuntu64_hw_stylo", 05:00:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:00:59 INFO - "slavebuilddir": "test", 05:00:59 INFO - "taskId": "BD9W6Z0HSLObbnc6bUT_Uw", 05:00:59 INFO - "branch": "mozilla-central", 05:00:59 INFO - "script_repo_revision": "production", 05:00:59 INFO - "revision": "04bee69b3274bd8d5cf52d54a0a5cc14dbe8693a", 05:00:59 INFO - "slavename": "talos-linux64-ix-049", 05:00:59 INFO - "repo_path": "mozilla-central" 05:00:59 INFO - } 05:00:59 INFO - Finding installer, test and symbols from parent task. 05:00:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BD9W6Z0HSLObbnc6bUT_Uw'}, attempt #1 05:00:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BD9W6Z0HSLObbnc6bUT_Uw'}, attempt #1 05:01:00 INFO - Task dependencies: Hf7JIq8cSQeLXgGMAgaziQ 05:01:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ'}, attempt #1 05:01:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.tar.bz2 05:01:00 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.test_packages.json 05:01:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.crashreporter-symbols.zip 05:01:00 INFO - Running post-action listener: _resource_record_post_action 05:01:00 INFO - [mozharness: 2017-08-17 12:01:00.787186Z] Finished read-buildbot-config step (success) 05:01:00 INFO - [mozharness: 2017-08-17 12:01:00.787412Z] Running download-and-extract step. 05:01:00 INFO - Running pre-action listener: _resource_record_pre_action 05:01:00 INFO - Running main action method: download_and_extract 05:01:00 INFO - mkdir: /builds/slave/test/build/tests 05:01: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')]} 05:01:00 INFO - trying https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.test_packages.json 05:01:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:01:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:01:01 INFO - Downloaded 989 bytes. 05:01:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:01:01 INFO - Using the following test package requirements: 05:01:01 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:01:01 INFO - u'common': [u'target.common.tests.zip'], 05:01:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:01:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:01:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:01:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:01:01 INFO - u'mozbase': [u'target.common.tests.zip'], 05:01:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:01:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:01:01 INFO - u'web-platform': [u'target.common.tests.zip', 05:01:01 INFO - u'target.web-platform.tests.tar.gz'], 05:01:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:01:01 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:01:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.common.tests.zip 05:01:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:01:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.common.tests.zip into memory 05:01:02 INFO - Content-Length response header: 53999674 05:01:02 INFO - Bytes received: 53999674 05:01:13 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:01:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.common.tests.zip 05:01:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:01:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.common.tests.zip into memory 05:01:14 INFO - Content-Length response header: 53999674 05:01:14 INFO - Bytes received: 53999674 05:01:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.talos.tests.zip 05:01:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:01:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.talos.tests.zip into memory 05:01:26 INFO - Content-Length response header: 14496161 05:01:26 INFO - Bytes received: 14496161 05:01:27 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')]} 05:01:27 INFO - trying https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.tar.bz2 05:01:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:01:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:01:28 INFO - Downloaded 62187487 bytes. 05:01:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.tar.bz2 05:01:28 INFO - mkdir: /builds/slave/test/properties 05:01:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:01:28 INFO - Writing to file /builds/slave/test/properties/build_url 05:01:28 INFO - Contents: 05:01:28 INFO - build_url:https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.tar.bz2 05:01:28 INFO - Running post-action listener: _resource_record_post_action 05:01:28 INFO - Running post-action listener: set_extra_try_arguments 05:01:28 INFO - [mozharness: 2017-08-17 12:01:28.336045Z] Finished download-and-extract step (success) 05:01:28 INFO - [mozharness: 2017-08-17 12:01:28.336144Z] Running populate-webroot step. 05:01:28 INFO - Running pre-action listener: _resource_record_pre_action 05:01:28 INFO - Running main action method: populate_webroot 05:01:28 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:01:28 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 05:01:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:01:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:01:28 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 05:01:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:01:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:01:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:01:28 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:01:28 INFO - 'talos_options': ['--stylo'], 05:01:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:01:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:01:28 INFO - 'tests': ['damp', 'tps']}, 05:01:28 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:01:28 INFO - 'talos_options': ['--stylo'], 05:01:28 INFO - 'tests': ['damp', 'tps']}, 05:01:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:01:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:01:28 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 05:01:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:01:28 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:01:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:01:28 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:01:28 INFO - 'talos_options': ['--stylo'], 05:01:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:01:28 INFO - 'other-e10s': {'tests': ['a11yr', 05:01:28 INFO - 'ts_paint', 05:01:28 INFO - 'tpaint', 05:01:28 INFO - 'sessionrestore', 05:01:28 INFO - 'sessionrestore_many_windows', 05:01:28 INFO - 'sessionrestore_no_auto_restore', 05:01:28 INFO - 'tabpaint']}, 05:01:28 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 05:01:28 INFO - 'tests': ['a11yr', 05:01:28 INFO - 'ts_paint', 05:01:28 INFO - 'tpaint', 05:01:28 INFO - 'sessionrestore', 05:01:28 INFO - 'sessionrestore_many_windows', 05:01:28 INFO - 'sessionrestore_no_auto_restore', 05:01:28 INFO - 'tabpaint']}, 05:01:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:01:28 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:01:28 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 05:01:28 INFO - 'tests': ['bloom_basic_singleton']}, 05:01:28 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 05:01:28 INFO - 'tests': ['bloom_basic']}, 05:01:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:01:28 INFO - 'tsvgr_opacity', 05:01:28 INFO - 'tart', 05:01:28 INFO - 'tscrollx', 05:01:28 INFO - 'tsvg_static']}, 05:01:28 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 05:01:28 INFO - 'tests': ['tsvgx', 05:01:28 INFO - 'tsvgr_opacity', 05:01:28 INFO - 'tart', 05:01:28 INFO - 'tscrollx', 05:01:28 INFO - 'tsvg_static']}, 05:01:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:01:28 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:01:28 INFO - 'talos_options': ['--stylo'], 05:01:28 INFO - 'tests': ['tp5o']}, 05:01:28 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:01:28 INFO - 'talos_options': ['--mitmproxy', 05:01:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:01:28 INFO - '--firstNonBlankPaint'], 05:01:28 INFO - 'tests': ['tp6_google', 05:01:28 INFO - 'tp6_youtube', 05:01:28 INFO - 'tp6_amazon', 05:01:28 INFO - 'tp6_facebook']}, 05:01:28 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:01:28 INFO - 'talos_options': ['--stylo', 05:01:28 INFO - '--mitmproxy', 05:01:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:01:28 INFO - '--firstNonBlankPaint'], 05:01:28 INFO - 'tests': ['tp6_google', 05:01:28 INFO - 'tp6_youtube', 05:01:28 INFO - 'tp6_amazon', 05:01:28 INFO - 'tp6_facebook']}, 05:01:28 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:01:28 INFO - 'talos_options': ['--stylo', 05:01:28 INFO - '--stylo-threads=1', 05:01:28 INFO - '--mitmproxy', 05:01:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:01:28 INFO - '--firstNonBlankPaint'], 05:01:28 INFO - 'tests': ['tp6_google', 05:01:28 INFO - 'tp6_youtube', 05:01:28 INFO - 'tp6_amazon', 05:01:28 INFO - 'tp6_facebook']}, 05:01:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:01:28 INFO - 'talos_options': ['--xperf_path', 05:01:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:01:28 INFO - 'tests': ['tp5n']}, 05:01:28 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 05:01:28 INFO - 'talos_options': ['--stylo', 05:01:28 INFO - '--xperf_path', 05:01:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:01:28 INFO - 'tests': ['tp5n']}}} 05:01:28 INFO - Running post-action listener: _resource_record_post_action 05:01:28 INFO - [mozharness: 2017-08-17 12:01:28.350521Z] Finished populate-webroot step (success) 05:01:28 INFO - [mozharness: 2017-08-17 12:01:28.350612Z] Running create-virtualenv step. 05:01:28 INFO - Running pre-action listener: _resource_record_pre_action 05:01:28 INFO - Running main action method: create_virtualenv 05:01:28 INFO - Creating virtualenv /builds/slave/test/build/venv 05:01:28 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 05:01:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:01:28 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:01:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:01:28 INFO - Using real prefix '/usr' 05:01:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:01:28 INFO - Installing distribute.............................................................................................................................................................................................done. 05:01:30 INFO - Installing pip.................done. 05:01:30 INFO - Return code: 0 05:01:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:01:31 INFO - Reading from file tmpfile_stdout 05:01:31 INFO - Output received: 05:01:31 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) 05:01:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:31 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 0x1f501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2054a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f7ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f50b0>, '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 0x21f5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f6440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', '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 05:01:31 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 05:01:31 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 05:01:31 INFO - Using env: {'DISPLAY': ':0', 05:01:31 INFO - 'HOME': '/home/cltbld', 05:01:31 INFO - 'LANG': 'en_US.UTF-8', 05:01:31 INFO - 'LANGUAGE': 'en_US:en', 05:01:31 INFO - 'LOGNAME': 'cltbld', 05:01:31 INFO - 'MAIL': '/var/mail/cltbld', 05:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:31 INFO - 'MOZ_NO_REMOTE': '1', 05:01:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:31 INFO - 'NO_EM_RESTART': '1', 05:01:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:31 INFO - 'PWD': '/builds/slave/test', 05:01:31 INFO - 'SHELL': '/bin/bash', 05:01:31 INFO - 'SHLVL': '1', 05:01:31 INFO - 'TERM': 'linux', 05:01:31 INFO - 'TMOUT': '86400', 05:01:31 INFO - 'USER': 'cltbld', 05:01:31 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', 05:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:31 INFO - '_': '/tools/buildbot/bin/python'} 05:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:31 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 05:01:31 INFO - Cleaning up... 05:01:31 INFO - Return code: 0 05:01:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:31 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 0x1f501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2054a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f7ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f50b0>, '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 0x21f5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f6440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', '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 05:01:31 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 05:01:31 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 05:01:31 INFO - Using env: {'DISPLAY': ':0', 05:01:31 INFO - 'HOME': '/home/cltbld', 05:01:31 INFO - 'LANG': 'en_US.UTF-8', 05:01:31 INFO - 'LANGUAGE': 'en_US:en', 05:01:31 INFO - 'LOGNAME': 'cltbld', 05:01:31 INFO - 'MAIL': '/var/mail/cltbld', 05:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:31 INFO - 'MOZ_NO_REMOTE': '1', 05:01:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:31 INFO - 'NO_EM_RESTART': '1', 05:01:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:31 INFO - 'PWD': '/builds/slave/test', 05:01:31 INFO - 'SHELL': '/bin/bash', 05:01:31 INFO - 'SHLVL': '1', 05:01:31 INFO - 'TERM': 'linux', 05:01:31 INFO - 'TMOUT': '86400', 05:01:31 INFO - 'USER': 'cltbld', 05:01:31 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', 05:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:31 INFO - '_': '/tools/buildbot/bin/python'} 05:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:31 INFO - Downloading/unpacking psutil>=3.1.1 05:01:31 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 05:01:31 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 05:01:31 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 05:01:31 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 05:01:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:01:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:01:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:01:32 INFO - Installing collected packages: psutil 05:01:32 INFO - Running setup.py install for psutil 05:01:32 INFO - building 'psutil._psutil_linux' extension 05:01:32 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 05:01:33 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 05:01:33 INFO - building 'psutil._psutil_posix' extension 05:01:33 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 05:01:33 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 05:01:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:01:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:01:33 INFO - Successfully installed psutil 05:01:33 INFO - Cleaning up... 05:01:33 INFO - Return code: 0 05:01:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:01:33 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')]} 05:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:33 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')]} 05:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:33 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 0x1f501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2054a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f7ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f50b0>, '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 0x21f5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f6440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', '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 05:01:33 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 05:01:33 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 05:01:33 INFO - Using env: {'DISPLAY': ':0', 05:01:33 INFO - 'HOME': '/home/cltbld', 05:01:33 INFO - 'LANG': 'en_US.UTF-8', 05:01:33 INFO - 'LANGUAGE': 'en_US:en', 05:01:33 INFO - 'LOGNAME': 'cltbld', 05:01:33 INFO - 'MAIL': '/var/mail/cltbld', 05:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:33 INFO - 'MOZ_NO_REMOTE': '1', 05:01:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:33 INFO - 'NO_EM_RESTART': '1', 05:01:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:33 INFO - 'PWD': '/builds/slave/test', 05:01:33 INFO - 'SHELL': '/bin/bash', 05:01:33 INFO - 'SHLVL': '1', 05:01:33 INFO - 'TERM': 'linux', 05:01:33 INFO - 'TMOUT': '86400', 05:01:33 INFO - 'USER': 'cltbld', 05:01:33 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', 05:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:33 INFO - '_': '/tools/buildbot/bin/python'} 05:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:01:33 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 05:01:33 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 05:01:33 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 05:01:33 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 05:01:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:01:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:01:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:01:34 INFO - Installing collected packages: mozsystemmonitor 05:01:34 INFO - Running setup.py install for mozsystemmonitor 05:01:34 INFO - Successfully installed mozsystemmonitor 05:01:34 INFO - Cleaning up... 05:01:34 INFO - Return code: 0 05:01:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:34 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 0x1f501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2054a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f7ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f50b0>, '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 0x21f5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f6440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', '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 05:01:34 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 05:01:34 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 05:01:34 INFO - Using env: {'DISPLAY': ':0', 05:01:34 INFO - 'HOME': '/home/cltbld', 05:01:34 INFO - 'LANG': 'en_US.UTF-8', 05:01:34 INFO - 'LANGUAGE': 'en_US:en', 05:01:34 INFO - 'LOGNAME': 'cltbld', 05:01:34 INFO - 'MAIL': '/var/mail/cltbld', 05:01:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:34 INFO - 'MOZ_NO_REMOTE': '1', 05:01:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:34 INFO - 'NO_EM_RESTART': '1', 05:01:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:34 INFO - 'PWD': '/builds/slave/test', 05:01:34 INFO - 'SHELL': '/bin/bash', 05:01:34 INFO - 'SHLVL': '1', 05:01:34 INFO - 'TERM': 'linux', 05:01:34 INFO - 'TMOUT': '86400', 05:01:34 INFO - 'USER': 'cltbld', 05:01:34 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', 05:01:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:34 INFO - '_': '/tools/buildbot/bin/python'} 05:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:35 INFO - Downloading/unpacking jsonschema==2.5.1 05:01:35 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 05:01:35 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 05:01:35 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 05:01:35 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 05:01:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:01:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:01:36 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 05:01:36 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 05:01:36 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 05:01:36 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 05:01:36 INFO - Downloading functools32-3.2.3-2.tar.gz 05:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:01:36 INFO - warning: no files found matching '*.txt' 05:01:36 INFO - no previously-included directories found matching 'build' 05:01:36 INFO - no previously-included directories found matching 'dist' 05:01:36 INFO - no previously-included directories found matching '.git*' 05:01:36 INFO - Installing collected packages: jsonschema, functools32 05:01:36 INFO - Running setup.py install for functools32 05:01:36 INFO - warning: no files found matching '*.txt' 05:01:36 INFO - no previously-included directories found matching 'build' 05:01:36 INFO - no previously-included directories found matching 'dist' 05:01:36 INFO - no previously-included directories found matching '.git*' 05:01:36 INFO - Successfully installed jsonschema functools32 05:01:36 INFO - Cleaning up... 05:01:36 INFO - Return code: 0 05:01:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:01:36 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')]} 05:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:36 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')]} 05:01:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:36 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 0x1f501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2054a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f7ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f50b0>, '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 0x21f5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f6440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', '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 05:01:36 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 05:01:36 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 05:01:36 INFO - Using env: {'DISPLAY': ':0', 05:01:36 INFO - 'HOME': '/home/cltbld', 05:01:36 INFO - 'LANG': 'en_US.UTF-8', 05:01:36 INFO - 'LANGUAGE': 'en_US:en', 05:01:36 INFO - 'LOGNAME': 'cltbld', 05:01:36 INFO - 'MAIL': '/var/mail/cltbld', 05:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:36 INFO - 'MOZ_NO_REMOTE': '1', 05:01:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:36 INFO - 'NO_EM_RESTART': '1', 05:01:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:36 INFO - 'PWD': '/builds/slave/test', 05:01:36 INFO - 'SHELL': '/bin/bash', 05:01:36 INFO - 'SHLVL': '1', 05:01:36 INFO - 'TERM': 'linux', 05:01:36 INFO - 'TMOUT': '86400', 05:01:36 INFO - 'USER': 'cltbld', 05:01:36 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', 05:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:36 INFO - '_': '/tools/buildbot/bin/python'} 05:01:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:01:36 INFO - Cleaning up... 05:01:36 INFO - Return code: 0 05:01:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:01:36 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')]} 05:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:36 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')]} 05:01:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:36 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 0x1f501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2054a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f7ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f50b0>, '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 0x21f5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f6440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', '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 05:01:36 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 05:01:36 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 05:01:36 INFO - Using env: {'DISPLAY': ':0', 05:01:36 INFO - 'HOME': '/home/cltbld', 05:01:36 INFO - 'LANG': 'en_US.UTF-8', 05:01:36 INFO - 'LANGUAGE': 'en_US:en', 05:01:36 INFO - 'LOGNAME': 'cltbld', 05:01:36 INFO - 'MAIL': '/var/mail/cltbld', 05:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:36 INFO - 'MOZ_NO_REMOTE': '1', 05:01:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:36 INFO - 'NO_EM_RESTART': '1', 05:01:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:36 INFO - 'PWD': '/builds/slave/test', 05:01:36 INFO - 'SHELL': '/bin/bash', 05:01:36 INFO - 'SHLVL': '1', 05:01:36 INFO - 'TERM': 'linux', 05:01:36 INFO - 'TMOUT': '86400', 05:01:36 INFO - 'USER': 'cltbld', 05:01:36 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', 05:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:36 INFO - '_': '/tools/buildbot/bin/python'} 05:01:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:36 INFO - Downloading/unpacking blobuploader==1.2.4 05:01:36 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 05:01:36 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 05:01:36 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 05:01:36 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 05:01:38 INFO - Downloading blobuploader-1.2.4.tar.gz 05:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:01:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:01:38 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 05:01:38 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 05:01:38 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 05:01:38 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 05:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:01:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:01:38 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 05:01:38 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 05:01:38 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 05:01:38 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 05:01:38 INFO - Downloading docopt-0.6.1.tar.gz 05:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:01:38 INFO - Installing collected packages: blobuploader, requests, docopt 05:01:38 INFO - Running setup.py install for blobuploader 05:01:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:01:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:01:38 INFO - Running setup.py install for requests 05:01:39 INFO - Running setup.py install for docopt 05:01:39 INFO - Successfully installed blobuploader requests docopt 05:01:39 INFO - Cleaning up... 05:01:39 INFO - Return code: 0 05:01:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:01:39 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')]} 05:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:39 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')]} 05:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:39 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 0x1f501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2054a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f7ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f50b0>, '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 0x21f5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f6440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', '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 05:01:39 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 05:01:39 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 05:01:39 INFO - Using env: {'DISPLAY': ':0', 05:01:39 INFO - 'HOME': '/home/cltbld', 05:01:39 INFO - 'LANG': 'en_US.UTF-8', 05:01:39 INFO - 'LANGUAGE': 'en_US:en', 05:01:39 INFO - 'LOGNAME': 'cltbld', 05:01:39 INFO - 'MAIL': '/var/mail/cltbld', 05:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:39 INFO - 'MOZ_NO_REMOTE': '1', 05:01:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:39 INFO - 'NO_EM_RESTART': '1', 05:01:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:39 INFO - 'PWD': '/builds/slave/test', 05:01:39 INFO - 'SHELL': '/bin/bash', 05:01:39 INFO - 'SHLVL': '1', 05:01:39 INFO - 'TERM': 'linux', 05:01:39 INFO - 'TMOUT': '86400', 05:01:39 INFO - 'USER': 'cltbld', 05:01:39 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', 05:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:39 INFO - '_': '/tools/buildbot/bin/python'} 05:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:01:39 INFO - Running setup.py (path:/tmp/pip-7OJy5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:01:39 INFO - Running setup.py (path:/tmp/pip-AquG13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:01:39 INFO - Running setup.py (path:/tmp/pip-bedyV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:01:39 INFO - Running setup.py (path:/tmp/pip-QvkusK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:01:39 INFO - Running setup.py (path:/tmp/pip-Ue6yFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:01:39 INFO - Running setup.py (path:/tmp/pip-9757wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:01:39 INFO - Running setup.py (path:/tmp/pip-J5pO3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:01:40 INFO - Running setup.py (path:/tmp/pip-PxrDFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:01:40 INFO - Running setup.py (path:/tmp/pip-DB55cz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:01:40 INFO - Running setup.py (path:/tmp/pip-ro5KMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:01:40 INFO - Running setup.py (path:/tmp/pip-t2F6FE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:01:40 INFO - Running setup.py (path:/tmp/pip-eyjCC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:01:40 INFO - Running setup.py (path:/tmp/pip-MbbIYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:01:40 INFO - Running setup.py (path:/tmp/pip-opYEhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:40 INFO - Running setup.py (path:/tmp/pip-5ZgtQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:01:40 INFO - Running setup.py (path:/tmp/pip-xcCfgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:01:40 INFO - Running setup.py (path:/tmp/pip-Dqb2Po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:01:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:01:40 INFO - Running setup.py install for manifestparser 05:01:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:01:40 INFO - Running setup.py install for mozcrash 05:01:40 INFO - Running setup.py install for mozdebug 05:01:41 INFO - Running setup.py install for mozdevice 05:01:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:01:41 INFO - Running setup.py install for mozfile 05:01:41 INFO - Running setup.py install for mozhttpd 05:01:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:01:41 INFO - Running setup.py install for mozinfo 05:01:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:01:41 INFO - Running setup.py install for mozInstall 05:01:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:01:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:01:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:01:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:01:41 INFO - Running setup.py install for mozleak 05:01:41 INFO - Running setup.py install for mozlog 05:01:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:01:41 INFO - Running setup.py install for moznetwork 05:01:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:01:41 INFO - Running setup.py install for mozprocess 05:01:41 INFO - Running setup.py install for mozprofile 05:01:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:01:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:01:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:01:42 INFO - Running setup.py install for mozrunner 05:01:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:01:42 INFO - Running setup.py install for mozscreenshot 05:01:42 INFO - Running setup.py install for moztest 05:01:42 INFO - Running setup.py install for mozversion 05:01:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:01:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:01:42 INFO - Cleaning up... 05:01:42 INFO - Return code: 0 05:01:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:01:42 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')]} 05:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:42 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')]} 05:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:42 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 0x1f501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2054a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f7ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f50b0>, '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 0x21f5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f6440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', '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 05:01:42 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 05:01:42 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 05:01:42 INFO - Using env: {'DISPLAY': ':0', 05:01:42 INFO - 'HOME': '/home/cltbld', 05:01:42 INFO - 'LANG': 'en_US.UTF-8', 05:01:42 INFO - 'LANGUAGE': 'en_US:en', 05:01:42 INFO - 'LOGNAME': 'cltbld', 05:01:42 INFO - 'MAIL': '/var/mail/cltbld', 05:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:42 INFO - 'MOZ_NO_REMOTE': '1', 05:01:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:42 INFO - 'NO_EM_RESTART': '1', 05:01:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:42 INFO - 'PWD': '/builds/slave/test', 05:01:42 INFO - 'SHELL': '/bin/bash', 05:01:42 INFO - 'SHLVL': '1', 05:01:42 INFO - 'TERM': 'linux', 05:01:42 INFO - 'TMOUT': '86400', 05:01:42 INFO - 'USER': 'cltbld', 05:01:42 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', 05:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:42 INFO - '_': '/tools/buildbot/bin/python'} 05:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:01:42 INFO - Running setup.py (path:/tmp/pip-1bdEqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:01:42 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)) 05:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:01:42 INFO - Running setup.py (path:/tmp/pip-nt6rgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:01:42 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)) 05:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:01:42 INFO - Running setup.py (path:/tmp/pip-VYocCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:01:42 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)) 05:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:01:42 INFO - Running setup.py (path:/tmp/pip-LxxoCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:01:43 INFO - Running setup.py (path:/tmp/pip-Ft1Sbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:01:43 INFO - Running setup.py (path:/tmp/pip-u9zWJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:01:43 INFO - Running setup.py (path:/tmp/pip-hlDNNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:01:43 INFO - Running setup.py (path:/tmp/pip-up3nM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:01:43 INFO - Running setup.py (path:/tmp/pip-wASRVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:01:43 INFO - Running setup.py (path:/tmp/pip-oszq14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:01:43 INFO - Running setup.py (path:/tmp/pip-CiLaVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:01:43 INFO - Running setup.py (path:/tmp/pip-pUOqd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:01:43 INFO - Running setup.py (path:/tmp/pip-CodMJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:01:43 INFO - Running setup.py (path:/tmp/pip-Zpt81z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:43 INFO - Running setup.py (path:/tmp/pip-9F1OHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:01:43 INFO - Running setup.py (path:/tmp/pip-VbBjDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:01:43 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)) 05:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:01:43 INFO - Running setup.py (path:/tmp/pip-eq0i1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:01:43 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)) 05:01:43 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)) 05:01:43 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)) 05:01:43 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)) 05:01:43 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)) 05:01:43 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)) 05:01:43 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)) 05:01:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:01:43 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 05:01:43 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 05:01:43 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 05:01:43 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 05:01:45 INFO - Downloading blessings-1.6.tar.gz 05:01:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:01:45 INFO - Installing collected packages: blessings 05:01:45 INFO - Running setup.py install for blessings 05:01:45 INFO - Successfully installed blessings 05:01:45 INFO - Cleaning up... 05:01:45 INFO - Return code: 0 05:01:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:01:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:01:45 INFO - Reading from file tmpfile_stdout 05:01:45 INFO - Current package versions: 05:01:45 INFO - argparse == 1.2.1 05:01:45 INFO - blessings == 1.6 05:01:45 INFO - blobuploader == 1.2.4 05:01:45 INFO - docopt == 0.6.1 05:01:45 INFO - functools32 == 3.2.3-2 05:01:45 INFO - jsonschema == 2.5.1 05:01:45 INFO - manifestparser == 1.1 05:01:45 INFO - mozInstall == 1.13 05:01:45 INFO - mozcrash == 1.0 05:01:45 INFO - mozdebug == 0.1 05:01:45 INFO - mozdevice == 0.51 05:01:45 INFO - mozfile == 1.2 05:01:45 INFO - mozhttpd == 0.7 05:01:45 INFO - mozinfo == 0.10 05:01:45 INFO - mozleak == 0.1 05:01:45 INFO - mozlog == 3.5 05:01:45 INFO - moznetwork == 0.27 05:01:45 INFO - mozprocess == 0.25 05:01:45 INFO - mozprofile == 0.28 05:01:45 INFO - mozrunner == 6.13 05:01:45 INFO - mozscreenshot == 0.1 05:01:45 INFO - mozsystemmonitor == 0.3 05:01:45 INFO - moztest == 0.8 05:01:45 INFO - mozversion == 1.4 05:01:45 INFO - psutil == 3.1.1 05:01:45 INFO - requests == 1.2.3 05:01:45 INFO - wsgiref == 0.1.2 05:01:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:45 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 0x1f501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2054a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f7ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f50b0>, '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 0x21f5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f6440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', '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 05:01:45 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 05:01:45 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 05:01:45 INFO - Using env: {'DISPLAY': ':0', 05:01:45 INFO - 'HOME': '/home/cltbld', 05:01:45 INFO - 'LANG': 'en_US.UTF-8', 05:01:45 INFO - 'LANGUAGE': 'en_US:en', 05:01:45 INFO - 'LOGNAME': 'cltbld', 05:01:45 INFO - 'MAIL': '/var/mail/cltbld', 05:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:45 INFO - 'MOZ_NO_REMOTE': '1', 05:01:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:45 INFO - 'NO_EM_RESTART': '1', 05:01:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:45 INFO - 'PWD': '/builds/slave/test', 05:01:45 INFO - 'SHELL': '/bin/bash', 05:01:45 INFO - 'SHLVL': '1', 05:01:45 INFO - 'TERM': 'linux', 05:01:45 INFO - 'TMOUT': '86400', 05:01:45 INFO - 'USER': 'cltbld', 05:01:45 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', 05:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:45 INFO - '_': '/tools/buildbot/bin/python'} 05:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:45 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)) 05:01:45 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)) 05:01:45 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)) 05:01:45 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)) 05:01:45 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)) 05:01:45 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)) 05:01:45 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)) 05:01:45 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)) 05:01:45 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)) 05:01:45 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:01:45 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 05:01:45 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 05:01:45 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 05:01:45 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 05:01:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:01:46 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)) 05:01:46 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)) 05:01:46 INFO - Installing collected packages: simplejson 05:01:46 INFO - Running setup.py install for simplejson 05:01:46 INFO - building 'simplejson._speedups' extension 05:01:46 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 05:01:47 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 05:01:47 INFO - Successfully installed simplejson 05:01:47 INFO - Cleaning up... 05:01:47 INFO - Return code: 0 05:01:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:01:47 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')]} 05:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:47 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')]} 05:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:47 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 0x1f501f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2054a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f7ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f50b0>, '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 0x21f5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f6440>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', '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 05:01:47 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 05:01:47 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 05:01:47 INFO - Using env: {'DISPLAY': ':0', 05:01:47 INFO - 'HOME': '/home/cltbld', 05:01:47 INFO - 'LANG': 'en_US.UTF-8', 05:01:47 INFO - 'LANGUAGE': 'en_US:en', 05:01:47 INFO - 'LOGNAME': 'cltbld', 05:01:47 INFO - 'MAIL': '/var/mail/cltbld', 05:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:47 INFO - 'MOZ_NO_REMOTE': '1', 05:01:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:47 INFO - 'NO_EM_RESTART': '1', 05:01:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:47 INFO - 'PWD': '/builds/slave/test', 05:01:47 INFO - 'SHELL': '/bin/bash', 05:01:47 INFO - 'SHLVL': '1', 05:01:47 INFO - 'TERM': 'linux', 05:01:47 INFO - 'TMOUT': '86400', 05:01:47 INFO - 'USER': 'cltbld', 05:01:47 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', 05:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:47 INFO - '_': '/tools/buildbot/bin/python'} 05:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:01:48 INFO - Cleaning up... 05:01:48 INFO - Return code: 0 05:01:48 INFO - Running post-action listener: _resource_record_post_action 05:01:48 INFO - Running post-action listener: _start_resource_monitoring 05:01:48 INFO - Starting resource monitoring. 05:01:48 INFO - [mozharness: 2017-08-17 12:01:48.231710Z] Finished create-virtualenv step (success) 05:01:48 INFO - [mozharness: 2017-08-17 12:01:48.232228Z] Running install step. 05:01:48 INFO - Running pre-action listener: _resource_record_pre_action 05:01:48 INFO - Running main action method: install 05:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:01:48 INFO - Reading from file tmpfile_stdout 05:01:48 INFO - Detecting whether we're running mozinstall >=1.0... 05:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:01:48 INFO - Reading from file tmpfile_stdout 05:01:48 INFO - Output received: 05:01:48 INFO - Usage: mozinstall [options] installer 05:01:48 INFO - Options: 05:01:48 INFO - -h, --help show this help message and exit 05:01:48 INFO - -d DEST, --destination=DEST 05:01:48 INFO - Directory to install application into. [default: 05:01:48 INFO - "/builds/slave/test"] 05:01:48 INFO - --app=APP Application being installed. [default: firefox] 05:01:48 INFO - mkdir: /builds/slave/test/build/application 05:01:48 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'] 05:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:02:06 INFO - Reading from file tmpfile_stdout 05:02:06 INFO - Output received: 05:02:06 INFO - /builds/slave/test/build/application/firefox/firefox 05:02:06 INFO - Running post-action listener: _resource_record_post_action 05:02:06 INFO - [mozharness: 2017-08-17 12:02:06.592237Z] Finished install step (success) 05:02:06 INFO - [mozharness: 2017-08-17 12:02:06.592492Z] Running setup-mitmproxy step. 05:02:06 INFO - Running pre-action listener: _resource_record_pre_action 05:02:06 INFO - Running main action method: setup_mitmproxy 05:02:06 INFO - Skipping: mitmproxy is not required 05:02:06 INFO - Running post-action listener: _resource_record_post_action 05:02:06 INFO - [mozharness: 2017-08-17 12:02:06.593703Z] Finished setup-mitmproxy step (success) 05:02:06 INFO - [mozharness: 2017-08-17 12:02:06.593935Z] Running run-tests step. 05:02:06 INFO - Running pre-action listener: _resource_record_pre_action 05:02:06 INFO - Running pre-action listener: _set_gcov_prefix 05:02:06 INFO - Running main action method: run_tests 05:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:02:06 INFO - Python 2.7.3 05:02:06 INFO - Return code: 0 05:02:06 INFO - grabbing minidump binary from tooltool 05:02: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')]} 05:02:06 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 0x21f50b0>, '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 0x21f5fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f6440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:02:06 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 05:02:06 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 05:02:06 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 05:02:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:02:06 INFO - Return code: 0 05:02:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:02:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:02:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:02:06 INFO - ENV: RUST_BACKTRACE is now full 05:02:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:02:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:02:06 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/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:02:06 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/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-049 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:02:06 INFO - Using env: {'DISPLAY': ':0', 05:02:06 INFO - 'HOME': '/home/cltbld', 05:02:06 INFO - 'LANG': 'en_US.UTF-8', 05:02:06 INFO - 'LANGUAGE': 'en_US:en', 05:02:06 INFO - 'LOGNAME': 'cltbld', 05:02:06 INFO - 'MAIL': '/var/mail/cltbld', 05:02:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:02:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:06 INFO - 'MOZ_NO_REMOTE': '1', 05:02:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:02:06 INFO - 'NO_EM_RESTART': '1', 05:02:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:06 INFO - 'PWD': '/builds/slave/test', 05:02:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:02:06 INFO - 'RUST_BACKTRACE': 'full', 05:02:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:02:06 INFO - 'SHELL': '/bin/bash', 05:02:06 INFO - 'SHLVL': '1', 05:02:06 INFO - 'STYLO_FORCE_ENABLED': '1', 05:02:06 INFO - 'TERM': 'linux', 05:02:06 INFO - 'TMOUT': '86400', 05:02:06 INFO - 'USER': 'cltbld', 05:02:06 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516', 05:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:06 INFO - '_': '/tools/buildbot/bin/python'} 05:02:06 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/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:02:06 INFO - mozversion application_buildid: 20170817110243 05:02:06 INFO - mozversion application_changeset: 04bee69b3274bd8d5cf52d54a0a5cc14dbe8693a 05:02:06 INFO - mozversion application_display_name: Nightly 05:02:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:02:06 INFO - mozversion application_name: Firefox 05:02:06 INFO - mozversion application_remotingname: firefox 05:02:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:02:06 INFO - mozversion application_vendor: Mozilla 05:02:06 INFO - mozversion application_version: 57.0a1 05:02:06 INFO - mozversion platform_buildid: 20170817110243 05:02:06 INFO - mozversion platform_changeset: 04bee69b3274bd8d5cf52d54a0a5cc14dbe8693a 05:02:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:02:06 INFO - mozversion platform_version: 57.0a1 05:02:06 INFO - using testdate: 1502971326 05:02:06 INFO - actual date: 1502971326 05:02:06 INFO - starting webserver on 'localhost:57462' 05:02:06 INFO - SUITE-START | Running 2 tests 05:02:06 INFO - TEST-START | dromaeo_css 05:02:06 INFO - Initialising browser for dromaeo_css test... 05:02:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57462/getInfo.html -profile /tmp/tmpGTOBsL/profile 05:02:06 INFO - TEST-INFO | started process 22179 (/builds/slave/test/build/application/firefox/firefox http://localhost:57462/getInfo.html) 05:02:12 INFO - TEST-INFO | 22179: exit 0 05:02:13 INFO - Browser initialized. 05:02:13 INFO - Running cycle 1/1 for dromaeo_css test... 05:02:13 INFO - TEST-INFO | started process 22370 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGTOBsL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:02:14 INFO - PID 22370 | 05:02:14 INFO - PID 22370 | (/builds/slave/test/build/application/firefox/firefox:22424): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:02:14 INFO - PID 22370 | 05:02:24 INFO - PID 22370 | RSS: Main: 166359040 05:02:24 INFO - PID 22370 | 05:05:23 INFO - PID 22370 | Cycle 1(1): loaded http://localhost:57462/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57462/tests/dromaeo/cssquery-ext.html) 05:05:24 INFO - PID 22370 | RSS: Main: 171855872 05:05:24 INFO - PID 22370 | 05:08:26 INFO - PID 22370 | Cycle 1(1): loaded http://localhost:57462/tests/dromaeo/cssquery-ext.html (next: http://localhost:57462/tests/dromaeo/cssquery-jquery.html) 05:08:27 INFO - PID 22370 | RSS: Main: 171499520 05:08:27 INFO - PID 22370 | 05:11:29 INFO - PID 22370 | Cycle 1(1): loaded http://localhost:57462/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57462/tests/dromaeo/cssquery-mootools.html) 05:11:29 INFO - PID 22370 | RSS: Main: 172933120 05:11:29 INFO - PID 22370 | 05:14:31 INFO - PID 22370 | Cycle 1(1): loaded http://localhost:57462/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57462/tests/dromaeo/cssquery-prototype.html) 05:14:32 INFO - PID 22370 | RSS: Main: 171843584 05:14:32 INFO - PID 22370 | 05:17:36 INFO - PID 22370 | Cycle 1(1): loaded http://localhost:57462/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57462/tests/dromaeo/cssquery-yui.html) 05:17:36 INFO - PID 22370 | RSS: Main: 173023232 05:17:36 INFO - PID 22370 | 05:20:38 INFO - PID 22370 | Cycle 1(1): loaded http://localhost:57462/tests/dromaeo/cssquery-yui.html (next: http://localhost:57462/tests/dromaeo/cssquery-dojo.html) 05:20:38 INFO - PID 22370 | RSS: Main: 176119808 05:20:38 INFO - PID 22370 | 05:20:38 INFO - PID 22370 | __start_tp_report 05:20:38 INFO - PID 22370 | _x_x_mozilla_page_load 05:20:38 INFO - PID 22370 | _x_x_mozilla_page_load_details 05:20:38 INFO - PID 22370 | |i|pagename|runs| 05:20:38 INFO - PID 22370 | |0;dojo.html;3154.7003034711693;3164.4145833020907;3198.6801319868014;3214.549963005179;3227.112544050386;5992.49063829575;6140.662263575501;6197.814065578034;6214.906776398358;6299.275583307921;6125.326214116455;6145.078238264263;6170.197874276338;6176.413240742134;6182;5625.831225063254;5628.831135065954;5629.183768353597;5633.126865335879;5650.237217975582;5677.716114194294;5705.6006079574445;5718.1136923776885;5743.626664266831;5763.855903602423;5721.31344238692;5726.026575482158;5733.512651424613;5741.224934633813;5742.511886489653;5485.424030476823;5577.9163312550345;5645.830625081254;5694.373618901917;5720.313562372542;5046.520580544842;5582.553395728372;5610.382857885629;5693.259876216107;5699.515541179005;6088.512918966472;6115.113308570232;6118.449339559461;6163.2604087509335;6174.289956654952;6533.673316334211;6586.176727909012;6607.867842643168;6617.239017512999;6747.898781518281;6636.236832764197;6660.833479163011;6699.095622091004;6738;6747.359000894907;6198.349173336825;6204.286507051656;6215.440610345091;6235.095911092912;6238.87522249557;6761.966190169018;6836.658167091626;6847.794566163023;6855.897161542481;6929.861402771916;6384.074309225136;6493.610383377012;6504.837379065467;6563.770268040691;6643.56816806906;6567;6657.034729964225;6658.167729033871;6660.766873159416;6665.966670166715;6573.934260657332;6669.566478178903;6672.266050734415;6674.599524028574;6674.83312917181;6633.2040155181685;6642.269350371455;6703.162104736908;6725.865482690417;6754.5947243165565;5310.309659744285;5377.7580008899695;5411.540019098342;5463.781448742086;5472.480114389126;4604.953950460453;4667.206574882278;4691.507391723888;4697.718136911796;4727.692699974491;5751.194832723422;5767.884642307215;5799.130130480462;5817.214676018683;5825.417458254141;6534.183227096613;6668.533202675766;6692;6749.898751518633;6759.459243260528;5717.514011309002;5739.655620662774;5747.942520574825;5811.535077193815;5814.505767009767;5880.14737863016;5903.911441328383;5906.202662640574;5936.881262374728;5945.61353512029;6405.903911441332;6416;6429.903551446732;6511.218653761484;6524.869502609827;6474.902876456763;6587.835304117244;6597.934020659732;6619.900701489578;6691.431228345645;6318.9052164216655;6332.398422149889;6334.714937827901;6335.461485773761;6437.291897891322;6471.320511346429;6575.210974683068;6590.604563726191;6590.934090659032;6601.207855057424;6711.060451536887;6760.391564759196;6771.52599318043;6776.45788336922;6794.626295553792;6438.195225596801;6449.903251451326;6507.577007494687;6537.411632953057;6557.180352455943;6232.532560057924;6392.20097487814;6443.67781610927;6548.8035358939305;6568.21181458209;6349.396807303484;6397.808065758035;6398.168238129014;6432.485401167802;6433.388828061326;6511.2512061113575;6532.477401807939;6567.737290508326;6579.407853293226;6628.83427914306;6346.746130154742;6397.776077837253;6415.294317624965;6436.9034464484;6439.388258115471 05:20:38 INFO - PID 22370 | |1;ext.html;845.1970627903492;862.3446180902514;1149.8562679665042;1166.842476265704;1172.26147527058;37604.811975940116;41327.58672413279;41505;41546.792266038705;41551.58448415515;40966.38550421746;40980.59019409805;41002.38496422556;41137.58862411375;41164.176716465656;7409.296116868916;7544.96227518863;7646.541207527538;7685.769426917173;7687.692492300302;7471.663775130132;7588.354989825872;7588.468807183499;7589.43079269057;7593.658285377143;7568.318851303409;7578.848423031536;7601.923980760204;7610.809729756745;7612.657430415645;7527.322540971311;7583.27958843909;7586.58273794941;7588.392928565701;7599.392048636096;7491.737789177353;7558.584277864716;7564.394848412114;7573.242675732383;7581.658825352873;38069;38086.619133808585;38087.80956095202;38264.234715305814;38316.80841595802;3486.94769578454;3536.8938931832085;3552.0764601203873;3579.069441945113;3594.802285874276;31006.534901976487;31010;31198.064058078293;31219.531707024413;31245.53131703026;5007.84976450707;5038.118329292359;5048.545630893201;5052.317937078521;5063.265826455144;187599;189534;189641;190207;190334;40748.79625601853;48606.27090593644;48760.26859597109;48819;48829.5117048825;44377.55622443734;52145.47854521482;52273.47726522762;52652;52763.472365275855;14868.925655371653;14960.401583936738;14979.176145311894;14983.550493485212;14985.400583976736;12012.519499219934;12026.4588093536;12039.699007524881;12051.758964820654;12051.879481205251;14739.189344586151;14798.260086995608;14798.704025919422;14812.62968425798;14864.331105100297;5778.937163303372;5799.420057994251;5856.472917437451;5865.794697185578;5939.851503712355;993.6770549571273;1002.6340385759146;1004.7036124343336;1005.3013155856755;1020.5254556631054;22958.19646312371;28542.429151416854;29336.26659333534;29927.551086733718;30165.547516787265;1058.4178701714027;1071.59815069349;1077.49357801833;1082.355998181081;1092.907102896247;12581.559645412366;15716.371345146295;16092.839071609369;16150.515484535483;16483.505494835175;233901;235118;235498;235610;236149;55573.444265557635;55847.44152558504;55985.44014559884;56053.43946560564;56091;1415.9575212743634;1436.727021865863;1443.4370595467776;1453.7092581483746;1456.4028748213182;27875.860620696767;27939.580906286017;28027.43945121127;28043.859780700965;28629;36708.44937325996;36755.26489470249;36878.8156059218;37127.81436092802;37134.071648209014;203801;203882;205161;205750;206186;33890.15274618154;35711.10722231965;37423.62576374201;37699;37701.24597508089;46146.53853461422;46222;46265;46715.532844672474;46747;33963.66036339704;34160.14599635029;34248.65751342455;34309;34811.477827832095;32825.17937051593;33190.5021424674;33226.667733322356;33604.83197583997;33701.3259734799;36848.63151368452;36984.07539811526;37271.6272837279;37506.437403439515;37559.62440375561;32681.509777353833;33283.167920802174;33573.992780216635;33653.15867103342;33848.49227261642;24361.51276974486;24878.253652390456;25235.74764252334;25397.61903571411;25600.871995639904 05:20:38 INFO - PID 22370 | |2;jquery.html;561.9438056194379;569.4789267819948;570.60913274407;573.9885202295953;597.8624916269258;21936.780632193673;22828.429289267755;22862;22865.08539658416;22947.196848110318;25504.617430738555;26135.085272015483;26199.869000655017;26258.47483050338;26268.343291417725;6283.214598175228;6444.130042444257;6445.83885402864;6446.742130314782;6447.677616119198;6161.661108639023;6327.367263273659;6414.871702565946;6427;6461.0954466374515;6371.171747672819;6379.266384365811;6382.776602818903;6403.679816009204;6411.807645770635;6379.425851673349;6421.101045853561;6439.83900402489;6442.581232219891;6447.774327898548;5990.460858522711;5991.340952495222;5995.820125396245;6010.609310394811;6027.457528822427;28440.573391399146;29259.97590084337;29796.404071918656;30090.097297081124;30168.396632067455;1838.4300866731223;1858.7026075827803;1865.2072869030744;1868.4021113243768;1869;19060.71408928867;19804;19811.90094049535;19812;19826.80173198264;1991.3826713718793;2027.9898600507051;2054.9930534038363;2073.3365323096614;2073.6785798201304;138715;143724;144217;144341;144547;14530.273486325641;14567.344469498898;14648.340824662915;14912.925435372754;15254.771178432333;23145.88427057854;23457.648135277985;23680.526389472114;23749.525009499714;23795.048198071883;9716.75708107303;9926.503674816231;9929.751756206007;9944.751381215527;9953.552090155961;8949.373543851998;9014.459132452073;9059.818803624023;9121.954390228006;9232.307576931757;9219.907800921905;9249.90750092504;9382.155605995493;9505.477198754135;9569;2617.5942728877053;2676.3175390275355;2747.6565429321336;2753.8072334936764;2768.1003673806094;954.2938225713061;962.1965658674944;968.2496065549255;974.1135566634329;980.181548407088;19377.128029238716;19563.315283964992;19708.802911970983;19844;20011.899940500494;1025.2310766924807;1026.5380578739448;1029.8764148302103;1035.7928414317166;1038.7974345002804;10979.560817567368;11004.559817607205;11011.394373309384;11152.721181970515;11170.329780213213;516949;536018;539274;616306;616582;124055.37972310261;128200;129665.35167324104;130969;131425;1204.8433703618473;1274.5347947998914;1279.4306533592649;1281.525835440893;1284.7237843863613;21282.36152915415;21321.78678213198;21421.78578214198;21540;21840.45398865041;57465.71267143638;58020.70989645025;59520.40479595149;61093.08360374687;62830.37169628428;71466;72558.63720681363;72603.63698181475;72615.27384726085;73394.63302683452;14559.41762329495;15095.32071056783;15164.317605707549;15215.847841521443;15239.3904243829;23585;23763.40591485227;24006.75993240045;24012.27963161108;24469.38826529351;18416.723749144043;18427.723584146515;18512.907435462737;18585.814141858948;18798.436046918614;16216.67566648684;16246.37536246366;16257.837421626105;16965.745513817055;17156.485305440856;17628.471145865646;18369.357072502396;18601.627967440847;18709.719354209425;18781.34265300708;14943.925280373529;14949.476768313058;15003.474878379204;15032.92483537619;15134.318955647243;18262.178201980678;19311;19397.4180774577;19405.32081377145;19423 05:20:38 INFO - PID 22370 | |3;mootools.html;1402.4951017633653;1453.1208618785633;1461.0503172937597;1473.609493484227;1475.0412232049164;14034.298285085755;14125;14595.708085838276;14666.63333416665;14678.486252981134;15128.54614361571;15366.462173823918;15366.923165384158;15367.69264614707;15373.23133843309;2649.629051932736;2700.891964321425;2701.4326991331745;2701.91894243173;2707.323169207698;2646.735326467357;2727.4545090981783;2730.8224965377185;2731.2762118038636;2732.098407525522;2645.0477827981913;2648.4438267963656;2668.9466210675773;2672.2384120525653;2673.79946504012;2611.7127116017223;2625.789936805051;2668.6664166979126;2670.7329267073333;2718.660167479075;2533.721290658026;2546.057958555328;2549.4391233928504;2553.540362734707;2574.897004119833;13974.860251397458;14135.929320353333;14161.78757318641;14176;14197.432102715879;7262.927370726278;7519.661615227328;7544.660490277951;7559.735409260649;7570.16728159902;4884.218525036013;4901.975490122562;5074.340335756365;5119.667221630582;5148.124818780816;1888.2069530796962;1892.0539730134933;1893.5550145715745;1916.4729699332656;1925.8748181368305;21650.566988660295;23428;23995.640065398857;23999.88000060006;24002.639960400607;6761.797146085527;6819.352161544645;6820.181578210547;6930.203026651899;6988.825279367955;18769.24923003092;18877.056147192587;18883.433496995112;18898.811011889706;18915.337963171223;7742.225777422213;7990.800229994296;8000;8002;8013.318867896178;7005.7547985821275;7019.89470157948;7174.1032370953635;7190.9280907191305;7194.064771579766;5373.167159090347;5448;5502.5597952163735;5503.697296648643;5523;1640.6882692288666;1655.96688066238;1664.508969853896;1677.8825482216128;1692.0862734123682;887.1084560017141;914.085914085914;915.2037727177266;926.578406824893;932.296116432103;7437.962810185914;7459.962700186465;7482.326590606763;7525.4355923305975;7536.359409450149;711.3739908880152;714.5926821711679;717.5730440387961;726.3390314813495;728.0789800901757;4575.58819706228;4577.404937358122;4713.74074425903;4755.928661070017;4803;19274.421767347;19671.803281967284;19767.604647906963;19824.801751982584;19972.50068748264;19264.8073519263;19561.902190488956;19695.803041969684;19776.90111549433;19853.106610202285;9103.408278461879;9503.334766566411;10920.399378034126;10979.890201098047;11073.114150867912;8868.778280543038;8962.596683149144;8982.775430614287;9043.547822608974;9100.499472529074;16001;16449.917750411172;16747.162641867613;17033.31866725317;17470.737938930673;16712.164391780607;16759;17261.91369043147;17351.739723904404;17576.472705818847;11707.590234341757;12066.276023438622;12067.69830754203;12070.336131512851;12106.273623582612;13248.668783280109;13309.267990260629;13454.990875684558;13531.188128711916;13538.390772415069;10808.459577021275;10823.025927666547;10909.34543927367;11013.889861101286;11138.777224455627;10631.94684026575;10641;10785.838212426977;10925.836112458479;10926.453677316262;9329;9416.482093484925;9420.670276540288;9455;9479.573419196015;7261.3101755333155;7313;7363.263673632594;7399.519031262951;7404.518706284074;7493.4379921505015;7547.358474529507;7658.310752032344;7685.730999414994;7701 05:20:38 INFO - PID 22370 | |4;prototype.html;501.3432403551347;503.30730591336334;521.7208793295588;522.7307936412745;524.7795925711201;6053.697315134242;6086.30007549132;6105.229483449805;6160.260768707756;6173.8765224695535;5741.913871291924;5985.34161242263;6017.939820601792;6039.18471006414;6080.300765411979;4874.53691899269;4914.582260507852;4938.259261110844;4946.802127914879;4971.080350135218;4864.489228630996;4922.630802689795;4943.788771750926;4973.825916092937;4993.350864387625;4696.577308042276;4894.437139728923;4900.799304170466;4916.065947469987;4916.434610019858;4888.388951381077;4900;4927.260910863363;4960.280759289883;4980;4432.758827528297;4438.334249862514;4441.444819397575;4444.488883778375;4447.799849006802;5882.323532793698;5978.880422391571;5997.250343707036;6000.579959402844;6006.849828754316;5552.555795536347;5620.578456615729;5653.265075540195;5680.11958146488;5702.515286200678;6323.93676063238;6345.302016778151;6379.170707807955;6384.425401713868;6442.581232219891;4782.473927867932;4787.688800227974;4843.176659967814;4858.44127925286;4860.7326597037145;6668.333166683293;6761.560498567626;6784.321567843176;6812.318768123148;6850;4828.37231159954;4941.985388912761;4951.801927922843;4953.108440480676;4958.1323268428605;4991.750412479361;5049.818614272985;5055.519725626134;5055.8988820223385;5059.671121377172;5583.190437386556;5593.328800543961;5654.773809047592;5686.744096515667;5696.088625819932;6412.61524308543;6430.903536446957;6447.709853056624;6515.381038801306;6535.986892059317;1663.6672665466713;1711.3400001998043;1718.1409295352323;1735.3318972195796;1768.849647779953;1194.8506436695413;1205.8974987126016;1212.4846940050443;1218.208164692939;1240.088534926846;5594.692291923983;5598.076317407582;5649.237352957381;5718.51392631631;5728.914066289009;6406.711697973602;6438.969764837603;6540.8364790880605;6541.738330466728;6627.204735431683;5617.831465056055;5656.341519485835;5667.149927510906;5679.687617181012;5691.430856914359;3462.4113900636953;3487.6512348765427;3509.368313703507;3548.2371290172737;3557.217412169319;6516.869662606816;6530.706118224596;6532.595491969249;6547.574407663486;6575.506836987245;6742.696578653874;6789.830254243585;6800.795976120724;6804.251532331439;6844.452443804584;6410.55126141175;6462.612243265325;6469.644169570719;6503.414692677682;6529.3470652935275;6616.206055273398;6643.667816609247;6670.266270710315;6673.665266946533;6704.631245281556;6460.288023673824;6477.627601368963;6549.4760419165395;6570.934290657224;6603.966980165068;6408.743650253938;6462.676866156579;6473.482121430368;6503.414692677682;6515.924872396191;4949.57928576075;4975.7512124392915;4975.975120124376;4986.626003049858;5000.67495612784;6649.734010639714;6677.43241824431;6686.498512611671;6705.765298214539;6763.560368576027;6339.429451349216;6414.711337989895;6422.42198202164;6426.007937539378;6473.611583305017;6221.564490485623;6441.484681225585;6441.838954026187;6448.709808058555;6454.967725161532;6194.977828658401;6307.842303942438;6340;6425.485961123006;6430;6459.935400645933;6497.935020649733;6500.642464664489;6519.641419721961;6539.901901471387;6314.210723659543;6334.809955701337;6344.429001389897;6356.332585078685;6359.236891573041 05:20:38 INFO - PID 22370 | |5;yui.html;917.3807679816125;924.9398789078709;933.6218831373292;939.267371450269;941.9011003844596;19139.71290430643;20101.798982010176;20141.597168056647;20150.69773953392;22789.886050569723;21751.45621359467;21883.12467501302;22072.8896355518;22178.11287548496;22214.88892555535;987.3384832162468;1058.5554067291716;1067.029003606718;1069.561479793285;1072.4101744040786;974.5906719177926;1043.7547176413536;1048.2871647279846;1048.7430579508032;1058.4125810175349;1089.5206109311878;1099.3788509492151;1102.9889701103007;1105.160078340463;1105.4030823355379;1090.7600327927844;1095.4686976816197;1096.9012788849002;1097.3909480238465;1097.747518070844;807.5154907055778;807.6930766308824;808.8988876390451;808.9919100809034;809.5062012172571;20169;20660.07029683668;20820.895895520425;20905.37283881486;20997.47506312339;21406.785932140792;21597.892010540003;22061.669074963887;22526.88736556323;22653.660195097087;16209.837901620951;16289.755653665205;16419.83580164195;16445.25996330156;16474.09392483413;3512;3514.8242587870504;3515.824208789576;3531.0642679689904;3531.170175008871;129014;129690;130056;132100;132935;17112.74330885038;17172.65654686899;17503.912480437517;17518.47444576639;17539.29842806299;29997;31041.68958310388;31153.844230779156;31256.68743312538;31320.686793132234;7937.166597507178;8030.678772849137;8232.423730338938;8301.169883011651;8773.473591584398;4227.492700875914;4275.700700950966;4308.482982042175;4314.741115533078;4316.503602085738;5021.974890125526;5095.643304968691;5129.769160387791;5164.8192313268855;5207.0887594670785;1014.2291858187831;1049.6850944716707;1054.599252284127;1061.490484567412;1064.8030114428855;671.144291028942;681.2744427185032;696.314130581377;703.5426972467936;712.4620911212002;11704.590339338083;11922.582709605122;12072.336021518726;12265;12558.434870430852;675.2605896543278;688.4595592459895;688.9586624802528;690.4752388184916;690.6650274616807;5809.331926828384;5892.823215303548;6063.787767428207;6117.143599896018;6181;106688.93311066946;111124;111187.44406278079;112009;115533.42233288947;101320.49339753401;102997;103107;106185;107330.46334768276;88411;88599.55700221586;91182;92171;94318;11169.273997190157;12649.430775615303;12806.423710932844;12869.292188929325;13005.284709340698;39756.403653945796;40307.3953890686;41088;41532.1693566133;42375.36436953386;69636.303636965;70349.29650703428;72181;72587;77166;17730.91134544319;18090.728639070687;18381.81618183801;18436;18452.169652365945;22022.33932982013;22246.88876555607;22304.10783568639;22542.21102261413;22557.09771609117;19206.903965480084;19307.8069219306;20368.388948331572;20468.38594842157;20497.59004819925;18308.542286442946;18427.81572184261;19852.70220946658;20479.897600511904;20687.793122068586;18189.727154092434;19883.60232795307;20001;20031.39905802828;20280.391588252376;12843.165194262343;12982.285974271506;13033.328167725784;13089.476420943436;13369.53206637763;15021.324040418425;15534.611634709223;15658.843411565738;15813.130277834829;16029.519114426586 05:20:38 INFO - PID 22370 | __end_tp_report 05:20:38 INFO - PID 22370 | __start_cc_report 05:20:38 INFO - PID 22370 | _x_x_mozilla_cycle_collect,361 05:20:38 INFO - PID 22370 | __end_cc_report 05:20:38 INFO - PID 22370 | __startTimestamp1502972438887__endTimestamp 05:20:38 INFO - PID 22370 | 05:20:38 INFO - PID 22370 | ------- Summary: start ------- 05:20:38 INFO - PID 22370 | Number of tests: 6 05:20:38 INFO - PID 22370 | 05:20:38 INFO - PID 22370 | [#0] dojo.html Cycles:165 Average:6122.91 Median:6384.07 stddev:716.01 (11.2%) stddev-sans-first:679.30 05:20:38 INFO - PID 22370 | Values: 3154.7 3164.4 3198.7 3214.5 3227.1 5992.5 6140.7 6197.8 6214.9 6299.3 6125.3 6145.1 6170.2 6176.4 6182.0 5625.8 5628.8 5629.2 5633.1 5650.2 5677.7 5705.6 5718.1 5743.6 5763.9 5721.3 5726.0 5733.5 5741.2 5742.5 5485.4 5577.9 5645.8 5694.4 5720.3 5046.5 5582.6 5610.4 5693.3 5699.5 6088.5 6115.1 6118.4 6163.3 6174.3 6533.7 6586.2 6607.9 6617.2 6747.9 6636.2 6660.8 6699.1 6738.0 6747.4 6198.3 6204.3 6215.4 6235.1 6238.9 6762.0 6836.7 6847.8 6855.9 6929.9 6384.1 6493.6 6504.8 6563.8 6643.6 6567.0 6657.0 6658.2 6660.8 6666.0 6573.9 6669.6 6672.3 6674.6 6674.8 6633.2 6642.3 6703.2 6725.9 6754.6 5310.3 5377.8 5411.5 5463.8 5472.5 4605.0 4667.2 4691.5 4697.7 4727.7 5751.2 5767.9 5799.1 5817.2 5825.4 6534.2 6668.5 6692.0 6749.9 6759.5 5717.5 5739.7 5747.9 5811.5 5814.5 5880.1 5903.9 5906.2 5936.9 5945.6 6405.9 6416.0 6429.9 6511.2 6524.9 6474.9 6587.8 6597.9 6619.9 6691.4 6318.9 6332.4 6334.7 6335.5 6437.3 6471.3 6575.2 6590.6 6590.9 6601.2 6711.1 6760.4 6771.5 6776.5 6794.6 6438.2 6449.9 6507.6 6537.4 6557.2 6232.5 6392.2 6443.7 6548.8 6568.2 6349.4 6397.8 6398.2 6432.5 6433.4 6511.3 6532.5 6567.7 6579.4 6628.8 6346.7 6397.8 6415.3 6436.9 6439.4 05:20:38 INFO - PID 22370 | 05:20:38 INFO - PID 22370 | [#1] ext.html Cycles:180 Average:38560.72 Median:19631.59 stddev:54542.34 (277.8%) stddev-sans-first:54621.83 05:20:38 INFO - PID 22370 | Values: 845.2 862.3 1149.9 1166.8 1172.3 37604.8 41327.6 41505.0 41546.8 41551.6 40966.4 40980.6 41002.4 41137.6 41164.2 7409.3 7545.0 7646.5 7685.8 7687.7 7471.7 7588.4 7588.5 7589.4 7593.7 7568.3 7578.8 7601.9 7610.8 7612.7 7527.3 7583.3 7586.6 7588.4 7599.4 7491.7 7558.6 7564.4 7573.2 7581.7 38069.0 38086.6 38087.8 38264.2 38316.8 3486.9 3536.9 3552.1 3579.1 3594.8 31006.5 31010.0 31198.1 31219.5 31245.5 5007.8 5038.1 5048.5 5052.3 5063.3 187599.0 189534.0 189641.0 190207.0 190334.0 40748.8 48606.3 48760.3 48819.0 48829.5 44377.6 52145.5 52273.5 52652.0 52763.5 14868.9 14960.4 14979.2 14983.6 14985.4 12012.5 12026.5 12039.7 12051.8 12051.9 14739.2 14798.3 14798.7 14812.6 14864.3 5778.9 5799.4 5856.5 5865.8 5939.9 993.7 1002.6 1004.7 1005.3 1020.5 22958.2 28542.4 29336.3 29927.6 30165.5 1058.4 1071.6 1077.5 1082.4 1092.9 12581.6 15716.4 16092.8 16150.5 16483.5 233901.0 235118.0 235498.0 235610.0 236149.0 55573.4 55847.4 55985.4 56053.4 56091.0 1416.0 1436.7 1443.4 1453.7 1456.4 27875.9 27939.6 28027.4 28043.9 28629.0 36708.4 36755.3 36878.8 37127.8 37134.1 203801.0 203882.0 205161.0 205750.0 206186.0 33890.2 35711.1 37423.6 37699.0 37701.2 46146.5 46222.0 46265.0 46715.5 46747.0 33963.7 34160.1 34248.7 34309.0 34811.5 32825.2 33190.5 33226.7 33604.8 33701.3 36848.6 36984.1 37271.6 37506.4 37559.6 32681.5 33283.2 33574.0 33653.2 33848.5 24361.5 24878.3 25235.7 25397.6 25600.9 05:20:38 INFO - PID 22370 | 05:20:38 INFO - PID 22370 | [#2] jquery.html Cycles:180 Average:37639.24 Median:23301.77 stddev:94993.42 (407.7%) stddev-sans-first:95219.11 05:20:38 INFO - PID 22370 | Values: 561.9 569.5 570.6 574.0 597.9 21936.8 22828.4 22862.0 22865.1 22947.2 25504.6 26135.1 26199.9 26258.5 26268.3 6283.2 6444.1 6445.8 6446.7 6447.7 6161.7 6327.4 6414.9 6427.0 6461.1 6371.2 6379.3 6382.8 6403.7 6411.8 6379.4 6421.1 6439.8 6442.6 6447.8 5990.5 5991.3 5995.8 6010.6 6027.5 28440.6 29260.0 29796.4 30090.1 30168.4 1838.4 1858.7 1865.2 1868.4 1869.0 19060.7 19804.0 19811.9 19812.0 19826.8 1991.4 2028.0 2055.0 2073.3 2073.7 138715.0 143724.0 144217.0 144341.0 144547.0 14530.3 14567.3 14648.3 14912.9 15254.8 23145.9 23457.6 23680.5 23749.5 23795.0 9716.8 9926.5 9929.8 9944.8 9953.6 8949.4 9014.5 9059.8 9122.0 9232.3 9219.9 9249.9 9382.2 9505.5 9569.0 2617.6 2676.3 2747.7 2753.8 2768.1 954.3 962.2 968.2 974.1 980.2 19377.1 19563.3 19708.8 19844.0 20011.9 1025.2 1026.5 1029.9 1035.8 1038.8 10979.6 11004.6 11011.4 11152.7 11170.3 516949.0 536018.0 539274.0 616306.0 616582.0 124055.4 128200.0 129665.4 130969.0 131425.0 1204.8 1274.5 1279.4 1281.5 1284.7 21282.4 21321.8 21421.8 21540.0 21840.5 57465.7 58020.7 59520.4 61093.1 62830.4 71466.0 72558.6 72603.6 72615.3 73394.6 14559.4 15095.3 15164.3 15215.8 15239.4 23585.0 23763.4 24006.8 24012.3 24469.4 18416.7 18427.7 18512.9 18585.8 18798.4 16216.7 16246.4 16257.8 16965.7 17156.5 17628.5 18369.4 18601.6 18709.7 18781.3 14943.9 14949.5 15003.5 15032.9 15134.3 18262.2 19311.0 19397.4 19405.3 19423.0 05:20:38 INFO - PID 22370 | 05:20:38 INFO - PID 22370 | [#3] mootools.html Cycles:180 Average:8950.57 Median:2645.89 stddev:6153.40 (232.6%) stddev-sans-first:6144.52 05:20:38 INFO - PID 22370 | Values: 1402.5 1453.1 1461.1 1473.6 1475.0 14034.3 14125.0 14595.7 14666.6 14678.5 15128.5 15366.5 15366.9 15367.7 15373.2 2649.6 2700.9 2701.4 2701.9 2707.3 2646.7 2727.5 2730.8 2731.3 2732.1 2645.0 2648.4 2668.9 2672.2 2673.8 2611.7 2625.8 2668.7 2670.7 2718.7 2533.7 2546.1 2549.4 2553.5 2574.9 13974.9 14135.9 14161.8 14176.0 14197.4 7262.9 7519.7 7544.7 7559.7 7570.2 4884.2 4902.0 5074.3 5119.7 5148.1 1888.2 1892.1 1893.6 1916.5 1925.9 21650.6 23428.0 23995.6 23999.9 24002.6 6761.8 6819.4 6820.2 6930.2 6988.8 18769.2 18877.1 18883.4 18898.8 18915.3 7742.2 7990.8 8000.0 8002.0 8013.3 7005.8 7019.9 7174.1 7190.9 7194.1 5373.2 5448.0 5502.6 5503.7 5523.0 1640.7 1656.0 1664.5 1677.9 1692.1 887.1 914.1 915.2 926.6 932.3 7438.0 7460.0 7482.3 7525.4 7536.4 711.4 714.6 717.6 726.3 728.1 4575.6 4577.4 4713.7 4755.9 4803.0 19274.4 19671.8 19767.6 19824.8 19972.5 19264.8 19561.9 19695.8 19776.9 19853.1 9103.4 9503.3 10920.4 10979.9 11073.1 8868.8 8962.6 8982.8 9043.5 9100.5 16001.0 16449.9 16747.2 17033.3 17470.7 16712.2 16759.0 17261.9 17351.7 17576.5 11707.6 12066.3 12067.7 12070.3 12106.3 13248.7 13309.3 13455.0 13531.2 13538.4 10808.5 10823.0 10909.3 11013.9 11138.8 10631.9 10641.0 10785.8 10925.8 10926.5 9329.0 9416.5 9420.7 9455.0 9479.6 7261.3 7313.0 7363.3 7399.5 7404.5 7493.4 7547.4 7658.3 7685.7 7701.0 05:20:38 INFO - PID 22370 | 05:20:38 INFO - PID 22370 | [#4] prototype.html Cycles:180 Average:5439.32 Median:6003.71 stddev:1522.83 (25.4%) stddev-sans-first:1481.31 05:20:38 INFO - PID 22370 | Values: 501.3 503.3 521.7 522.7 524.8 6053.7 6086.3 6105.2 6160.3 6173.9 5741.9 5985.3 6017.9 6039.2 6080.3 4874.5 4914.6 4938.3 4946.8 4971.1 4864.5 4922.6 4943.8 4973.8 4993.4 4696.6 4894.4 4900.8 4916.1 4916.4 4888.4 4900.0 4927.3 4960.3 4980.0 4432.8 4438.3 4441.4 4444.5 4447.8 5882.3 5978.9 5997.3 6000.6 6006.8 5552.6 5620.6 5653.3 5680.1 5702.5 6323.9 6345.3 6379.2 6384.4 6442.6 4782.5 4787.7 4843.2 4858.4 4860.7 6668.3 6761.6 6784.3 6812.3 6850.0 4828.4 4942.0 4951.8 4953.1 4958.1 4991.8 5049.8 5055.5 5055.9 5059.7 5583.2 5593.3 5654.8 5686.7 5696.1 6412.6 6430.9 6447.7 6515.4 6536.0 1663.7 1711.3 1718.1 1735.3 1768.8 1194.9 1205.9 1212.5 1218.2 1240.1 5594.7 5598.1 5649.2 5718.5 5728.9 6406.7 6439.0 6540.8 6541.7 6627.2 5617.8 5656.3 5667.1 5679.7 5691.4 3462.4 3487.7 3509.4 3548.2 3557.2 6516.9 6530.7 6532.6 6547.6 6575.5 6742.7 6789.8 6800.8 6804.3 6844.5 6410.6 6462.6 6469.6 6503.4 6529.3 6616.2 6643.7 6670.3 6673.7 6704.6 6460.3 6477.6 6549.5 6570.9 6604.0 6408.7 6462.7 6473.5 6503.4 6515.9 4949.6 4975.8 4976.0 4986.6 5000.7 6649.7 6677.4 6686.5 6705.8 6763.6 6339.4 6414.7 6422.4 6426.0 6473.6 6221.6 6441.5 6441.8 6448.7 6455.0 6195.0 6307.8 6340.0 6425.5 6430.0 6459.9 6497.9 6500.6 6519.6 6539.9 6314.2 6334.8 6344.4 6356.3 6359.2 05:20:38 INFO - PID 22370 | 05:20:38 INFO - PID 22370 | [#5] yui.html Cycles:180 Average:24700.17 Median:20488.74 stddev:33357.27 (162.8%) stddev-sans-first:33403.05 05:20:38 INFO - PID 22370 | Values: 917.4 924.9 933.6 939.3 941.9 19139.7 20101.8 20141.6 20150.7 22789.9 21751.5 21883.1 22072.9 22178.1 22214.9 987.3 1058.6 1067.0 1069.6 1072.4 974.6 1043.8 1048.3 1048.7 1058.4 1089.5 1099.4 1103.0 1105.2 1105.4 1090.8 1095.5 1096.9 1097.4 1097.7 807.5 807.7 808.9 809.0 809.5 20169.0 20660.1 20820.9 20905.4 20997.5 21406.8 21597.9 22061.7 22526.9 22653.7 16209.8 16289.8 16419.8 16445.3 16474.1 3512.0 3514.8 3515.8 3531.1 3531.2 129014.0 129690.0 130056.0 132100.0 132935.0 17112.7 17172.7 17503.9 17518.5 17539.3 29997.0 31041.7 31153.8 31256.7 31320.7 7937.2 8030.7 8232.4 8301.2 8773.5 4227.5 4275.7 4308.5 4314.7 4316.5 5022.0 5095.6 5129.8 5164.8 5207.1 1014.2 1049.7 1054.6 1061.5 1064.8 671.1 681.3 696.3 703.5 712.5 11704.6 11922.6 12072.3 12265.0 12558.4 675.3 688.5 689.0 690.5 690.7 5809.3 5892.8 6063.8 6117.1 6181.0 106688.9 111124.0 111187.4 112009.0 115533.4 101320.5 102997.0 103107.0 106185.0 107330.5 88411.0 88599.6 91182.0 92171.0 94318.0 11169.3 12649.4 12806.4 12869.3 13005.3 39756.4 40307.4 41088.0 41532.2 42375.4 69636.3 70349.3 72181.0 72587.0 77166.0 17730.9 18090.7 18381.8 18436.0 18452.2 22022.3 22246.9 22304.1 22542.2 22557.1 19206.9 19307.8 20368.4 20468.4 20497.6 18308.5 18427.8 19852.7 20479.9 20687.8 18189.7 19883.6 20001.0 20031.4 20280.4 12843.2 12982.3 13033.3 13089.5 13369.5 15021.3 15534.6 15658.8 15813.1 16029.5 05:20:38 INFO - PID 22370 | -------- Summary: end -------- 05:20:38 INFO - PID 22370 | 05:20:39 INFO - TEST-INFO | 22370: exit 0 05:20:39 INFO - TEST-OK | dromaeo_css | took 1112625ms 05:20:39 INFO - TEST-START | kraken 05:20:39 INFO - Initialising browser for kraken test... 05:20:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57462/getInfo.html -profile /tmp/tmpX71KtY/profile 05:20:39 INFO - TEST-INFO | started process 22749 (/builds/slave/test/build/application/firefox/firefox http://localhost:57462/getInfo.html) 05:20:45 INFO - TEST-INFO | 22749: exit 0 05:20:45 INFO - Browser initialized. 05:20:45 INFO - Running cycle 1/1 for kraken test... 05:20:45 INFO - TEST-INFO | started process 22943 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpX71KtY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:46 INFO - PID 22943 | 05:20:46 INFO - PID 22943 | (/builds/slave/test/build/application/firefox/firefox:22997): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:20:46 INFO - PID 22943 | 05:20:56 INFO - PID 22943 | RSS: Main: 166002688 05:20:56 INFO - PID 22943 | 05:20:59 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57462/tests/kraken/driver.html?testName=audio-beat-detection) 05:21:00 INFO - PID 22943 | RSS: Main: 172535808 05:21:00 INFO - PID 22943 | 05:21:03 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57462/tests/kraken/driver.html?testName=audio-dft) 05:21:03 INFO - PID 22943 | RSS: Main: 176537600 05:21:03 INFO - PID 22943 | 05:21:06 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57462/tests/kraken/driver.html?testName=audio-fft) 05:21:07 INFO - PID 22943 | RSS: Main: 170958848 05:21:07 INFO - PID 22943 | 05:21:09 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57462/tests/kraken/driver.html?testName=audio-oscillator) 05:21:10 INFO - PID 22943 | RSS: Main: 171266048 05:21:10 INFO - PID 22943 | 05:21:12 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57462/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:21:13 INFO - PID 22943 | RSS: Main: 171343872 05:21:13 INFO - PID 22943 | 05:21:16 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57462/tests/kraken/driver.html?testName=imaging-darkroom) 05:21:16 INFO - PID 22943 | RSS: Main: 172707840 05:21:16 INFO - PID 22943 | 05:21:20 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57462/tests/kraken/driver.html?testName=imaging-desaturate) 05:21:21 INFO - PID 22943 | RSS: Main: 172261376 05:21:21 INFO - PID 22943 | 05:21:23 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57462/tests/kraken/driver.html?testName=json-parse-financial) 05:21:24 INFO - PID 22943 | RSS: Main: 172650496 05:21:24 INFO - PID 22943 | 05:21:26 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57462/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:21:26 INFO - PID 22943 | RSS: Main: 172654592 05:21:26 INFO - PID 22943 | 05:21:28 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57462/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:21:29 INFO - PID 22943 | RSS: Main: 172707840 05:21:29 INFO - PID 22943 | 05:21:31 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57462/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:21:32 INFO - PID 22943 | RSS: Main: 172498944 05:21:32 INFO - PID 22943 | 05:21:34 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:21:35 INFO - PID 22943 | RSS: Main: 173006848 05:21:35 INFO - PID 22943 | 05:21:38 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:21:39 INFO - PID 22943 | RSS: Main: 173514752 05:21:39 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | Cycle 1(1): loaded http://localhost:57462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57462/tests/kraken/driver.html?testName=ai-astar) 05:21:41 INFO - PID 22943 | RSS: Main: 174850048 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | __start_tp_report 05:21:41 INFO - PID 22943 | _x_x_mozilla_page_load 05:21:41 INFO - PID 22943 | _x_x_mozilla_page_load_details 05:21:41 INFO - PID 22943 | |i|pagename|runs| 05:21:41 INFO - PID 22943 | |0;ai-astar;99;109;101;110;111;109;109;100;106;108 05:21:41 INFO - PID 22943 | |1;audio-beat-detection;152;117;143;187;119;148;155;119;179;121 05:21:41 INFO - PID 22943 | |2;audio-dft;164;163;162;163;168;162;161;163;165;167 05:21:41 INFO - PID 22943 | |3;audio-fft;78;79;75;159;77;75;76;90;103;88 05:21:41 INFO - PID 22943 | |4;audio-oscillator;79;80;142;79;83;85;125;81;80;135 05:21:41 INFO - PID 22943 | |5;imaging-gaussian-blur;114;115;115;114;114;118;114;113;115;114 05:21:41 INFO - PID 22943 | |6;imaging-darkroom;150;152;151;151;151;150;150;151;152;150 05:21:41 INFO - PID 22943 | |7;imaging-desaturate;100;95;94;95;94;95;94;93;94;94 05:21:41 INFO - PID 22943 | |8;json-parse-financial;53;53;58;53;56;61;62;53;57;55 05:21:41 INFO - PID 22943 | |9;json-stringify-tinderbox;61;54;54;54;54;53;55;54;55;59 05:21:41 INFO - PID 22943 | |10;stanford-crypto-aes;77;83;77;84;76;78;77;79;75;81 05:21:41 INFO - PID 22943 | |11;stanford-crypto-ccm;121;125;124;131;118;121;125;123;124;166 05:21:41 INFO - PID 22943 | |12;stanford-crypto-pbkdf2;176;178;173;177;172;174;185;179;172;182 05:21:41 INFO - PID 22943 | |13;stanford-crypto-sha256-iterative;72;67;68;74;72;75;68;68;69;67 05:21:41 INFO - PID 22943 | __end_tp_report 05:21:41 INFO - PID 22943 | __start_cc_report 05:21:41 INFO - PID 22943 | _x_x_mozilla_cycle_collect,973 05:21:41 INFO - PID 22943 | __end_cc_report 05:21:41 INFO - PID 22943 | __startTimestamp1502972501515__endTimestamp 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | ------- Summary: start ------- 05:21:41 INFO - PID 22943 | Number of tests: 14 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#0] ai-astar Cycles:10 Average:106.20 Median:109.00 stddev:4.49 (4.1%) stddev-sans-first:3.94 05:21:41 INFO - PID 22943 | Values: 99.0 109.0 101.0 110.0 111.0 109.0 109.0 100.0 106.0 108.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#1] audio-beat-detection Cycles:10 Average:144.00 Median:150.00 stddev:25.35 (16.9%) stddev-sans-first:26.72 05:21:41 INFO - PID 22943 | Values: 152.0 117.0 143.0 187.0 119.0 148.0 155.0 119.0 179.0 121.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.50 stddev:2.25 (1.4%) stddev-sans-first:2.39 05:21:41 INFO - PID 22943 | Values: 164.0 163.0 162.0 163.0 168.0 162.0 161.0 163.0 165.0 167.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#3] audio-fft Cycles:10 Average:90.00 Median:77.50 stddev:25.85 (33.4%) stddev-sans-first:27.05 05:21:41 INFO - PID 22943 | Values: 78.0 79.0 75.0 159.0 77.0 75.0 76.0 90.0 103.0 88.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:80.00 stddev:25.98 (32.5%) stddev-sans-first:26.74 05:21:41 INFO - PID 22943 | Values: 79.0 80.0 142.0 79.0 83.0 85.0 125.0 81.0 80.0 135.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:114.50 stddev:1.35 (1.2%) stddev-sans-first:1.41 05:21:41 INFO - PID 22943 | Values: 114.0 115.0 115.0 114.0 114.0 118.0 114.0 113.0 115.0 114.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 05:21:41 INFO - PID 22943 | Values: 150.0 152.0 151.0 151.0 151.0 150.0 150.0 151.0 152.0 150.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.00 stddev:1.93 (2.1%) stddev-sans-first:0.67 05:21:41 INFO - PID 22943 | Values: 100.0 95.0 94.0 95.0 94.0 95.0 94.0 93.0 94.0 94.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:56.50 stddev:3.38 (6.0%) stddev-sans-first:3.40 05:21:41 INFO - PID 22943 | Values: 53.0 53.0 58.0 53.0 56.0 61.0 62.0 53.0 57.0 55.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#9] json-stringify-tinderbox Cycles:10 Average:55.30 Median:54.50 stddev:2.58 (4.7%) stddev-sans-first:1.73 05:21:41 INFO - PID 22943 | Values: 61.0 54.0 54.0 54.0 54.0 53.0 55.0 54.0 55.0 59.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:78.50 stddev:3.02 (3.8%) stddev-sans-first:3.14 05:21:41 INFO - PID 22943 | Values: 77.0 83.0 77.0 84.0 76.0 78.0 77.0 79.0 75.0 81.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:124.50 stddev:13.85 (11.1%) stddev-sans-first:14.47 05:21:41 INFO - PID 22943 | Values: 121.0 125.0 124.0 131.0 118.0 121.0 125.0 123.0 124.0 166.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:177.50 stddev:4.34 (2.4%) stddev-sans-first:4.59 05:21:41 INFO - PID 22943 | Values: 176.0 178.0 173.0 177.0 172.0 174.0 185.0 179.0 172.0 182.0 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:70.50 stddev:2.98 (4.2%) stddev-sans-first:3.07 05:21:41 INFO - PID 22943 | Values: 72.0 67.0 68.0 74.0 72.0 75.0 68.0 68.0 69.0 67.0 05:21:41 INFO - PID 22943 | -------- Summary: end -------- 05:21:41 INFO - PID 22943 | 05:21:41 INFO - PID 22943 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:21:42 INFO - TEST-INFO | 22943: exit 0 05:21:42 INFO - TEST-OK | kraken | took 62658ms 05:21:42 INFO - SUITE-END | took 1175s 05:21:42 INFO - Completed test suite (00:19:35) 05:21:42 INFO - javascript benchmark 05:21:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8570.478317227562, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3154.7003034711693, 3164.4145833020907, 3198.6801319868014, 3214.549963005179, 3227.112544050386, 5992.49063829575, 6140.662263575501, 6197.814065578034, 6214.906776398358, 6299.275583307921, 6125.326214116455, 6145.078238264263, 6170.197874276338, 6176.413240742134, 6182.0, 5625.831225063254, 5628.831135065954, 5629.183768353597, 5633.126865335879, 5650.237217975582, 5677.716114194294, 5705.6006079574445, 5718.1136923776885, 5743.626664266831, 5763.855903602423, 5721.31344238692, 5726.026575482158, 5733.512651424613, 5741.224934633813, 5742.511886489653, 5485.424030476823, 5577.9163312550345, 5645.830625081254, 5694.373618901917, 5720.313562372542, 5046.520580544842, 5582.553395728372, 5610.382857885629, 5693.259876216107, 5699.515541179005, 6088.512918966472, 6115.113308570232, 6118.449339559461, 6163.2604087509335, 6174.289956654952, 6533.673316334211, 6586.176727909012, 6607.867842643168, 6617.239017512999, 6747.898781518281, 6636.236832764197, 6660.833479163011, 6699.095622091004, 6738.0, 6747.359000894907, 6198.349173336825, 6204.286507051656, 6215.440610345091, 6235.095911092912, 6238.87522249557, 6761.966190169018, 6836.658167091626, 6847.794566163023, 6855.897161542481, 6929.861402771916, 6384.074309225136, 6493.610383377012, 6504.837379065467, 6563.770268040691, 6643.56816806906, 6567.0, 6657.034729964225, 6658.167729033871, 6660.766873159416, 6665.966670166715, 6573.934260657332, 6669.566478178903, 6672.266050734415, 6674.599524028574, 6674.83312917181, 6633.2040155181685, 6642.269350371455, 6703.162104736908, 6725.865482690417, 6754.5947243165565, 5310.309659744285, 5377.7580008899695, 5411.540019098342, 5463.781448742086, 5472.480114389126, 4604.953950460453, 4667.206574882278, 4691.507391723888, 4697.718136911796, 4727.692699974491, 5751.194832723422, 5767.884642307215, 5799.130130480462, 5817.214676018683, 5825.417458254141, 6534.183227096613, 6668.533202675766, 6692.0, 6749.898751518633, 6759.459243260528, 5717.514011309002, 5739.655620662774, 5747.942520574825, 5811.535077193815, 5814.505767009767, 5880.14737863016, 5903.911441328383, 5906.202662640574, 5936.881262374728, 5945.61353512029, 6405.903911441332, 6416.0, 6429.903551446732, 6511.218653761484, 6524.869502609827, 6474.902876456763, 6587.835304117244, 6597.934020659732, 6619.900701489578, 6691.431228345645, 6318.9052164216655, 6332.398422149889, 6334.714937827901, 6335.461485773761, 6437.291897891322, 6471.320511346429, 6575.210974683068, 6590.604563726191, 6590.934090659032, 6601.207855057424, 6711.060451536887, 6760.391564759196, 6771.52599318043, 6776.45788336922, 6794.626295553792, 6438.195225596801, 6449.903251451326, 6507.577007494687, 6537.411632953057, 6557.180352455943, 6232.532560057924, 6392.20097487814, 6443.67781610927, 6548.8035358939305, 6568.21181458209, 6349.396807303484, 6397.808065758035, 6398.168238129014, 6432.485401167802, 6433.388828061326, 6511.2512061113575, 6532.477401807939, 6567.737290508326, 6579.407853293226, 6628.83427914306, 6346.746130154742, 6397.776077837253, 6415.294317624965, 6436.9034464484, 6439.388258115471], "value": 6070.364581158574, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [845.1970627903492, 862.3446180902514, 1149.8562679665042, 1166.842476265704, 1172.26147527058, 37604.811975940116, 41327.58672413279, 41505.0, 41546.792266038705, 41551.58448415515, 40966.38550421746, 40980.59019409805, 41002.38496422556, 41137.58862411375, 41164.176716465656, 7409.296116868916, 7544.96227518863, 7646.541207527538, 7685.769426917173, 7687.692492300302, 7471.663775130132, 7588.354989825872, 7588.468807183499, 7589.43079269057, 7593.658285377143, 7568.318851303409, 7578.848423031536, 7601.923980760204, 7610.809729756745, 7612.657430415645, 7527.322540971311, 7583.27958843909, 7586.58273794941, 7588.392928565701, 7599.392048636096, 7491.737789177353, 7558.584277864716, 7564.394848412114, 7573.242675732383, 7581.658825352873, 38069.0, 38086.619133808585, 38087.80956095202, 38264.234715305814, 38316.80841595802, 3486.94769578454, 3536.8938931832085, 3552.0764601203873, 3579.069441945113, 3594.802285874276, 31006.534901976487, 31010.0, 31198.064058078293, 31219.531707024413, 31245.53131703026, 5007.84976450707, 5038.118329292359, 5048.545630893201, 5052.317937078521, 5063.265826455144, 187599.0, 189534.0, 189641.0, 190207.0, 190334.0, 40748.79625601853, 48606.27090593644, 48760.26859597109, 48819.0, 48829.5117048825, 44377.55622443734, 52145.47854521482, 52273.47726522762, 52652.0, 52763.472365275855, 14868.925655371653, 14960.401583936738, 14979.176145311894, 14983.550493485212, 14985.400583976736, 12012.519499219934, 12026.4588093536, 12039.699007524881, 12051.758964820654, 12051.879481205251, 14739.189344586151, 14798.260086995608, 14798.704025919422, 14812.62968425798, 14864.331105100297, 5778.937163303372, 5799.420057994251, 5856.472917437451, 5865.794697185578, 5939.851503712355, 993.6770549571273, 1002.6340385759146, 1004.7036124343336, 1005.3013155856755, 1020.5254556631054, 22958.19646312371, 28542.429151416854, 29336.26659333534, 29927.551086733718, 30165.547516787265, 1058.4178701714027, 1071.59815069349, 1077.49357801833, 1082.355998181081, 1092.907102896247, 12581.559645412366, 15716.371345146295, 16092.839071609369, 16150.515484535483, 16483.505494835175, 233901.0, 235118.0, 235498.0, 235610.0, 236149.0, 55573.444265557635, 55847.44152558504, 55985.44014559884, 56053.43946560564, 56091.0, 1415.9575212743634, 1436.727021865863, 1443.4370595467776, 1453.7092581483746, 1456.4028748213182, 27875.860620696767, 27939.580906286017, 28027.43945121127, 28043.859780700965, 28629.0, 36708.44937325996, 36755.26489470249, 36878.8156059218, 37127.81436092802, 37134.071648209014, 203801.0, 203882.0, 205161.0, 205750.0, 206186.0, 33890.15274618154, 35711.10722231965, 37423.62576374201, 37699.0, 37701.24597508089, 46146.53853461422, 46222.0, 46265.0, 46715.532844672474, 46747.0, 33963.66036339704, 34160.14599635029, 34248.65751342455, 34309.0, 34811.477827832095, 32825.17937051593, 33190.5021424674, 33226.667733322356, 33604.83197583997, 33701.3259734799, 36848.63151368452, 36984.07539811526, 37271.6272837279, 37506.437403439515, 37559.62440375561, 32681.509777353833, 33283.167920802174, 33573.992780216635, 33653.15867103342, 33848.49227261642, 24361.51276974486, 24878.253652390456, 25235.74764252334, 25397.61903571411, 25600.871995639904], "value": 17641.276469181576, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.9438056194379, 569.4789267819948, 570.60913274407, 573.9885202295953, 597.8624916269258, 21936.780632193673, 22828.429289267755, 22862.0, 22865.08539658416, 22947.196848110318, 25504.617430738555, 26135.085272015483, 26199.869000655017, 26258.47483050338, 26268.343291417725, 6283.214598175228, 6444.130042444257, 6445.83885402864, 6446.742130314782, 6447.677616119198, 6161.661108639023, 6327.367263273659, 6414.871702565946, 6427.0, 6461.0954466374515, 6371.171747672819, 6379.266384365811, 6382.776602818903, 6403.679816009204, 6411.807645770635, 6379.425851673349, 6421.101045853561, 6439.83900402489, 6442.581232219891, 6447.774327898548, 5990.460858522711, 5991.340952495222, 5995.820125396245, 6010.609310394811, 6027.457528822427, 28440.573391399146, 29259.97590084337, 29796.404071918656, 30090.097297081124, 30168.396632067455, 1838.4300866731223, 1858.7026075827803, 1865.2072869030744, 1868.4021113243768, 1869.0, 19060.71408928867, 19804.0, 19811.90094049535, 19812.0, 19826.80173198264, 1991.3826713718793, 2027.9898600507051, 2054.9930534038363, 2073.3365323096614, 2073.6785798201304, 138715.0, 143724.0, 144217.0, 144341.0, 144547.0, 14530.273486325641, 14567.344469498898, 14648.340824662915, 14912.925435372754, 15254.771178432333, 23145.88427057854, 23457.648135277985, 23680.526389472114, 23749.525009499714, 23795.048198071883, 9716.75708107303, 9926.503674816231, 9929.751756206007, 9944.751381215527, 9953.552090155961, 8949.373543851998, 9014.459132452073, 9059.818803624023, 9121.954390228006, 9232.307576931757, 9219.907800921905, 9249.90750092504, 9382.155605995493, 9505.477198754135, 9569.0, 2617.5942728877053, 2676.3175390275355, 2747.6565429321336, 2753.8072334936764, 2768.1003673806094, 954.2938225713061, 962.1965658674944, 968.2496065549255, 974.1135566634329, 980.181548407088, 19377.128029238716, 19563.315283964992, 19708.802911970983, 19844.0, 20011.899940500494, 1025.2310766924807, 1026.5380578739448, 1029.8764148302103, 1035.7928414317166, 1038.7974345002804, 10979.560817567368, 11004.559817607205, 11011.394373309384, 11152.721181970515, 11170.329780213213, 516949.0, 536018.0, 539274.0, 616306.0, 616582.0, 124055.37972310261, 128200.0, 129665.35167324104, 130969.0, 131425.0, 1204.8433703618473, 1274.5347947998914, 1279.4306533592649, 1281.525835440893, 1284.7237843863613, 21282.36152915415, 21321.78678213198, 21421.78578214198, 21540.0, 21840.45398865041, 57465.71267143638, 58020.70989645025, 59520.40479595149, 61093.08360374687, 62830.37169628428, 71466.0, 72558.63720681363, 72603.63698181475, 72615.27384726085, 73394.63302683452, 14559.41762329495, 15095.32071056783, 15164.317605707549, 15215.847841521443, 15239.3904243829, 23585.0, 23763.40591485227, 24006.75993240045, 24012.27963161108, 24469.38826529351, 18416.723749144043, 18427.723584146515, 18512.907435462737, 18585.814141858948, 18798.436046918614, 16216.67566648684, 16246.37536246366, 16257.837421626105, 16965.745513817055, 17156.485305440856, 17628.471145865646, 18369.357072502396, 18601.627967440847, 18709.719354209425, 18781.34265300708, 14943.925280373529, 14949.476768313058, 15003.474878379204, 15032.92483537619, 15134.318955647243, 18262.178201980678, 19311.0, 19397.4180774577, 19405.32081377145, 19423.0], "value": 12097.434570152951, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.4951017633653, 1453.1208618785633, 1461.0503172937597, 1473.609493484227, 1475.0412232049164, 14034.298285085755, 14125.0, 14595.708085838276, 14666.63333416665, 14678.486252981134, 15128.54614361571, 15366.462173823918, 15366.923165384158, 15367.69264614707, 15373.23133843309, 2649.629051932736, 2700.891964321425, 2701.4326991331745, 2701.91894243173, 2707.323169207698, 2646.735326467357, 2727.4545090981783, 2730.8224965377185, 2731.2762118038636, 2732.098407525522, 2645.0477827981913, 2648.4438267963656, 2668.9466210675773, 2672.2384120525653, 2673.79946504012, 2611.7127116017223, 2625.789936805051, 2668.6664166979126, 2670.7329267073333, 2718.660167479075, 2533.721290658026, 2546.057958555328, 2549.4391233928504, 2553.540362734707, 2574.897004119833, 13974.860251397458, 14135.929320353333, 14161.78757318641, 14176.0, 14197.432102715879, 7262.927370726278, 7519.661615227328, 7544.660490277951, 7559.735409260649, 7570.16728159902, 4884.218525036013, 4901.975490122562, 5074.340335756365, 5119.667221630582, 5148.124818780816, 1888.2069530796962, 1892.0539730134933, 1893.5550145715745, 1916.4729699332656, 1925.8748181368305, 21650.566988660295, 23428.0, 23995.640065398857, 23999.88000060006, 24002.639960400607, 6761.797146085527, 6819.352161544645, 6820.181578210547, 6930.203026651899, 6988.825279367955, 18769.24923003092, 18877.056147192587, 18883.433496995112, 18898.811011889706, 18915.337963171223, 7742.225777422213, 7990.800229994296, 8000.0, 8002.0, 8013.318867896178, 7005.7547985821275, 7019.89470157948, 7174.1032370953635, 7190.9280907191305, 7194.064771579766, 5373.167159090347, 5448.0, 5502.5597952163735, 5503.697296648643, 5523.0, 1640.6882692288666, 1655.96688066238, 1664.508969853896, 1677.8825482216128, 1692.0862734123682, 887.1084560017141, 914.085914085914, 915.2037727177266, 926.578406824893, 932.296116432103, 7437.962810185914, 7459.962700186465, 7482.326590606763, 7525.4355923305975, 7536.359409450149, 711.3739908880152, 714.5926821711679, 717.5730440387961, 726.3390314813495, 728.0789800901757, 4575.58819706228, 4577.404937358122, 4713.74074425903, 4755.928661070017, 4803.0, 19274.421767347, 19671.803281967284, 19767.604647906963, 19824.801751982584, 19972.50068748264, 19264.8073519263, 19561.902190488956, 19695.803041969684, 19776.90111549433, 19853.106610202285, 9103.408278461879, 9503.334766566411, 10920.399378034126, 10979.890201098047, 11073.114150867912, 8868.778280543038, 8962.596683149144, 8982.775430614287, 9043.547822608974, 9100.499472529074, 16001.0, 16449.917750411172, 16747.162641867613, 17033.31866725317, 17470.737938930673, 16712.164391780607, 16759.0, 17261.91369043147, 17351.739723904404, 17576.472705818847, 11707.590234341757, 12066.276023438622, 12067.69830754203, 12070.336131512851, 12106.273623582612, 13248.668783280109, 13309.267990260629, 13454.990875684558, 13531.188128711916, 13538.390772415069, 10808.459577021275, 10823.025927666547, 10909.34543927367, 11013.889861101286, 11138.777224455627, 10631.94684026575, 10641.0, 10785.838212426977, 10925.836112458479, 10926.453677316262, 9329.0, 9416.482093484925, 9420.670276540288, 9455.0, 9479.573419196015, 7261.3101755333155, 7313.0, 7363.263673632594, 7399.519031262951, 7404.518706284074, 7493.4379921505015, 7547.358474529507, 7658.310752032344, 7685.730999414994, 7701.0], "value": 6474.985294934708, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.3432403551347, 503.30730591336334, 521.7208793295588, 522.7307936412745, 524.7795925711201, 6053.697315134242, 6086.30007549132, 6105.229483449805, 6160.260768707756, 6173.8765224695535, 5741.913871291924, 5985.34161242263, 6017.939820601792, 6039.18471006414, 6080.300765411979, 4874.53691899269, 4914.582260507852, 4938.259261110844, 4946.802127914879, 4971.080350135218, 4864.489228630996, 4922.630802689795, 4943.788771750926, 4973.825916092937, 4993.350864387625, 4696.577308042276, 4894.437139728923, 4900.799304170466, 4916.065947469987, 4916.434610019858, 4888.388951381077, 4900.0, 4927.260910863363, 4960.280759289883, 4980.0, 4432.758827528297, 4438.334249862514, 4441.444819397575, 4444.488883778375, 4447.799849006802, 5882.323532793698, 5978.880422391571, 5997.250343707036, 6000.579959402844, 6006.849828754316, 5552.555795536347, 5620.578456615729, 5653.265075540195, 5680.11958146488, 5702.515286200678, 6323.93676063238, 6345.302016778151, 6379.170707807955, 6384.425401713868, 6442.581232219891, 4782.473927867932, 4787.688800227974, 4843.176659967814, 4858.44127925286, 4860.7326597037145, 6668.333166683293, 6761.560498567626, 6784.321567843176, 6812.318768123148, 6850.0, 4828.37231159954, 4941.985388912761, 4951.801927922843, 4953.108440480676, 4958.1323268428605, 4991.750412479361, 5049.818614272985, 5055.519725626134, 5055.8988820223385, 5059.671121377172, 5583.190437386556, 5593.328800543961, 5654.773809047592, 5686.744096515667, 5696.088625819932, 6412.61524308543, 6430.903536446957, 6447.709853056624, 6515.381038801306, 6535.986892059317, 1663.6672665466713, 1711.3400001998043, 1718.1409295352323, 1735.3318972195796, 1768.849647779953, 1194.8506436695413, 1205.8974987126016, 1212.4846940050443, 1218.208164692939, 1240.088534926846, 5594.692291923983, 5598.076317407582, 5649.237352957381, 5718.51392631631, 5728.914066289009, 6406.711697973602, 6438.969764837603, 6540.8364790880605, 6541.738330466728, 6627.204735431683, 5617.831465056055, 5656.341519485835, 5667.149927510906, 5679.687617181012, 5691.430856914359, 3462.4113900636953, 3487.6512348765427, 3509.368313703507, 3548.2371290172737, 3557.217412169319, 6516.869662606816, 6530.706118224596, 6532.595491969249, 6547.574407663486, 6575.506836987245, 6742.696578653874, 6789.830254243585, 6800.795976120724, 6804.251532331439, 6844.452443804584, 6410.55126141175, 6462.612243265325, 6469.644169570719, 6503.414692677682, 6529.3470652935275, 6616.206055273398, 6643.667816609247, 6670.266270710315, 6673.665266946533, 6704.631245281556, 6460.288023673824, 6477.627601368963, 6549.4760419165395, 6570.934290657224, 6603.966980165068, 6408.743650253938, 6462.676866156579, 6473.482121430368, 6503.414692677682, 6515.924872396191, 4949.57928576075, 4975.7512124392915, 4975.975120124376, 4986.626003049858, 5000.67495612784, 6649.734010639714, 6677.43241824431, 6686.498512611671, 6705.765298214539, 6763.560368576027, 6339.429451349216, 6414.711337989895, 6422.42198202164, 6426.007937539378, 6473.611583305017, 6221.564490485623, 6441.484681225585, 6441.838954026187, 6448.709808058555, 6454.967725161532, 6194.977828658401, 6307.842303942438, 6340.0, 6425.485961123006, 6430.0, 6459.935400645933, 6497.935020649733, 6500.642464664489, 6519.641419721961, 6539.901901471387, 6314.210723659543, 6334.809955701337, 6344.429001389897, 6356.332585078685, 6359.236891573041], "value": 4992.8919347879455, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [917.3807679816125, 924.9398789078709, 933.6218831373292, 939.267371450269, 941.9011003844596, 19139.71290430643, 20101.798982010176, 20141.597168056647, 20150.69773953392, 22789.886050569723, 21751.45621359467, 21883.12467501302, 22072.8896355518, 22178.11287548496, 22214.88892555535, 987.3384832162468, 1058.5554067291716, 1067.029003606718, 1069.561479793285, 1072.4101744040786, 974.5906719177926, 1043.7547176413536, 1048.2871647279846, 1048.7430579508032, 1058.4125810175349, 1089.5206109311878, 1099.3788509492151, 1102.9889701103007, 1105.160078340463, 1105.4030823355379, 1090.7600327927844, 1095.4686976816197, 1096.9012788849002, 1097.3909480238465, 1097.747518070844, 807.5154907055778, 807.6930766308824, 808.8988876390451, 808.9919100809034, 809.5062012172571, 20169.0, 20660.07029683668, 20820.895895520425, 20905.37283881486, 20997.47506312339, 21406.785932140792, 21597.892010540003, 22061.669074963887, 22526.88736556323, 22653.660195097087, 16209.837901620951, 16289.755653665205, 16419.83580164195, 16445.25996330156, 16474.09392483413, 3512.0, 3514.8242587870504, 3515.824208789576, 3531.0642679689904, 3531.170175008871, 129014.0, 129690.0, 130056.0, 132100.0, 132935.0, 17112.74330885038, 17172.65654686899, 17503.912480437517, 17518.47444576639, 17539.29842806299, 29997.0, 31041.68958310388, 31153.844230779156, 31256.68743312538, 31320.686793132234, 7937.166597507178, 8030.678772849137, 8232.423730338938, 8301.169883011651, 8773.473591584398, 4227.492700875914, 4275.700700950966, 4308.482982042175, 4314.741115533078, 4316.503602085738, 5021.974890125526, 5095.643304968691, 5129.769160387791, 5164.8192313268855, 5207.0887594670785, 1014.2291858187831, 1049.6850944716707, 1054.599252284127, 1061.490484567412, 1064.8030114428855, 671.144291028942, 681.2744427185032, 696.314130581377, 703.5426972467936, 712.4620911212002, 11704.590339338083, 11922.582709605122, 12072.336021518726, 12265.0, 12558.434870430852, 675.2605896543278, 688.4595592459895, 688.9586624802528, 690.4752388184916, 690.6650274616807, 5809.331926828384, 5892.823215303548, 6063.787767428207, 6117.143599896018, 6181.0, 106688.93311066946, 111124.0, 111187.44406278079, 112009.0, 115533.42233288947, 101320.49339753401, 102997.0, 103107.0, 106185.0, 107330.46334768276, 88411.0, 88599.55700221586, 91182.0, 92171.0, 94318.0, 11169.273997190157, 12649.430775615303, 12806.423710932844, 12869.292188929325, 13005.284709340698, 39756.403653945796, 40307.3953890686, 41088.0, 41532.1693566133, 42375.36436953386, 69636.303636965, 70349.29650703428, 72181.0, 72587.0, 77166.0, 17730.91134544319, 18090.728639070687, 18381.81618183801, 18436.0, 18452.169652365945, 22022.33932982013, 22246.88876555607, 22304.10783568639, 22542.21102261413, 22557.09771609117, 19206.903965480084, 19307.8069219306, 20368.388948331572, 20468.38594842157, 20497.59004819925, 18308.542286442946, 18427.81572184261, 19852.70220946658, 20479.897600511904, 20687.793122068586, 18189.727154092434, 19883.60232795307, 20001.0, 20031.39905802828, 20280.391588252376, 12843.165194262343, 12982.285974271506, 13033.328167725784, 13089.476420943436, 13369.53206637763, 15021.324040418425, 15534.611634709223, 15658.843411565738, 15813.130277834829, 16029.519114426586], "value": 9461.791577859478, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1525.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 101.0, 110.0, 111.0, 109.0, 109.0, 100.0, 106.0, 108.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 143.0, 187.0, 119.0, 148.0, 155.0, 119.0, 179.0, 121.0], "value": 144.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 162.0, 163.0, 168.0, 162.0, 161.0, 163.0, 165.0, 167.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 75.0, 159.0, 77.0, 75.0, 76.0, 90.0, 103.0, 88.0], "value": 90.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 142.0, 79.0, 83.0, 85.0, 125.0, 81.0, 80.0, 135.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 114.0, 114.0, 118.0, 114.0, 113.0, 115.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 151.0, 151.0, 151.0, 150.0, 150.0, 151.0, 152.0, 150.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 93.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 58.0, 53.0, 56.0, 61.0, 62.0, 53.0, 57.0, 55.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 54.0, 54.0, 54.0, 54.0, 53.0, 55.0, 54.0, 55.0, 59.0], "value": 55.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 83.0, 77.0, 84.0, 76.0, 78.0, 77.0, 79.0, 75.0, 81.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 125.0, 124.0, 131.0, 118.0, 121.0, 125.0, 123.0, 124.0, 166.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 178.0, 173.0, 177.0, 172.0, 174.0, 185.0, 179.0, 172.0, 182.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 67.0, 68.0, 74.0, 72.0, 75.0, 68.0, 68.0, 69.0, 67.0], "value": 70.0, "unit": "score"}]}]} 05:21:42 INFO - Return code: 0 05:21:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:21:42 INFO - # TBPL SUCCESS # 05:21:42 INFO - Running post-action listener: _package_coverage_data 05:21:42 INFO - Running post-action listener: _resource_record_post_action 05:21:42 INFO - [mozharness: 2017-08-17 12:21:42.775841Z] Finished run-tests step (success) 05:21:42 INFO - Running post-run listener: _resource_record_post_run 05:21:42 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 447791104; Read time: 12; Write time: 419548 05:21:42 INFO - TinderboxPrint: CPU usage
13.1% 05:21:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:21:42 INFO - TinderboxPrint: I/O write bytes / time
447,791,104 / 419,548 05:21:42 INFO - TinderboxPrint: CPU idle
8,265.9 (86.8%) 05:21:42 INFO - TinderboxPrint: CPU user
1,201.3 (12.6%) 05:21:42 INFO - TinderboxPrint: Swap in / out
0 / 0 05:21:42 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 121212928; Read time: 0; Write time: 101004 05:21:42 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:21:42 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 326578176; Read time: 0; Write time: 318544 05:21:43 INFO - Running post-run listener: _upload_blobber_files 05:21:43 INFO - Blob upload gear active. 05:21:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:21:43 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 05:21:43 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'] 05:21:43 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 05:21:43 INFO - (blobuploader) - INFO - Open directory for files ... 05:21:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:21:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:21:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:21:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:44 INFO - (blobuploader) - INFO - Done attempting. 05:21:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:21:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:21:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:21:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:45 INFO - (blobuploader) - INFO - Done attempting. 05:21:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:21:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:21:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:21:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:46 INFO - (blobuploader) - INFO - Done attempting. 05:21:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:21:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:21:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:21:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:53 INFO - (blobuploader) - INFO - Done attempting. 05:21:53 INFO - (blobuploader) - INFO - Iteration through files over. 05:21:53 INFO - Return code: 0 05:21:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:21:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:21:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f15558b86cb1a0864fd0c712df334021468ed7ea9e147500e7b13395529161d1ad7acecce246855b70cecadb0d7acca67af970d1da337f4d472808cdc2192a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c48f0e9786aed0a4735f21c8cb123f32849403e64739f919518e5af785e8c4109d1d998efada60371e7f83006a2e942e38b558ed82a350f3707e44cfe1b1aa6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19d05d34f0094069011794db850fa10adb434bff3464b51b03383f1f5b07856ca522540a441ce3dc30a9feaf34cbe481805dd801e76c91175522deb74ba39ff6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeaa10e7d13870909eccbdf630aaae8e0d89a6afb8630d95f067ad2f5ca4d14a60a55ab487b7ca558ae6bcbce829195fe6e58bcac016ca80600d67416eeab31c"} 05:21:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:21:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:21:53 INFO - Contents: 05:21:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f15558b86cb1a0864fd0c712df334021468ed7ea9e147500e7b13395529161d1ad7acecce246855b70cecadb0d7acca67af970d1da337f4d472808cdc2192a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c48f0e9786aed0a4735f21c8cb123f32849403e64739f919518e5af785e8c4109d1d998efada60371e7f83006a2e942e38b558ed82a350f3707e44cfe1b1aa6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19d05d34f0094069011794db850fa10adb434bff3464b51b03383f1f5b07856ca522540a441ce3dc30a9feaf34cbe481805dd801e76c91175522deb74ba39ff6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeaa10e7d13870909eccbdf630aaae8e0d89a6afb8630d95f067ad2f5ca4d14a60a55ab487b7ca558ae6bcbce829195fe6e58bcac016ca80600d67416eeab31c"} 05:21:53 INFO - Running post-run listener: copy_logs_to_upload_dir 05:21:53 INFO - Copying logs to upload dir... 05:21:53 INFO - mkdir: /builds/slave/test/build/upload/logs 05:21:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.722649 ========= master_lag: -0.28 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-17 05:21:53.355465) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-17 05:21:53.357951) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516 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/6f15558b86cb1a0864fd0c712df334021468ed7ea9e147500e7b13395529161d1ad7acecce246855b70cecadb0d7acca67af970d1da337f4d472808cdc2192a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c48f0e9786aed0a4735f21c8cb123f32849403e64739f919518e5af785e8c4109d1d998efada60371e7f83006a2e942e38b558ed82a350f3707e44cfe1b1aa6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19d05d34f0094069011794db850fa10adb434bff3464b51b03383f1f5b07856ca522540a441ce3dc30a9feaf34cbe481805dd801e76c91175522deb74ba39ff6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeaa10e7d13870909eccbdf630aaae8e0d89a6afb8630d95f067ad2f5ca4d14a60a55ab487b7ca558ae6bcbce829195fe6e58bcac016ca80600d67416eeab31c"} build_url:https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011276 build_url: 'https://queue.taskcluster.net/v1/task/Hf7JIq8cSQeLXgGMAgaziQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f15558b86cb1a0864fd0c712df334021468ed7ea9e147500e7b13395529161d1ad7acecce246855b70cecadb0d7acca67af970d1da337f4d472808cdc2192a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c48f0e9786aed0a4735f21c8cb123f32849403e64739f919518e5af785e8c4109d1d998efada60371e7f83006a2e942e38b558ed82a350f3707e44cfe1b1aa6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19d05d34f0094069011794db850fa10adb434bff3464b51b03383f1f5b07856ca522540a441ce3dc30a9feaf34cbe481805dd801e76c91175522deb74ba39ff6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eeaa10e7d13870909eccbdf630aaae8e0d89a6afb8630d95f067ad2f5ca4d14a60a55ab487b7ca558ae6bcbce829195fe6e58bcac016ca80600d67416eeab31c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-17 05:21:53.384211) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 05:21:53.384492) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1502971256.196829-1517538516 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.27 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 05:21:53.660988) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-17 05:21:53.661286) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-17 05:21:53.661564) ========= ========= Total master_lag: 0.06 =========