builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-088 starttime: 1502193859.32 results: success (0) revision: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:19.320892) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:19.321316) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:19.321590) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:19.365210) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:19.365486) ========= 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=9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-08 05:04:19-- 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% 46.5M=0s 2017-08-08 05:04:19 (46.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147650 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:19.526102) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:19.529411) ========= 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=9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022701 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:19.579030) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-08 05:04:19.582382) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a921bfb8a2cf3db4d9edebe9b35799a3f9d035da --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 a921bfb8a2cf3db4d9edebe9b35799a3f9d035da --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=9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-08 05:04:19,664 truncating revision to first 12 chars 2017-08-08 05:04:19,664 Setting DEBUG logging. 2017-08-08 05:04:19,664 attempt 1/10 2017-08-08 05:04:19,664 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a921bfb8a2cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-08 05:04:20,893 unpacking tar archive at: mozilla-central-a921bfb8a2cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.553726 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-08 05:04:21.148729) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:21.149019) ========= script_repo_revision: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:21.152413) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:21.152728) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 05:04:21.161942) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-08-08 05:04:21.162264) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:04:21 INFO - MultiFileLogger online at 20170808 05:04:21 in /builds/slave/test 05:04:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 05:04:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:04:21 INFO - {'append_to_log': False, 05:04:21 INFO - 'base_work_dir': '/builds/slave/test', 05:04:21 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:04:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:04:21 INFO - 'branch': 'Firefox-Non-PGO', 05:04:21 INFO - 'buildbot_json_path': 'buildprops.json', 05:04:21 INFO - 'code_coverage': False, 05:04:21 INFO - 'config_files': ('talos/linux_config.py',), 05:04:21 INFO - 'default_actions': ('clobber', 05:04:21 INFO - 'read-buildbot-config', 05:04:21 INFO - 'download-and-extract', 05:04:21 INFO - 'populate-webroot', 05:04:21 INFO - 'create-virtualenv', 05:04:21 INFO - 'install', 05:04:21 INFO - 'setup-mitmproxy', 05:04:21 INFO - 'run-tests'), 05:04:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:04:21 INFO - 'disable_ccov_upload': False, 05:04:21 INFO - 'download_minidump_stackwalk': True, 05:04:21 INFO - 'download_symbols': 'ondemand', 05:04:21 INFO - 'enable_stylo': False, 05:04:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:04:21 INFO - 'tooltool.py': '/tools/tooltool.py', 05:04:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:04:21 INFO - '/tools/misc-python/virtualenv.py')}, 05:04:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:04:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:04:21 INFO - 'gecko_profile': False, 05:04:21 INFO - 'gecko_profile_interval': 0, 05:04:21 INFO - 'installer_path': 'installer.exe', 05:04:21 INFO - 'log_level': 'info', 05:04:21 INFO - 'log_name': 'talos', 05:04:21 INFO - 'log_to_console': True, 05:04:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:04:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:04:21 INFO - 'opt_config_files': (), 05:04:21 INFO - 'pip_index': False, 05:04:21 INFO - 'suite': 'dromaeojs-e10s', 05:04:21 INFO - 'system_bits': '32', 05:04:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:04:21 INFO - 'title': 'talos-linux64-ix-088', 05:04:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:04:21 INFO - 'use_talos_json': True, 05:04:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:04:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:04:21 INFO - 'work_dir': 'build'} 05:04:21 INFO - [mozharness: 2017-08-08 12:04:21.350626Z] Running clobber step. 05:04:21 INFO - Running pre-action listener: _resource_record_pre_action 05:04:21 INFO - Running main action method: clobber 05:04:21 INFO - rmtree: /builds/slave/test/build 05:04:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:04:22 INFO - Running post-action listener: _resource_record_post_action 05:04:22 INFO - [mozharness: 2017-08-08 12:04:22.495530Z] Finished clobber step (success) 05:04:22 INFO - [mozharness: 2017-08-08 12:04:22.495632Z] Running read-buildbot-config step. 05:04:22 INFO - Running pre-action listener: _resource_record_pre_action 05:04:22 INFO - Running main action method: read_buildbot_config 05:04:22 INFO - Using buildbot properties: 05:04:22 INFO - { 05:04:22 INFO - "project": "", 05:04:22 INFO - "product": "firefox", 05:04:22 INFO - "who": "cbook@mozilla.com", 05:04:22 INFO - "installer_path": "public/build/target.tar.bz2", 05:04:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:04:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:04:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:04:22 INFO - "stage_platform": "linux64", 05:04:22 INFO - "basedir": "/builds/slave/test", 05:04:22 INFO - "buildnumber": 763, 05:04:22 INFO - "platform": "ubuntu64_hw", 05:04:22 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:04:22 INFO - "slavebuilddir": "test", 05:04:22 INFO - "taskId": "dIkg1kQ5TguIjAacA8pf6w", 05:04:22 INFO - "branch": "mozilla-central", 05:04:22 INFO - "script_repo_revision": "production", 05:04:22 INFO - "revision": "a921bfb8a2cf3db4d9edebe9b35799a3f9d035da", 05:04:22 INFO - "slavename": "talos-linux64-ix-088", 05:04:22 INFO - "repo_path": "mozilla-central" 05:04:22 INFO - } 05:04:22 INFO - Finding installer, test and symbols from parent task. 05:04:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dIkg1kQ5TguIjAacA8pf6w'}, attempt #1 05:04:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dIkg1kQ5TguIjAacA8pf6w'}, attempt #1 05:04:23 INFO - Task dependencies: BYO0e8EGR6OPzlcMpTM4Pw 05:04:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw'}, attempt #1 05:04:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:04:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json 05:04:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip 05:04:24 INFO - Running post-action listener: _resource_record_post_action 05:04:24 INFO - [mozharness: 2017-08-08 12:04:24.096582Z] Finished read-buildbot-config step (success) 05:04:24 INFO - [mozharness: 2017-08-08 12:04:24.096806Z] Running download-and-extract step. 05:04:24 INFO - Running pre-action listener: _resource_record_pre_action 05:04:24 INFO - Running main action method: download_and_extract 05:04:24 INFO - mkdir: /builds/slave/test/build/tests 05:04:24 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:04:24 INFO - trying https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json 05:04:24 INFO - Downloading https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:04:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:04:24 INFO - Downloaded 989 bytes. 05:04:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:04:24 INFO - Using the following test package requirements: 05:04:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:04:24 INFO - u'common': [u'target.common.tests.zip'], 05:04:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:04:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:04:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:04:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:04:24 INFO - u'mozbase': [u'target.common.tests.zip'], 05:04:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:04:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:04:24 INFO - u'web-platform': [u'target.common.tests.zip', 05:04:24 INFO - u'target.web-platform.tests.tar.gz'], 05:04:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:04:24 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:04:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip 05:04:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:04:24 INFO - Fetch https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip into memory 05:04:25 INFO - Content-Length response header: 54284738 05:04:25 INFO - Bytes received: 54284738 05:04:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:04:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip 05:04:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:04:36 INFO - Fetch https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip into memory 05:04:37 INFO - Content-Length response header: 54284738 05:04:37 INFO - Bytes received: 54284738 05:04:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.talos.tests.zip 05:04:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:04:49 INFO - Fetch https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.talos.tests.zip into memory 05:04:50 INFO - Content-Length response header: 14496008 05:04:50 INFO - Bytes received: 14496008 05:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:50 INFO - trying https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:04:50 INFO - Downloading https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:04:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:04:51 INFO - Downloaded 61893500 bytes. 05:04:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:04:51 INFO - mkdir: /builds/slave/test/properties 05:04:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:04:51 INFO - Writing to file /builds/slave/test/properties/build_url 05:04:51 INFO - Contents: 05:04:51 INFO - build_url:https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:04:51 INFO - Running post-action listener: _resource_record_post_action 05:04:51 INFO - Running post-action listener: set_extra_try_arguments 05:04:51 INFO - [mozharness: 2017-08-08 12:04:51.993605Z] Finished download-and-extract step (success) 05:04:51 INFO - [mozharness: 2017-08-08 12:04:51.993720Z] Running populate-webroot step. 05:04:51 INFO - Running pre-action listener: _resource_record_pre_action 05:04:51 INFO - Running main action method: populate_webroot 05:04:51 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:04:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:04:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:04:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:04:51 INFO - 'tests': ['damp', 'tps']}, 05:04:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:04:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:04:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:04:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:04:51 INFO - 'other-e10s': {'tests': ['a11yr', 05:04:51 INFO - 'ts_paint', 05:04:51 INFO - 'tpaint', 05:04:51 INFO - 'sessionrestore', 05:04:51 INFO - 'sessionrestore_many_windows', 05:04:51 INFO - 'sessionrestore_no_auto_restore', 05:04:51 INFO - 'tabpaint']}, 05:04:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:04:51 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:04:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:04:51 INFO - 'tsvgr_opacity', 05:04:51 INFO - 'tart', 05:04:51 INFO - 'tscrollx', 05:04:51 INFO - 'tsvg_static']}, 05:04:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:04:51 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:04:51 INFO - 'talos_options': ['--mitmproxy', 05:04:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:04:51 INFO - '--firstNonBlankPaint'], 05:04:52 INFO - 'tests': ['tp6_google', 05:04:52 INFO - 'tp6_youtube', 05:04:52 INFO - 'tp6_amazon', 05:04:52 INFO - 'tp6_facebook']}, 05:04:52 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:04:52 INFO - 'talos_options': ['--stylo', 05:04:52 INFO - '--mitmproxy', 05:04:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:04:52 INFO - '--firstNonBlankPaint'], 05:04:52 INFO - 'tests': ['tp6_google', 05:04:52 INFO - 'tp6_youtube', 05:04:52 INFO - 'tp6_amazon', 05:04:52 INFO - 'tp6_facebook']}, 05:04:52 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:04:52 INFO - 'talos_options': ['--stylo', 05:04:52 INFO - '--stylo-threads=1', 05:04:52 INFO - '--mitmproxy', 05:04:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:04:52 INFO - '--firstNonBlankPaint'], 05:04:52 INFO - 'tests': ['tp6_google', 05:04:52 INFO - 'tp6_youtube', 05:04:52 INFO - 'tp6_amazon', 05:04:52 INFO - 'tp6_facebook']}, 05:04:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:04:52 INFO - 'talos_options': ['--xperf_path', 05:04:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:52 INFO - 'tests': ['tp5n']}}} 05:04:52 INFO - Running post-action listener: _resource_record_post_action 05:04:52 INFO - [mozharness: 2017-08-08 12:04:52.002214Z] Finished populate-webroot step (success) 05:04:52 INFO - [mozharness: 2017-08-08 12:04:52.002304Z] Running create-virtualenv step. 05:04:52 INFO - Running pre-action listener: _resource_record_pre_action 05:04:52 INFO - Running main action method: create_virtualenv 05:04:52 INFO - Creating virtualenv /builds/slave/test/build/venv 05:04:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:04:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:04:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:04:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:04:52 INFO - Using real prefix '/usr' 05:04:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:04:52 INFO - Installing distribute.............................................................................................................................................................................................done. 05:04:54 INFO - Installing pip.................done. 05:04:54 INFO - Return code: 0 05:04:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:04:54 INFO - Reading from file tmpfile_stdout 05:04:54 INFO - Output received: 05:04:54 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:04:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1723a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c3eb0>, '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 0x18c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c5240>, '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': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', '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:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:04:54 INFO - Using env: {'DISPLAY': ':0', 05:04:54 INFO - 'HOME': '/home/cltbld', 05:04:54 INFO - 'LANG': 'en_US.UTF-8', 05:04:54 INFO - 'LANGUAGE': 'en_US:en', 05:04:54 INFO - 'LOGNAME': 'cltbld', 05:04:54 INFO - 'MAIL': '/var/mail/cltbld', 05:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:54 INFO - 'MOZ_NO_REMOTE': '1', 05:04:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:54 INFO - 'NO_EM_RESTART': '1', 05:04:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:54 INFO - 'PWD': '/builds/slave/test', 05:04:54 INFO - 'SHELL': '/bin/bash', 05:04:54 INFO - 'SHLVL': '1', 05:04:54 INFO - 'TERM': 'linux', 05:04:54 INFO - 'TMOUT': '86400', 05:04:54 INFO - 'USER': 'cltbld', 05:04:54 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', 05:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:54 INFO - '_': '/tools/buildbot/bin/python'} 05:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:04:55 INFO - Cleaning up... 05:04:55 INFO - Return code: 0 05:04:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1723a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c3eb0>, '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 0x18c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c5240>, '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': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', '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:04:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:04:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:04:55 INFO - Using env: {'DISPLAY': ':0', 05:04:55 INFO - 'HOME': '/home/cltbld', 05:04:55 INFO - 'LANG': 'en_US.UTF-8', 05:04:55 INFO - 'LANGUAGE': 'en_US:en', 05:04:55 INFO - 'LOGNAME': 'cltbld', 05:04:55 INFO - 'MAIL': '/var/mail/cltbld', 05:04:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:55 INFO - 'MOZ_NO_REMOTE': '1', 05:04:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:55 INFO - 'NO_EM_RESTART': '1', 05:04:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:55 INFO - 'PWD': '/builds/slave/test', 05:04:55 INFO - 'SHELL': '/bin/bash', 05:04:55 INFO - 'SHLVL': '1', 05:04:55 INFO - 'TERM': 'linux', 05:04:55 INFO - 'TMOUT': '86400', 05:04:55 INFO - 'USER': 'cltbld', 05:04:55 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', 05:04:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:55 INFO - '_': '/tools/buildbot/bin/python'} 05:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:55 INFO - Downloading/unpacking psutil>=3.1.1 05:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:04:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:56 INFO - Installing collected packages: psutil 05:04:56 INFO - Running setup.py install for psutil 05:04:56 INFO - building 'psutil._psutil_linux' extension 05:04:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:04:56 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:04:56 INFO - building 'psutil._psutil_posix' extension 05:04:56 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:04:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:04:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:57 INFO - Successfully installed psutil 05:04:57 INFO - Cleaning up... 05:04:57 INFO - Return code: 0 05:04:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1723a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c3eb0>, '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 0x18c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c5240>, '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': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', '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:04:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:04:57 INFO - Using env: {'DISPLAY': ':0', 05:04:57 INFO - 'HOME': '/home/cltbld', 05:04:57 INFO - 'LANG': 'en_US.UTF-8', 05:04:57 INFO - 'LANGUAGE': 'en_US:en', 05:04:57 INFO - 'LOGNAME': 'cltbld', 05:04:57 INFO - 'MAIL': '/var/mail/cltbld', 05:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:57 INFO - 'MOZ_NO_REMOTE': '1', 05:04:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:57 INFO - 'NO_EM_RESTART': '1', 05:04:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:57 INFO - 'PWD': '/builds/slave/test', 05:04:57 INFO - 'SHELL': '/bin/bash', 05:04:57 INFO - 'SHLVL': '1', 05:04:57 INFO - 'TERM': 'linux', 05:04:57 INFO - 'TMOUT': '86400', 05:04:57 INFO - 'USER': 'cltbld', 05:04:57 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', 05:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:57 INFO - '_': '/tools/buildbot/bin/python'} 05:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:04:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:04:58 INFO - Installing collected packages: mozsystemmonitor 05:04:58 INFO - Running setup.py install for mozsystemmonitor 05:04:58 INFO - Successfully installed mozsystemmonitor 05:04:58 INFO - Cleaning up... 05:04:58 INFO - Return code: 0 05:04:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1723a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c3eb0>, '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 0x18c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c5240>, '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': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', '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:04:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:04:58 INFO - Using env: {'DISPLAY': ':0', 05:04:58 INFO - 'HOME': '/home/cltbld', 05:04:58 INFO - 'LANG': 'en_US.UTF-8', 05:04:58 INFO - 'LANGUAGE': 'en_US:en', 05:04:58 INFO - 'LOGNAME': 'cltbld', 05:04:58 INFO - 'MAIL': '/var/mail/cltbld', 05:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:58 INFO - 'MOZ_NO_REMOTE': '1', 05:04:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:58 INFO - 'NO_EM_RESTART': '1', 05:04:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:58 INFO - 'PWD': '/builds/slave/test', 05:04:58 INFO - 'SHELL': '/bin/bash', 05:04:58 INFO - 'SHLVL': '1', 05:04:58 INFO - 'TERM': 'linux', 05:04:58 INFO - 'TMOUT': '86400', 05:04:58 INFO - 'USER': 'cltbld', 05:04:58 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', 05:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:58 INFO - '_': '/tools/buildbot/bin/python'} 05:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:58 INFO - Downloading/unpacking jsonschema==2.5.1 05:04:58 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:04:58 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:04:58 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:04:58 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:05:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:05:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:00 INFO - Downloading functools32-3.2.3-2.tar.gz 05:05:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:05:00 INFO - warning: no files found matching '*.txt' 05:05:00 INFO - no previously-included directories found matching 'build' 05:05:00 INFO - no previously-included directories found matching 'dist' 05:05:00 INFO - no previously-included directories found matching '.git*' 05:05:00 INFO - Installing collected packages: jsonschema, functools32 05:05:00 INFO - Running setup.py install for functools32 05:05:00 INFO - warning: no files found matching '*.txt' 05:05:00 INFO - no previously-included directories found matching 'build' 05:05:00 INFO - no previously-included directories found matching 'dist' 05:05:00 INFO - no previously-included directories found matching '.git*' 05:05:00 INFO - Successfully installed jsonschema functools32 05:05:00 INFO - Cleaning up... 05:05:00 INFO - Return code: 0 05:05:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:05: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:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05: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:05:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1723a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c3eb0>, '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 0x18c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c5240>, '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': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', '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:05:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:05:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:05:00 INFO - Using env: {'DISPLAY': ':0', 05:05:00 INFO - 'HOME': '/home/cltbld', 05:05:00 INFO - 'LANG': 'en_US.UTF-8', 05:05:00 INFO - 'LANGUAGE': 'en_US:en', 05:05:00 INFO - 'LOGNAME': 'cltbld', 05:05:00 INFO - 'MAIL': '/var/mail/cltbld', 05:05:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:00 INFO - 'MOZ_NO_REMOTE': '1', 05:05:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:00 INFO - 'NO_EM_RESTART': '1', 05:05:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:00 INFO - 'PWD': '/builds/slave/test', 05:05:00 INFO - 'SHELL': '/bin/bash', 05:05:00 INFO - 'SHLVL': '1', 05:05:00 INFO - 'TERM': 'linux', 05:05:00 INFO - 'TMOUT': '86400', 05:05:00 INFO - 'USER': 'cltbld', 05:05:00 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', 05:05:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:00 INFO - '_': '/tools/buildbot/bin/python'} 05:05:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:05:00 INFO - Cleaning up... 05:05:00 INFO - Return code: 0 05:05:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05: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:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05: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:05:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1723a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c3eb0>, '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 0x18c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c5240>, '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': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', '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:05:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:05:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:05:00 INFO - Using env: {'DISPLAY': ':0', 05:05:00 INFO - 'HOME': '/home/cltbld', 05:05:00 INFO - 'LANG': 'en_US.UTF-8', 05:05:00 INFO - 'LANGUAGE': 'en_US:en', 05:05:00 INFO - 'LOGNAME': 'cltbld', 05:05:00 INFO - 'MAIL': '/var/mail/cltbld', 05:05:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:00 INFO - 'MOZ_NO_REMOTE': '1', 05:05:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:00 INFO - 'NO_EM_RESTART': '1', 05:05:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:00 INFO - 'PWD': '/builds/slave/test', 05:05:00 INFO - 'SHELL': '/bin/bash', 05:05:00 INFO - 'SHLVL': '1', 05:05:00 INFO - 'TERM': 'linux', 05:05:00 INFO - 'TMOUT': '86400', 05:05:00 INFO - 'USER': 'cltbld', 05:05:00 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', 05:05:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:00 INFO - '_': '/tools/buildbot/bin/python'} 05:05:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:00 INFO - Downloading/unpacking blobuploader==1.2.4 05:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:01 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:02 INFO - Downloading docopt-0.6.1.tar.gz 05:05:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:05:02 INFO - Installing collected packages: blobuploader, requests, docopt 05:05:02 INFO - Running setup.py install for blobuploader 05:05:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:05:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:05:02 INFO - Running setup.py install for requests 05:05:02 INFO - Running setup.py install for docopt 05:05:03 INFO - Successfully installed blobuploader requests docopt 05:05:03 INFO - Cleaning up... 05:05:03 INFO - Return code: 0 05:05:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1723a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c3eb0>, '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 0x18c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c5240>, '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': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', '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:05:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:05:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:05:03 INFO - Using env: {'DISPLAY': ':0', 05:05:03 INFO - 'HOME': '/home/cltbld', 05:05:03 INFO - 'LANG': 'en_US.UTF-8', 05:05:03 INFO - 'LANGUAGE': 'en_US:en', 05:05:03 INFO - 'LOGNAME': 'cltbld', 05:05:03 INFO - 'MAIL': '/var/mail/cltbld', 05:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:03 INFO - 'MOZ_NO_REMOTE': '1', 05:05:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:03 INFO - 'NO_EM_RESTART': '1', 05:05:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:03 INFO - 'PWD': '/builds/slave/test', 05:05:03 INFO - 'SHELL': '/bin/bash', 05:05:03 INFO - 'SHLVL': '1', 05:05:03 INFO - 'TERM': 'linux', 05:05:03 INFO - 'TMOUT': '86400', 05:05:03 INFO - 'USER': 'cltbld', 05:05:03 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', 05:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:03 INFO - '_': '/tools/buildbot/bin/python'} 05:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:03 INFO - Running setup.py (path:/tmp/pip-CSN0AC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:03 INFO - Running setup.py (path:/tmp/pip-Ps9qiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:03 INFO - Running setup.py (path:/tmp/pip-e4Bknp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:03 INFO - Running setup.py (path:/tmp/pip-luUQE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:03 INFO - Running setup.py (path:/tmp/pip-lLRYya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:03 INFO - Running setup.py (path:/tmp/pip-mnbCmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:03 INFO - Running setup.py (path:/tmp/pip-JyKWhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:03 INFO - Running setup.py (path:/tmp/pip-_3hSb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:03 INFO - Running setup.py (path:/tmp/pip-v9FtDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:03 INFO - Running setup.py (path:/tmp/pip-s49ZAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:04 INFO - Running setup.py (path:/tmp/pip-k9K8WS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:04 INFO - Running setup.py (path:/tmp/pip-PWXZP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:04 INFO - Running setup.py (path:/tmp/pip-oJaQUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:04 INFO - Running setup.py (path:/tmp/pip-Uk72Ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:04 INFO - Running setup.py (path:/tmp/pip-6fq3dE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:04 INFO - Running setup.py (path:/tmp/pip-OYx6H8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:04 INFO - Running setup.py (path:/tmp/pip-TDDwKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:05:04 INFO - Running setup.py install for manifestparser 05:05:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:05:04 INFO - Running setup.py install for mozcrash 05:05:04 INFO - Running setup.py install for mozdebug 05:05:04 INFO - Running setup.py install for mozdevice 05:05:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:05:04 INFO - Running setup.py install for mozfile 05:05:05 INFO - Running setup.py install for mozhttpd 05:05:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:05:05 INFO - Running setup.py install for mozinfo 05:05:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:05 INFO - Running setup.py install for mozInstall 05:05:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:05 INFO - Running setup.py install for mozleak 05:05:05 INFO - Running setup.py install for mozlog 05:05:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:05 INFO - Running setup.py install for moznetwork 05:05:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:05 INFO - Running setup.py install for mozprocess 05:05:05 INFO - Running setup.py install for mozprofile 05:05:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:05 INFO - Running setup.py install for mozrunner 05:05:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:06 INFO - Running setup.py install for mozscreenshot 05:05:06 INFO - Running setup.py install for moztest 05:05:06 INFO - Running setup.py install for mozversion 05:05:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:06 INFO - Cleaning up... 05:05:06 INFO - Return code: 0 05:05:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05: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:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05: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:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1723a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c3eb0>, '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 0x18c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c5240>, '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': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', '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:05:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:05:06 INFO - Using env: {'DISPLAY': ':0', 05:05:06 INFO - 'HOME': '/home/cltbld', 05:05:06 INFO - 'LANG': 'en_US.UTF-8', 05:05:06 INFO - 'LANGUAGE': 'en_US:en', 05:05:06 INFO - 'LOGNAME': 'cltbld', 05:05:06 INFO - 'MAIL': '/var/mail/cltbld', 05:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:06 INFO - 'MOZ_NO_REMOTE': '1', 05:05:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:06 INFO - 'NO_EM_RESTART': '1', 05:05:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:06 INFO - 'PWD': '/builds/slave/test', 05:05:06 INFO - 'SHELL': '/bin/bash', 05:05:06 INFO - 'SHLVL': '1', 05:05:06 INFO - 'TERM': 'linux', 05:05:06 INFO - 'TMOUT': '86400', 05:05:06 INFO - 'USER': 'cltbld', 05:05:06 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', 05:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:06 INFO - '_': '/tools/buildbot/bin/python'} 05:05:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:06 INFO - Running setup.py (path:/tmp/pip-QJa5Ek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:05:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:06 INFO - Running setup.py (path:/tmp/pip-dV6F67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:06 INFO - Running setup.py (path:/tmp/pip-JbQR7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:05:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:06 INFO - Running setup.py (path:/tmp/pip-E89dGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:05:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:06 INFO - Running setup.py (path:/tmp/pip-4QUCQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:06 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:05:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:06 INFO - Running setup.py (path:/tmp/pip-4CQIO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:06 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:05:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:06 INFO - Running setup.py (path:/tmp/pip-4xcPrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:07 INFO - Running setup.py (path:/tmp/pip-Zg4k7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:07 INFO - Running setup.py (path:/tmp/pip-ABXyB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:07 INFO - Running setup.py (path:/tmp/pip-4K8vzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:07 INFO - Running setup.py (path:/tmp/pip-dntde2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:07 INFO - Running setup.py (path:/tmp/pip-rsjXKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:07 INFO - Running setup.py (path:/tmp/pip-jlLNdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:07 INFO - Running setup.py (path:/tmp/pip-4bdqoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:07 INFO - Running setup.py (path:/tmp/pip-iemiol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:07 INFO - Running setup.py (path:/tmp/pip-msDhkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:05:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:07 INFO - Running setup.py (path:/tmp/pip-xwlYKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:05:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:08 INFO - Downloading blessings-1.6.tar.gz 05:05:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:05:08 INFO - Installing collected packages: blessings 05:05:08 INFO - Running setup.py install for blessings 05:05:09 INFO - Successfully installed blessings 05:05:09 INFO - Cleaning up... 05:05:09 INFO - Return code: 0 05:05:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:05:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:09 INFO - Reading from file tmpfile_stdout 05:05:09 INFO - Current package versions: 05:05:09 INFO - argparse == 1.2.1 05:05:09 INFO - blessings == 1.6 05:05:09 INFO - blobuploader == 1.2.4 05:05:09 INFO - docopt == 0.6.1 05:05:09 INFO - functools32 == 3.2.3-2 05:05:09 INFO - jsonschema == 2.5.1 05:05:09 INFO - manifestparser == 1.1 05:05:09 INFO - mozInstall == 1.13 05:05:09 INFO - mozcrash == 1.0 05:05:09 INFO - mozdebug == 0.1 05:05:09 INFO - mozdevice == 0.51 05:05:09 INFO - mozfile == 1.2 05:05:09 INFO - mozhttpd == 0.7 05:05:09 INFO - mozinfo == 0.10 05:05:09 INFO - mozleak == 0.1 05:05:09 INFO - mozlog == 3.5 05:05:09 INFO - moznetwork == 0.27 05:05:09 INFO - mozprocess == 0.25 05:05:09 INFO - mozprofile == 0.28 05:05:09 INFO - mozrunner == 6.13 05:05:09 INFO - mozscreenshot == 0.1 05:05:09 INFO - mozsystemmonitor == 0.3 05:05:09 INFO - moztest == 0.8 05:05:09 INFO - mozversion == 1.4 05:05:09 INFO - psutil == 3.1.1 05:05:09 INFO - requests == 1.2.3 05:05:09 INFO - wsgiref == 0.1.2 05:05:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1723a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c3eb0>, '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 0x18c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c5240>, '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': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', '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:05:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:05:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:05:09 INFO - Using env: {'DISPLAY': ':0', 05:05:09 INFO - 'HOME': '/home/cltbld', 05:05:09 INFO - 'LANG': 'en_US.UTF-8', 05:05:09 INFO - 'LANGUAGE': 'en_US:en', 05:05:09 INFO - 'LOGNAME': 'cltbld', 05:05:09 INFO - 'MAIL': '/var/mail/cltbld', 05:05:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:09 INFO - 'MOZ_NO_REMOTE': '1', 05:05:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:09 INFO - 'NO_EM_RESTART': '1', 05:05:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:09 INFO - 'PWD': '/builds/slave/test', 05:05:09 INFO - 'SHELL': '/bin/bash', 05:05:09 INFO - 'SHLVL': '1', 05:05:09 INFO - 'TERM': 'linux', 05:05:09 INFO - 'TMOUT': '86400', 05:05:09 INFO - 'USER': 'cltbld', 05:05:09 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', 05:05:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:09 INFO - '_': '/tools/buildbot/bin/python'} 05:05:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:05:09 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:05:10 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:05:10 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:05:10 INFO - Installing collected packages: simplejson 05:05:10 INFO - Running setup.py install for simplejson 05:05:10 INFO - building 'simplejson._speedups' extension 05:05:10 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:05:11 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:05:11 INFO - Successfully installed simplejson 05:05:11 INFO - Cleaning up... 05:05:11 INFO - Return code: 0 05:05:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:05:11 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:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:11 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:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:11 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 0x15561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1723a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18c6d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c3eb0>, '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 0x18c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c5240>, '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': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', '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:05:11 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:05:11 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:05:11 INFO - Using env: {'DISPLAY': ':0', 05:05:11 INFO - 'HOME': '/home/cltbld', 05:05:11 INFO - 'LANG': 'en_US.UTF-8', 05:05:11 INFO - 'LANGUAGE': 'en_US:en', 05:05:11 INFO - 'LOGNAME': 'cltbld', 05:05:11 INFO - 'MAIL': '/var/mail/cltbld', 05:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:11 INFO - 'MOZ_NO_REMOTE': '1', 05:05:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:11 INFO - 'NO_EM_RESTART': '1', 05:05:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:11 INFO - 'PWD': '/builds/slave/test', 05:05:11 INFO - 'SHELL': '/bin/bash', 05:05:11 INFO - 'SHLVL': '1', 05:05:11 INFO - 'TERM': 'linux', 05:05:11 INFO - 'TMOUT': '86400', 05:05:11 INFO - 'USER': 'cltbld', 05:05:11 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', 05:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:11 INFO - '_': '/tools/buildbot/bin/python'} 05:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:05:12 INFO - Cleaning up... 05:05:12 INFO - Return code: 0 05:05:12 INFO - Running post-action listener: _resource_record_post_action 05:05:12 INFO - Running post-action listener: _start_resource_monitoring 05:05:12 INFO - Starting resource monitoring. 05:05:12 INFO - [mozharness: 2017-08-08 12:05:12.038792Z] Finished create-virtualenv step (success) 05:05:12 INFO - [mozharness: 2017-08-08 12:05:12.039267Z] Running install step. 05:05:12 INFO - Running pre-action listener: _resource_record_pre_action 05:05:12 INFO - Running main action method: install 05:05:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:12 INFO - Reading from file tmpfile_stdout 05:05:12 INFO - Detecting whether we're running mozinstall >=1.0... 05:05:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:05:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:05:12 INFO - Reading from file tmpfile_stdout 05:05:12 INFO - Output received: 05:05:12 INFO - Usage: mozinstall [options] installer 05:05:12 INFO - Options: 05:05:12 INFO - -h, --help show this help message and exit 05:05:12 INFO - -d DEST, --destination=DEST 05:05:12 INFO - Directory to install application into. [default: 05:05:12 INFO - "/builds/slave/test"] 05:05:12 INFO - --app=APP Application being installed. [default: firefox] 05:05:12 INFO - mkdir: /builds/slave/test/build/application 05:05:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:05:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:05:30 INFO - Reading from file tmpfile_stdout 05:05:30 INFO - Output received: 05:05:30 INFO - /builds/slave/test/build/application/firefox/firefox 05:05:30 INFO - Running post-action listener: _resource_record_post_action 05:05:30 INFO - [mozharness: 2017-08-08 12:05:30.297034Z] Finished install step (success) 05:05:30 INFO - [mozharness: 2017-08-08 12:05:30.297323Z] Running setup-mitmproxy step. 05:05:30 INFO - Running pre-action listener: _resource_record_pre_action 05:05:30 INFO - Running main action method: setup_mitmproxy 05:05:30 INFO - Skipping: mitmproxy is not required 05:05:30 INFO - Running post-action listener: _resource_record_post_action 05:05:30 INFO - [mozharness: 2017-08-08 12:05:30.298538Z] Finished setup-mitmproxy step (success) 05:05:30 INFO - [mozharness: 2017-08-08 12:05:30.298760Z] Running run-tests step. 05:05:30 INFO - Running pre-action listener: _resource_record_pre_action 05:05:30 INFO - Running pre-action listener: _set_gcov_prefix 05:05:30 INFO - Running main action method: run_tests 05:05:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:05:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:05:30 INFO - Python 2.7.3 05:05:30 INFO - Return code: 0 05:05:30 INFO - grabbing minidump binary from tooltool 05:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:30 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c3eb0>, '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 0x18c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18c5240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:05:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:05:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:05:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:05:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:05:30 INFO - Return code: 0 05:05:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:05:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:05:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:05:30 INFO - ENV: RUST_BACKTRACE is now 1 05:05:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:05:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:05:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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:05:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-088 --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:05:30 INFO - Using env: {'DISPLAY': ':0', 05:05:30 INFO - 'HOME': '/home/cltbld', 05:05:30 INFO - 'LANG': 'en_US.UTF-8', 05:05:30 INFO - 'LANGUAGE': 'en_US:en', 05:05:30 INFO - 'LOGNAME': 'cltbld', 05:05:30 INFO - 'MAIL': '/var/mail/cltbld', 05:05:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:05:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:30 INFO - 'MOZ_NO_REMOTE': '1', 05:05:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:05:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:05:30 INFO - 'NO_EM_RESTART': '1', 05:05:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:30 INFO - 'PWD': '/builds/slave/test', 05:05:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:05:30 INFO - 'RUST_BACKTRACE': '1', 05:05:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:05:30 INFO - 'SHELL': '/bin/bash', 05:05:30 INFO - 'SHLVL': '1', 05:05:30 INFO - 'TERM': 'linux', 05:05:30 INFO - 'TMOUT': '86400', 05:05:30 INFO - 'USER': 'cltbld', 05:05:30 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698', 05:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:30 INFO - '_': '/tools/buildbot/bin/python'} 05:05:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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:05:30 INFO - mozversion application_buildid: 20170808111841 05:05:30 INFO - mozversion application_changeset: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da 05:05:30 INFO - mozversion application_display_name: Nightly 05:05:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:05:30 INFO - mozversion application_name: Firefox 05:05:30 INFO - mozversion application_remotingname: firefox 05:05:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:05:30 INFO - mozversion application_vendor: Mozilla 05:05:30 INFO - mozversion application_version: 57.0a1 05:05:30 INFO - mozversion platform_buildid: 20170808111841 05:05:30 INFO - mozversion platform_changeset: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da 05:05:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:05:30 INFO - mozversion platform_version: 57.0a1 05:05:30 INFO - using testdate: 1502193930 05:05:30 INFO - actual date: 1502193930 05:05:30 INFO - starting webserver on 'localhost:46698' 05:05:30 INFO - SUITE-START | Running 2 tests 05:05:30 INFO - TEST-START | dromaeo_css 05:05:30 INFO - Initialising browser for dromaeo_css test... 05:05:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46698/getInfo.html -profile /tmp/tmpBpe8WF/profile 05:05:30 INFO - TEST-INFO | started process 14187 (/builds/slave/test/build/application/firefox/firefox http://localhost:46698/getInfo.html) 05:05:36 INFO - TEST-INFO | 14187: exit 0 05:05:36 INFO - Browser initialized. 05:05:36 INFO - Running cycle 1/1 for dromaeo_css test... 05:05:36 INFO - TEST-INFO | started process 14399 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBpe8WF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:37 INFO - PID 14399 | 05:05:37 INFO - PID 14399 | (/builds/slave/test/build/application/firefox/firefox:14447): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:05:37 INFO - PID 14399 | 05:05:47 INFO - PID 14399 | RSS: Main: 168042496 05:05:47 INFO - PID 14399 | 05:08:47 INFO - PID 14399 | Cycle 1(1): loaded http://localhost:46698/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46698/tests/dromaeo/cssquery-ext.html) 05:08:48 INFO - PID 14399 | RSS: Main: 173170688 05:08:48 INFO - PID 14399 | 05:11:50 INFO - PID 14399 | Cycle 1(1): loaded http://localhost:46698/tests/dromaeo/cssquery-ext.html (next: http://localhost:46698/tests/dromaeo/cssquery-jquery.html) 05:11:51 INFO - PID 14399 | RSS: Main: 170684416 05:11:51 INFO - PID 14399 | 05:14:52 INFO - PID 14399 | Cycle 1(1): loaded http://localhost:46698/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46698/tests/dromaeo/cssquery-mootools.html) 05:14:53 INFO - PID 14399 | RSS: Main: 172498944 05:14:53 INFO - PID 14399 | 05:17:55 INFO - PID 14399 | Cycle 1(1): loaded http://localhost:46698/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46698/tests/dromaeo/cssquery-prototype.html) 05:17:55 INFO - PID 14399 | RSS: Main: 170696704 05:17:55 INFO - PID 14399 | 05:20:59 INFO - PID 14399 | Cycle 1(1): loaded http://localhost:46698/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46698/tests/dromaeo/cssquery-yui.html) 05:21:00 INFO - PID 14399 | RSS: Main: 173989888 05:21:00 INFO - PID 14399 | 05:24:02 INFO - PID 14399 | Cycle 1(1): loaded http://localhost:46698/tests/dromaeo/cssquery-yui.html (next: http://localhost:46698/tests/dromaeo/cssquery-dojo.html) 05:24:02 INFO - PID 14399 | RSS: Main: 174804992 05:24:02 INFO - PID 14399 | 05:24:02 INFO - PID 14399 | __start_tp_report 05:24:02 INFO - PID 14399 | _x_x_mozilla_page_load 05:24:02 INFO - PID 14399 | _x_x_mozilla_page_load_details 05:24:02 INFO - PID 14399 | |i|pagename|runs| 05:24:02 INFO - PID 14399 | |0;dojo.html;3159.4470967580683;3195.5206718992135;3196.5844440222777;3196.680331966803;3260.119767662731;6115.6636384998865;6259.624422534651;6294.118823364733;6319.368063193679;6332.240131184253;6171.3211546729935;6256.0615907613765;6278.874422511547;6282.52881033922;6294.150289710888;5702.059160238556;5717.171010203529;5731.340895796974;5761.654300741948;5821.446962538552;5749.223854779614;5752.74112664929;5776.277965254343;5798.246227990356;5801.535877129841;5641.8589535261535;5774.711264436762;5793.971030144843;5848.795292164775;5881.617694849842;5202.713850738208;5647.124695672177;5666.829995100154;5758.769649214027;5781.219535362714;5646.2942132233475;5675.574331925122;5722.2561067061015;5751.3961034091235;5763.394843541451;6156.415140561684;6193.752249909998;6213.968930155365;6280.434760871543;6290.559660823744;6726.730930762764;6813.1142951415995;6821.829454263633;6838.9741538769085;6842.965785171092;6793.252742198354;6796.116504854387;6799.150106236721;6922.100126983511;6935.583864968067;6121.173641558422;6241.750329986749;6242.625442473466;6315.210598675166;6393.424591786761;6936.132983377078;6944.583325000516;6945.40964018054;6948.756793512304;6965.477589180831;6518.706658200393;6583.440407565315;6592.571482853599;6596;6598.406143447113;6725.092112564839;6729.39435450802;6744.966275168691;6752.189737231465;6763.628000460022;6685.899711504331;6691.899621505681;6701.832454188684;6706.664666766642;6717.496187785838;6717.1267735194115;6717.899231511531;6719.798406047827;6750.797476075725;6760.1549806274215;4790.664653474293;5412.052890744183;5450.373207081237;5482.396936337;5483.945160548423;4608.262677971508;4630.305454181832;4640.071985602893;4725.4565724941385;4736.597389221886;5798.043322851682;5851;5863.413658634103;5914.378990206053;5916.7337469813965;6826.317368263134;6832.419244364185;6848.486363522756;6850.965745171242;6855.725770969106;5838.12428135783;5853.268341457318;5859.414058594191;5872.383399743051;5877.971355012855;5917.585768996215;5919.1713160157615;5993.370696076936;6003.219581454383;6005.969970150121;6493.512986525935;6494.350564943468;6592.538522303393;6592.80221593353;6593.868122637617;6656.400923916871;6747.055412242388;6749.257581666111;6830.385265326145;6835.282295359115;6527.249366322933;6549.901751473636;6625.370589793962;6631.734730610721;6645.9003114954285;6671.66641667905;6725.327467253432;6733.79798606023;6737.898931515933;6762.459003279824;6799.082123913208;6824.863502729819;6833.521653484208;6895.482838787211;6911;6445.677716114082;6567.671616419065;6576.046473261446;6656.201255849329;6677.565958212701;6550.901736473861;6558.934410655832;6565.638889860912;6567.605943643397;6573.704183311666;6480.254770701241;6485.837854053686;6495.1556297681;6497;6505.41451269388;5784.566157538117;6533.444657204191;6629.801105966829;6634.867302653824;6637.734490620321;6451.70967306463;6452.322506136788;6477.222733272038;6549.345065493412;6557.901631475436 05:24:02 INFO - PID 14399 | |1;ext.html;771.7183228121737;817.8200795824916;838.2539539809569;858.9141085891412;866.761640548849;42132.578674213284;42939.78530107349;43054.56945430553;43062.784686076564;43234.567654323444;38344;38499;38549.61450385495;38947.61052389482;38950.4157437638;6729.158855143095;6810.3189681031745;6828.556143850634;6836.726530938757;6850.725970961155;6750.696218670171;6830.453563714916;6851.143607049119;6855.657217139148;6864.41652459539;7531.887021694679;7627.199144089874;7627.923720762777;7677.923220767804;7696.846063078735;7593.696252149907;7659.731909383173;7666.195049519832;7667.118281397655;7676.577788221645;7524.172341042481;7537.133229678548;7568.659410326549;7660.578668173248;7663.5785031823225;39138.21723565513;39476.21047579032;39592.60407395918;39708.40437393441;39714;3458.1354661334667;3471.045462497808;3481.147118955872;3481.1645205150835;3487.232808782064;31723.84138079318;31904.361912761848;31924;32008.679913200802;32071.679283207104;5013.473585273567;5066.240063990394;5090.211017292326;5099.286099946011;5102.770375333119;198083;198910.99999999854;199653;199805;200516;50429;50451;50468.74765626148;50515;50645;51908.740456298234;52060.47939520632;52140;52199;52301.47698523042;16529.090900000614;16840.494785156465;16851.41020064292;16865.578360541083;16897.408590699266;13842.515511957234;13864.653383665287;13875.583732488041;13876.306184690928;13876.93061534706;14938.551843444715;15069.698606027818;15090.849091509164;15150.393984240727;15273.465428710164;5631.746571404296;5825.533957283407;5846.479915222073;5989.401059894063;6000.639961602318;976.8192884316283;1016.8118898003893;1022.5347466902541;1032.4992378696327;1035.4253389368973;23651.645225321634;30046.54930176049;30088.548671769942;30145.246368840955;30884.53673194904;1104.3539529375246;1109.1293334732359;1112.6828853776797;1118.7147277444199;1119.8264269038311;12830.80753788694;16509;16623.6675266494;16624.00255984645;16853.578660533338;258655;260357;260926;261165;263201;50197.49802502001;51096;58581.70709146512;58852.11721824176;60010.69994650086;1475;1481.2963842175095;1495.5513345996158;1498.595379247597;1502.6243439140214;27261;27722.584161237195;28378.00676976296;28681.569776453787;28742.281442964093;39966.2006759869;39970;40267.597324027556;40276;40301.39547906842;226446;229502;229590;229994;230295;33679.831600842816;34495.482567761;34519.30961380708;35000;35070.29859402747;43548.78225608852;43931.34102988394;44230.11539769251;44243;44425.33361999508;31986.6801331993;32091.518627221078;32211;32319.192020199684;32324.83837580797;31294.37411251808;31477.213069672525;31639;31706.365872681956;32304.35391292208;35144.47283290804;35298;35305.47041794426;35480.645193548764;35555.288894221456;30669.53995690022;30756.692433075383;31215.687843122185;31328.53007204848;31599.84200078985;22383.9927203273;23922.760772392052;23953.520929580962;24067.518649627258;24177 05:24:02 INFO - PID 14399 | |2;jquery.html;573.7819628541155;574.6638216643263;580.7502773807266;587.4859497939303;590.2032256453787;21586.460338491546;22638.660420093693;22798.65802012971;22800.20199293025;22815.42961425965;24045.15841945532;25083.37291567712;26439.471210575775;26466.86766566169;26494.470110597777;6481.935180648192;6654.6672666366485;6655.966720166392;6656.93343066568;6666.733330666768;5940.732667029973;6461.547691661585;6525.119108920282;6576.9148090564995;6581.670916454182;6415.101885736;6422.678866056702;6423.903641445382;6424.100625912376;6424.38968298011;6406.327335629761;6415.550911436178;6420.422162005395;6426.839329016789;6428.8071357859335;5937.554683398719;5970.13433052205;5977.432143946318;5986.102084687288;6007.128966299862;28227.57658635122;28549.429011419863;28883;28919.56620650692;30319.24201894948;1858.1081081081022;1912.5792325688465;1914.9138288776899;1915.186045930474;1916.0802814648907;18904.62190756177;19590.608187836307;19949.9002504988;19979.800201998085;19993.800061999485;2386.53462574798;2436.196055301746;2446.2416650838295;2447.8041756659422;2451.742567030472;131617;134344;135691;136050;136295.31852340774;14630.780538291934;15423.161194044633;15527.767083493756;15584.532464026097;15879.52361429159;24338;24403.511929761306;24416.26751197467;24453.510929781303;24475.632865507032;10689.9465502672;10850.67447976562;10856.45717714127;10946.233763636468;10975.341479511255;10143.644972426076;10147.238957078249;10155.492225388702;10185.08334249921;10312.742181445525;9154.31342649304;9419.728336674461;9662.855057174149;9716.757081072888;9828.803423931624;2744.4785490756694;2774.500589893798;2791.749250520406;2804.789640776909;2872.8850845966344;951.3720944176948;980.0836218136066;984.3159004491953;985.3348989432104;1001.1390204424189;19716.42268618507;19731;19820.70268945967;20213.797862021485;20449;977.9657711980046;997.4065431068502;1001.1990407673832;1004.3321191407632;1020.8570800087994;11189.552417903193;11338.60314888975;11547.076233901267;11615.6515304541;13630.386632601358;529056;544265;547151;560371;566162;126245.36877315554;127500;130690.34654826856;131076.3446182763;131208;1262.7663882181826;1273.6688461000022;1306.5296493262417;1310.8951283897266;1313.0480401708685;21576.892115539322;21673.241436549644;21695;21819.23632672849;22008.779912200673;58744;58774;59759.70120149371;60074.39925600688;60435.39564604474;78400.64798056068;78593;78606.6069669648;79047.60476197582;79661.60169199116;14827.703445930805;14931.626709331918;15098.320575573905;15174.165420901956;15176;23194.88402558044;23321.416964576023;23481.882590586938;23492.76507234906;23641.52716945617;18094.81905180984;18858.434246972614;18930.716039259147;18969.43091707251;19117.61764764669;16697.666046678754;16750.58123546921;17040.74438883393;17329.133543323034;17401;18482.907585461988;18727.90636046811;18997.525061873563;19149.234030638618;19295.324663636704;14778.778318325449;14841.480548180762;14855.108693478425;14865;14882.10707357562;17648.470545883643;18503.53741156482;18525.0737463129;18536.258549658403;18583.44249672512 05:24:02 INFO - PID 14399 | |3;mootools.html;1335.7795963665997;1363.1275983370651;1364.2196933002433;1378.0766886186261;1378.7587172244857;13630.182189068662;14227.78658320126;14307.71384572308;14340.569782906516;14357.425702971868;14317.570472885804;14661.486847960323;14699.338529766159;14746.115233086022;14802.037867538602;2594.584866421373;2629.0929629277916;2652.655154829879;2656.9070082547114;2658.694250161237;2716.1579910227792;2778.402643431662;2789.9581506277423;2804.354998350381;2805.8737356818892;2609.921702348923;2656.335916020995;2660.414708764069;2660.946781064378;2661.3213630524187;2483.788877945377;2504.887280072401;2571.8585477798715;2599.376149724062;2639.7492238237337;2423.9757602423924;2569.1907049279416;2574.4979728952862;2606.1660268714018;2608.6608740863758;13763.862361376358;13768.724625507533;13790;13801.723965520634;13803.309834508334;7640;7957.244061814118;7974.082980457264;7981.361491080757;8019.839603207962;5003.2995380646735;5126.436092029911;5185.585153187736;5253.789848406059;5273.815416460444;1815.6836293687104;1840.3558754435974;1861.757971463701;1879.9436016919378;1884.6984482482737;22635.77364226353;22758.203462878886;23302.53394932109;23374.766252337427;24010.279691609107;7059.752908648173;7110.32451917067;7147.2587762891435;7163.391111755455;7304.780856574312;18202.180901859447;18203.45389638313;18223.088845557933;18288.72566911471;18326.63346733058;7555.773326800205;7826.060872695313;7889.289963903161;7891.487053341514;7917.960410198028;6955.547889387173;7042.154941407064;7070.36366726997;7086.291370862872;7186.10173728284;5454.972725136349;5560.304961879765;5598.160275958558;5640.605157639008;5688.288963879515;1766.222861940742;1812.2750899639987;1817.5092724964186;1842.474894655017;1844.8524118070522;900.2662829793697;910.3445519226146;914.9085091490931;921.1847514949318;922.9400088994194;7433.442491813135;7447.180810110884;7490.475666703279;7539.585322807298;7547.16981132075;705.3898377903076;732.5568629799287;742.6620887496176;743.5427212264418;747.4356860570234;4634;4949.158643030693;5209.843704688866;5258.290130832365;5542.612017158841;18667.439976800717;18849.24603015864;19219.519512012033;19226.134823932956;19560.510987225432;18980.240790368505;19095.427137185907;19264.422067338;19267.22931082769;19301.131449084824;10213.693589192188;10339.896601034045;10610.57557697699;10947.124230061576;10999.835002474969;7615.847683046197;7810;7930.3973664944;8078.192180781874;8114.5942702865805;16600.252988615783;16882.577935551217;17059.573510661838;17240.91379543094;17398.73901891447;16880.240389182272;16969.74545381845;17139.742903856204;17505;17670.82329176692;12887.291198984145;13142.540011099565;13155.868441315464;13179;13221.471141154247;12677.366131693194;12696.74606507883;12709.618711438672;12716.74566508646;12773.61679149627;10347.43089130105;10498.737531561772;10544.947275263574;10654.094401975608;10799.244052916221;9817.803643927224;10146.594136234467;10329.018743219382;10508.316959397614;10712.35725856451;8809.207171354608;8945.59744811498;8964.462132272085;8979.50612716307;9016.323775716979;7369.410447164107;7371.889421658787;7424.591647459441;7433.479656424215;7520.661570229244;7522.172561018174;7540.3213710766295;7601.847963040597;7624.199459056719;7729.304362607392 05:24:02 INFO - PID 14399 | |4;prototype.html;498.82291786415846;509.462517044518;527.8310940499042;537.0574641504162;539.1454544546893;6160.907586386208;6204.379562043793;6206.286277078132;6254.7498100076;6276.65478398689;5918.792842250522;6089.634621922688;6107.786227482039;6112.083187521874;6121.632702037881;4876.390451193601;4929.926051109236;4940.25896115582;4948.2824990376375;4948.653594248404;4909.533594308535;4924.852254432372;4952.479989601094;4962.255661650764;4986.576141027999;4784.569388755029;4843.031393721252;4871.634627402959;4907.460179380265;4917.877053073684;4867.805287788484;4922.778474968635;4927.1870141426625;4941.654084214107;4946.505349465061;4440.511543730187;4442.355858400514;4442.711223770477;4459.665525085599;4461.553844615545;6036.396360363972;6053.606515576517;6066.939330606681;6067.848303792396;6070.696465176724;5690.857728556778;5700.2589663343915;5702.173184888168;5703.4011428799795;5728.770849166029;6521.608703477807;6538.574992625464;6553.6395498247575;6560.835979100513;6567.211934567835;4824.710517368969;4842.564169224786;4850.6362022848425;4876.609871210295;4881.438634557036;6816.454683625348;6910.7581234656545;6917.342852429011;6917.7232910683515;6924.065251191126;4851.466338702739;4935.802567897315;4945.908806995628;4975.154223781966;4993.176125939252;5007.197408932781;5051.292819005343;5060.620453466005;5071.518205770446;5088.847334579968;5648.350439699487;5666.943330566724;5677.120046392815;5679.545636349082;5684.005299072645;6512.023196520559;6549.639769812611;6563.540552161303;6570.310117437601;6621.437177839937;1655.6605895791574;1720.8537274331572;1728.567858035491;1761.1722490429477;1763.206557049333;1382.2743059893435;1444.2995147353524;1444.6893917807722;1450.100937418807;1473.6168596165073;5636;5710.314762228559;5712.600117991784;5724.227229324071;5759.884802303931;6539.476841852736;6653.833654158588;6685.328667833041;6691.26396096429;6735.696893639798;5699.145128230715;5723.112917497794;5754.913676294859;5764.097847710245;5773.422657734193;3501.124718820295;3536.5932917714276;3568.3576956148145;3587.461880717913;3632.3461776879885;6507.446867016357;6514.73941042353;6516.380943810331;6537.5750576212395;6539.280679125292;6849.39039325758;6861.039454476378;6864.519483636197;6921.965390173118;6937.802629409236;6423.132877061536;6500.772472963424;6517.674116294072;6535.607863528204;6569.507286953498;6649.534532582867;6649.634270114997;6694.297098804555;6744.5616034957575;6795.38841504267;6599.835004124935;6658.866822663423;6659.800205993828;6736.5958042517605;6738.35985581369;6452;6461.256955450183;6485.902711459426;6487.18910136233;6548.1814773153355;4940.876478087933;4952.356193694796;4973.801047958186;4986.451490336132;5011.5489605935645;6661.600303981777;6683.131192944886;6686.53194276396;6721.395074443323;6800.693968771318;6417.839554011187;6497.415232628897;6521.608703477807;6528.37980391862;6556.8360790980605;6417.647029413427;6446.194225721785;6465.806025819233;6469.6090340577;6476.579022363531;6071.423214794588;6117.3576774439825;6294.622322660655;6305.653189074645;6349.746010159542;6407.615543067431;6475;6481.643509607017;6500.902486462802;6527.934720652733;6190.566660333733;6258.874822503615;6279.461531924708;6306.180196574416;6310.810675679737 05:24:02 INFO - PID 14399 | |5;yui.html;821.7165078048079;831.4221615976895;846.5809424334955;902.2601466797224;909.354358405532;18918.716219256723;19067;19234.519137021565;19363.709544356847;20869.37391878242;19641.116149773257;19645.50886227845;19704.211831526758;19822.70265946012;19834.107465164067;984.8965858584853;1012.7518757904287;1014.761531040205;1015.0154350280217;1015.8019186258682;1045.028123844824;1056.461204785561;1056.5245639462235;1056.6248981611532;1057.2704833664784;965.5944503308592;1007.652359935821;1022.1924679503184;1045.0646671229244;1058.0688993685585;1044.5508431374506;1047.5704960966004;1053.1522124689623;1053.4311471805293;1053.9104176145074;763.5571368606195;764.5412752348602;772.0233904111303;772.9884051739228;773.6131934032984;17666.11669416537;18154.636907261913;18264.36074737391;18483.353082642043;18649.90675046616;20033;20311.086001129985;20313.187472501082;20579.48551286215;20603.381898543066;16382.098984555845;16628.750568741478;16791;16791.32834686611;16814.495565133064;3668.192997540537;3673.7612055216327;3687.7418580699364;3688.6495782900583;3702.129999450127;124837;126680;127267.3636631811;128522.3573882134;129443;15794.763078553831;15990.520284391487;16457.753133703005;16459.177041147894;16507.752383714254;29999;30482;30507.2373190672;30529.69470305313;30657.08028759141;7509;7634.427417943565;8160.102388737234;8978.640854365884;9314.347995640375;4233.343831706091;4338.305871060616;4345.087531618332;4346.434963454731;4350.60844523988;5205.7397130143345;5272.841814745564;5272.868178295574;5290.497402746733;5360.892782144335;1089.9400532970603;1103.9282446640943;1110.1396417776289;1114.643314139484;1118.5022664914036;674.7672053141658;687.8280429892527;688.0779755128052;693.2893783871572;708.8475977664826;12051.879481205251;12126.393680315949;12478.126531142732;12665.303408312448;12866.935665321613;664.8271449423088;667.9833004174934;682.4642655515397;695.7390978383106;716.3409663109951;5643.209950606918;5708.143778433268;5726.312842458931;5739.8565035874435;5863.208466857005;102076.48961755293;105397;105922;106102.93897061085;106860.46569767101;92939;94690;95402.52298738601;101138.49430752799;101231;77077.22922770813;79719;80516;81556;82266.17733822705;10686.73283167927;10856.945715271373;10879.728006799893;11231.943840280746;11234.662960111209;31984.84007579947;32823.67176328302;33018.33963320672;34716;34867.47698784571;43961;44173.55826441823;44315.55684443243;44808.32787508255;45876.77061614671;16277.75583366274;16465.83534164643;16577.50267491977;16711.24799384055;16858.578535536708;17661.205245763715;20266.18935242633;20661.58676826485;20779.68830467514;21115.57768844584;18016.189271483076;18031.81968180354;18222.271109155485;18407.90796046011;18438.72341914899;16753.162341882613;16864.988100713996;16878.83121168822;17008.319667213174;17056.744148838025;18328.17523211432;18412.631747365245;18417;18725.438236852915;18739.437816865517;10096.798064038825;10137.442440665835;10360.948195258976;10574.735631609272;10634.840477393;13802.378892950042;14200.92899535537;14266.42934282617;14439.278036098363;14816.481423150139 05:24:02 INFO - PID 14399 | __end_tp_report 05:24:02 INFO - PID 14399 | __start_cc_report 05:24:02 INFO - PID 14399 | _x_x_mozilla_cycle_collect,342 05:24:02 INFO - PID 14399 | __end_cc_report 05:24:02 INFO - PID 14399 | __startTimestamp1502195042327__endTimestamp 05:24:02 INFO - PID 14399 | 05:24:02 INFO - PID 14399 | ------- Summary: start ------- 05:24:02 INFO - PID 14399 | Number of tests: 6 05:24:02 INFO - PID 14399 | 05:24:02 INFO - PID 14399 | [#0] dojo.html Cycles:165 Average:6206.46 Median:6493.51 stddev:748.58 (11.5%) stddev-sans-first:711.69 05:24:02 INFO - PID 14399 | Values: 3159.4 3195.5 3196.6 3196.7 3260.1 6115.7 6259.6 6294.1 6319.4 6332.2 6171.3 6256.1 6278.9 6282.5 6294.2 5702.1 5717.2 5731.3 5761.7 5821.4 5749.2 5752.7 5776.3 5798.2 5801.5 5641.9 5774.7 5794.0 5848.8 5881.6 5202.7 5647.1 5666.8 5758.8 5781.2 5646.3 5675.6 5722.3 5751.4 5763.4 6156.4 6193.8 6214.0 6280.4 6290.6 6726.7 6813.1 6821.8 6839.0 6843.0 6793.3 6796.1 6799.2 6922.1 6935.6 6121.2 6241.8 6242.6 6315.2 6393.4 6936.1 6944.6 6945.4 6948.8 6965.5 6518.7 6583.4 6592.6 6596.0 6598.4 6725.1 6729.4 6745.0 6752.2 6763.6 6685.9 6691.9 6701.8 6706.7 6717.5 6717.1 6717.9 6719.8 6750.8 6760.2 4790.7 5412.1 5450.4 5482.4 5483.9 4608.3 4630.3 4640.1 4725.5 4736.6 5798.0 5851.0 5863.4 5914.4 5916.7 6826.3 6832.4 6848.5 6851.0 6855.7 5838.1 5853.3 5859.4 5872.4 5878.0 5917.6 5919.2 5993.4 6003.2 6006.0 6493.5 6494.4 6592.5 6592.8 6593.9 6656.4 6747.1 6749.3 6830.4 6835.3 6527.2 6549.9 6625.4 6631.7 6645.9 6671.7 6725.3 6733.8 6737.9 6762.5 6799.1 6824.9 6833.5 6895.5 6911.0 6445.7 6567.7 6576.0 6656.2 6677.6 6550.9 6558.9 6565.6 6567.6 6573.7 6480.3 6485.8 6495.2 6497.0 6505.4 5784.6 6533.4 6629.8 6634.9 6637.7 6451.7 6452.3 6477.2 6549.3 6557.9 05:24:02 INFO - PID 14399 | 05:24:02 INFO - PID 14399 | [#1] ext.html Cycles:180 Average:40215.80 Median:3484.20 stddev:60010.23 (1722.4%) stddev-sans-first:60105.49 05:24:02 INFO - PID 14399 | Values: 771.7 817.8 838.3 858.9 866.8 42132.6 42939.8 43054.6 43062.8 43234.6 38344.0 38499.0 38549.6 38947.6 38950.4 6729.2 6810.3 6828.6 6836.7 6850.7 6750.7 6830.5 6851.1 6855.7 6864.4 7531.9 7627.2 7627.9 7677.9 7696.8 7593.7 7659.7 7666.2 7667.1 7676.6 7524.2 7537.1 7568.7 7660.6 7663.6 39138.2 39476.2 39592.6 39708.4 39714.0 3458.1 3471.0 3481.1 3481.2 3487.2 31723.8 31904.4 31924.0 32008.7 32071.7 5013.5 5066.2 5090.2 5099.3 5102.8 198083.0 198911.0 199653.0 199805.0 200516.0 50429.0 50451.0 50468.7 50515.0 50645.0 51908.7 52060.5 52140.0 52199.0 52301.5 16529.1 16840.5 16851.4 16865.6 16897.4 13842.5 13864.7 13875.6 13876.3 13876.9 14938.6 15069.7 15090.8 15150.4 15273.5 5631.7 5825.5 5846.5 5989.4 6000.6 976.8 1016.8 1022.5 1032.5 1035.4 23651.6 30046.5 30088.5 30145.2 30884.5 1104.4 1109.1 1112.7 1118.7 1119.8 12830.8 16509.0 16623.7 16624.0 16853.6 258655.0 260357.0 260926.0 261165.0 263201.0 50197.5 51096.0 58581.7 58852.1 60010.7 1475.0 1481.3 1495.6 1498.6 1502.6 27261.0 27722.6 28378.0 28681.6 28742.3 39966.2 39970.0 40267.6 40276.0 40301.4 226446.0 229502.0 229590.0 229994.0 230295.0 33679.8 34495.5 34519.3 35000.0 35070.3 43548.8 43931.3 44230.1 44243.0 44425.3 31986.7 32091.5 32211.0 32319.2 32324.8 31294.4 31477.2 31639.0 31706.4 32304.4 35144.5 35298.0 35305.5 35480.6 35555.3 30669.5 30756.7 31215.7 31328.5 31599.8 22384.0 23922.8 23953.5 24067.5 24177.0 05:24:02 INFO - PID 14399 | 05:24:02 INFO - PID 14399 | [#2] jquery.html Cycles:180 Average:37283.07 Median:22718.66 stddev:92114.11 (405.5%) stddev-sans-first:92331.28 05:24:02 INFO - PID 14399 | Values: 573.8 574.7 580.8 587.5 590.2 21586.5 22638.7 22798.7 22800.2 22815.4 24045.2 25083.4 26439.5 26466.9 26494.5 6481.9 6654.7 6656.0 6656.9 6666.7 5940.7 6461.5 6525.1 6576.9 6581.7 6415.1 6422.7 6423.9 6424.1 6424.4 6406.3 6415.6 6420.4 6426.8 6428.8 5937.6 5970.1 5977.4 5986.1 6007.1 28227.6 28549.4 28883.0 28919.6 30319.2 1858.1 1912.6 1914.9 1915.2 1916.1 18904.6 19590.6 19949.9 19979.8 19993.8 2386.5 2436.2 2446.2 2447.8 2451.7 131617.0 134344.0 135691.0 136050.0 136295.3 14630.8 15423.2 15527.8 15584.5 15879.5 24338.0 24403.5 24416.3 24453.5 24475.6 10689.9 10850.7 10856.5 10946.2 10975.3 10143.6 10147.2 10155.5 10185.1 10312.7 9154.3 9419.7 9662.9 9716.8 9828.8 2744.5 2774.5 2791.7 2804.8 2872.9 951.4 980.1 984.3 985.3 1001.1 19716.4 19731.0 19820.7 20213.8 20449.0 978.0 997.4 1001.2 1004.3 1020.9 11189.6 11338.6 11547.1 11615.7 13630.4 529056.0 544265.0 547151.0 560371.0 566162.0 126245.4 127500.0 130690.3 131076.3 131208.0 1262.8 1273.7 1306.5 1310.9 1313.0 21576.9 21673.2 21695.0 21819.2 22008.8 58744.0 58774.0 59759.7 60074.4 60435.4 78400.6 78593.0 78606.6 79047.6 79661.6 14827.7 14931.6 15098.3 15174.2 15176.0 23194.9 23321.4 23481.9 23492.8 23641.5 18094.8 18858.4 18930.7 18969.4 19117.6 16697.7 16750.6 17040.7 17329.1 17401.0 18482.9 18727.9 18997.5 19149.2 19295.3 14778.8 14841.5 14855.1 14865.0 14882.1 17648.5 18503.5 18525.1 18536.3 18583.4 05:24:02 INFO - PID 14399 | 05:24:02 INFO - PID 14399 | [#3] mootools.html Cycles:180 Average:8861.75 Median:2596.98 stddev:6029.20 (232.2%) stddev-sans-first:6019.59 05:24:02 INFO - PID 14399 | Values: 1335.8 1363.1 1364.2 1378.1 1378.8 13630.2 14227.8 14307.7 14340.6 14357.4 14317.6 14661.5 14699.3 14746.1 14802.0 2594.6 2629.1 2652.7 2656.9 2658.7 2716.2 2778.4 2790.0 2804.4 2805.9 2609.9 2656.3 2660.4 2660.9 2661.3 2483.8 2504.9 2571.9 2599.4 2639.7 2424.0 2569.2 2574.5 2606.2 2608.7 13763.9 13768.7 13790.0 13801.7 13803.3 7640.0 7957.2 7974.1 7981.4 8019.8 5003.3 5126.4 5185.6 5253.8 5273.8 1815.7 1840.4 1861.8 1879.9 1884.7 22635.8 22758.2 23302.5 23374.8 24010.3 7059.8 7110.3 7147.3 7163.4 7304.8 18202.2 18203.5 18223.1 18288.7 18326.6 7555.8 7826.1 7889.3 7891.5 7918.0 6955.5 7042.2 7070.4 7086.3 7186.1 5455.0 5560.3 5598.2 5640.6 5688.3 1766.2 1812.3 1817.5 1842.5 1844.9 900.3 910.3 914.9 921.2 922.9 7433.4 7447.2 7490.5 7539.6 7547.2 705.4 732.6 742.7 743.5 747.4 4634.0 4949.2 5209.8 5258.3 5542.6 18667.4 18849.2 19219.5 19226.1 19560.5 18980.2 19095.4 19264.4 19267.2 19301.1 10213.7 10339.9 10610.6 10947.1 10999.8 7615.8 7810.0 7930.4 8078.2 8114.6 16600.3 16882.6 17059.6 17240.9 17398.7 16880.2 16969.7 17139.7 17505.0 17670.8 12887.3 13142.5 13155.9 13179.0 13221.5 12677.4 12696.7 12709.6 12716.7 12773.6 10347.4 10498.7 10544.9 10654.1 10799.2 9817.8 10146.6 10329.0 10508.3 10712.4 8809.2 8945.6 8964.5 8979.5 9016.3 7369.4 7371.9 7424.6 7433.5 7520.7 7522.2 7540.3 7601.8 7624.2 7729.3 05:24:02 INFO - PID 14399 | 05:24:02 INFO - PID 14399 | [#4] prototype.html Cycles:180 Average:5485.37 Median:6071.06 stddev:1523.78 (25.1%) stddev-sans-first:1481.38 05:24:02 INFO - PID 14399 | Values: 498.8 509.5 527.8 537.1 539.1 6160.9 6204.4 6206.3 6254.7 6276.7 5918.8 6089.6 6107.8 6112.1 6121.6 4876.4 4929.9 4940.3 4948.3 4948.7 4909.5 4924.9 4952.5 4962.3 4986.6 4784.6 4843.0 4871.6 4907.5 4917.9 4867.8 4922.8 4927.2 4941.7 4946.5 4440.5 4442.4 4442.7 4459.7 4461.6 6036.4 6053.6 6066.9 6067.8 6070.7 5690.9 5700.3 5702.2 5703.4 5728.8 6521.6 6538.6 6553.6 6560.8 6567.2 4824.7 4842.6 4850.6 4876.6 4881.4 6816.5 6910.8 6917.3 6917.7 6924.1 4851.5 4935.8 4945.9 4975.2 4993.2 5007.2 5051.3 5060.6 5071.5 5088.8 5648.4 5666.9 5677.1 5679.5 5684.0 6512.0 6549.6 6563.5 6570.3 6621.4 1655.7 1720.9 1728.6 1761.2 1763.2 1382.3 1444.3 1444.7 1450.1 1473.6 5636.0 5710.3 5712.6 5724.2 5759.9 6539.5 6653.8 6685.3 6691.3 6735.7 5699.1 5723.1 5754.9 5764.1 5773.4 3501.1 3536.6 3568.4 3587.5 3632.3 6507.4 6514.7 6516.4 6537.6 6539.3 6849.4 6861.0 6864.5 6922.0 6937.8 6423.1 6500.8 6517.7 6535.6 6569.5 6649.5 6649.6 6694.3 6744.6 6795.4 6599.8 6658.9 6659.8 6736.6 6738.4 6452.0 6461.3 6485.9 6487.2 6548.2 4940.9 4952.4 4973.8 4986.5 5011.5 6661.6 6683.1 6686.5 6721.4 6800.7 6417.8 6497.4 6521.6 6528.4 6556.8 6417.6 6446.2 6465.8 6469.6 6476.6 6071.4 6117.4 6294.6 6305.7 6349.7 6407.6 6475.0 6481.6 6500.9 6527.9 6190.6 6258.9 6279.5 6306.2 6310.8 05:24:02 INFO - PID 14399 | 05:24:02 INFO - PID 14399 | [#5] yui.html Cycles:180 Average:22312.13 Median:18829.08 stddev:30708.42 (163.1%) stddev-sans-first:30752.17 05:24:02 INFO - PID 14399 | Values: 821.7 831.4 846.6 902.3 909.4 18918.7 19067.0 19234.5 19363.7 20869.4 19641.1 19645.5 19704.2 19822.7 19834.1 984.9 1012.8 1014.8 1015.0 1015.8 1045.0 1056.5 1056.5 1056.6 1057.3 965.6 1007.7 1022.2 1045.1 1058.1 1044.6 1047.6 1053.2 1053.4 1053.9 763.6 764.5 772.0 773.0 773.6 17666.1 18154.6 18264.4 18483.4 18649.9 20033.0 20311.1 20313.2 20579.5 20603.4 16382.1 16628.8 16791.0 16791.3 16814.5 3668.2 3673.8 3687.7 3688.6 3702.1 124837.0 126680.0 127267.4 128522.4 129443.0 15794.8 15990.5 16457.8 16459.2 16507.8 29999.0 30482.0 30507.2 30529.7 30657.1 7509.0 7634.4 8160.1 8978.6 9314.3 4233.3 4338.3 4345.1 4346.4 4350.6 5205.7 5272.8 5272.9 5290.5 5360.9 1089.9 1103.9 1110.1 1114.6 1118.5 674.8 687.8 688.1 693.3 708.8 12051.9 12126.4 12478.1 12665.3 12866.9 664.8 668.0 682.5 695.7 716.3 5643.2 5708.1 5726.3 5739.9 5863.2 102076.5 105397.0 105922.0 106102.9 106860.5 92939.0 94690.0 95402.5 101138.5 101231.0 77077.2 79719.0 80516.0 81556.0 82266.2 10686.7 10856.9 10879.7 11231.9 11234.7 31984.8 32823.7 33018.3 34716.0 34867.5 43961.0 44173.6 44315.6 44808.3 45876.8 16277.8 16465.8 16577.5 16711.2 16858.6 17661.2 20266.2 20661.6 20779.7 21115.6 18016.2 18031.8 18222.3 18407.9 18438.7 16753.2 16865.0 16878.8 17008.3 17056.7 18328.2 18412.6 18417.0 18725.4 18739.4 10096.8 10137.4 10360.9 10574.7 10634.8 13802.4 14200.9 14266.4 14439.3 14816.5 05:24:02 INFO - PID 14399 | -------- Summary: end -------- 05:24:02 INFO - PID 14399 | 05:24:02 INFO - TEST-INFO | 14399: exit 0 05:24:02 INFO - TEST-OK | dromaeo_css | took 1112307ms 05:24:02 INFO - TEST-START | kraken 05:24:02 INFO - Initialising browser for kraken test... 05:24:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46698/getInfo.html -profile /tmp/tmpyr3kK9/profile 05:24:02 INFO - TEST-INFO | started process 14759 (/builds/slave/test/build/application/firefox/firefox http://localhost:46698/getInfo.html) 05:24:08 INFO - TEST-INFO | 14759: exit 0 05:24:08 INFO - Browser initialized. 05:24:08 INFO - Running cycle 1/1 for kraken test... 05:24:08 INFO - TEST-INFO | started process 14969 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyr3kK9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:09 INFO - PID 14969 | 05:24:09 INFO - PID 14969 | (/builds/slave/test/build/application/firefox/firefox:15017): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:24:09 INFO - PID 14969 | 05:24:20 INFO - PID 14969 | RSS: Main: 171941888 05:24:20 INFO - PID 14969 | 05:24:22 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46698/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:23 INFO - PID 14969 | RSS: Main: 177459200 05:24:23 INFO - PID 14969 | 05:24:26 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46698/tests/kraken/driver.html?testName=audio-dft) 05:24:27 INFO - PID 14969 | RSS: Main: 177963008 05:24:27 INFO - PID 14969 | 05:24:30 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46698/tests/kraken/driver.html?testName=audio-fft) 05:24:30 INFO - PID 14969 | RSS: Main: 171503616 05:24:30 INFO - PID 14969 | 05:24:32 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46698/tests/kraken/driver.html?testName=audio-oscillator) 05:24:33 INFO - PID 14969 | RSS: Main: 172007424 05:24:33 INFO - PID 14969 | 05:24:35 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46698/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:24:36 INFO - PID 14969 | RSS: Main: 172191744 05:24:36 INFO - PID 14969 | 05:24:39 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46698/tests/kraken/driver.html?testName=imaging-darkroom) 05:24:39 INFO - PID 14969 | RSS: Main: 174645248 05:24:39 INFO - PID 14969 | 05:24:43 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46698/tests/kraken/driver.html?testName=imaging-desaturate) 05:24:44 INFO - PID 14969 | RSS: Main: 175038464 05:24:44 INFO - PID 14969 | 05:24:46 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46698/tests/kraken/driver.html?testName=json-parse-financial) 05:24:47 INFO - PID 14969 | RSS: Main: 175177728 05:24:47 INFO - PID 14969 | 05:24:49 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46698/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:24:49 INFO - PID 14969 | RSS: Main: 175398912 05:24:49 INFO - PID 14969 | 05:24:51 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46698/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:24:52 INFO - PID 14969 | RSS: Main: 175468544 05:24:52 INFO - PID 14969 | 05:24:54 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46698/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:24:54 INFO - PID 14969 | RSS: Main: 172523520 05:24:54 INFO - PID 14969 | 05:24:57 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46698/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:24:58 INFO - PID 14969 | RSS: Main: 172773376 05:24:58 INFO - PID 14969 | 05:25:01 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46698/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:25:02 INFO - PID 14969 | RSS: Main: 172859392 05:25:02 INFO - PID 14969 | 05:25:03 INFO - PID 14969 | Cycle 1(1): loaded http://localhost:46698/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46698/tests/kraken/driver.html?testName=ai-astar) 05:25:04 INFO - PID 14969 | RSS: Main: 174247936 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | __start_tp_report 05:25:04 INFO - PID 14969 | _x_x_mozilla_page_load 05:25:04 INFO - PID 14969 | _x_x_mozilla_page_load_details 05:25:04 INFO - PID 14969 | |i|pagename|runs| 05:25:04 INFO - PID 14969 | |0;ai-astar;99;108;100;107;108;108;109;99;106;109 05:25:04 INFO - PID 14969 | |1;audio-beat-detection;147;124;145;181;116;146;162;121;168;125 05:25:04 INFO - PID 14969 | |2;audio-dft;165;164;164;162;163;166;166;165;171;164 05:25:04 INFO - PID 14969 | |3;audio-fft;74;75;75;173;77;75;74;74;172;77 05:25:04 INFO - PID 14969 | |4;audio-oscillator;79;80;128;79;80;79;126;79;77;127 05:25:04 INFO - PID 14969 | |5;imaging-gaussian-blur;114;114;113;115;115;114;114;114;114;114 05:25:04 INFO - PID 14969 | |6;imaging-darkroom;151;151;150;154;152;151;155;150;152;151 05:25:04 INFO - PID 14969 | |7;imaging-desaturate;99;96;94;94;94;94;94;94;95;94 05:25:04 INFO - PID 14969 | |8;json-parse-financial;54;57;54;54;54;54;55;54;54;55 05:25:04 INFO - PID 14969 | |9;json-stringify-tinderbox;54;53;54;53;52;55;54;53;53;53 05:25:04 INFO - PID 14969 | |10;stanford-crypto-aes;97;88;79;80;78;89;77;87;79;80 05:25:04 INFO - PID 14969 | |11;stanford-crypto-ccm;129;137;127;136;126;144;135;129;135;164 05:25:04 INFO - PID 14969 | |12;stanford-crypto-pbkdf2;174;178;190;190;193;182;184;185;187;176 05:25:04 INFO - PID 14969 | |13;stanford-crypto-sha256-iterative;67;67;65;67;67;68;65;73;66;71 05:25:04 INFO - PID 14969 | __end_tp_report 05:25:04 INFO - PID 14969 | __start_cc_report 05:25:04 INFO - PID 14969 | _x_x_mozilla_cycle_collect,922 05:25:04 INFO - PID 14969 | __end_cc_report 05:25:04 INFO - PID 14969 | __startTimestamp1502195104169__endTimestamp 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | ------- Summary: start ------- 05:25:04 INFO - PID 14969 | Number of tests: 14 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#0] ai-astar Cycles:10 Average:105.30 Median:108.50 stddev:4.22 (3.9%) stddev-sans-first:3.81 05:25:04 INFO - PID 14969 | Values: 99.0 108.0 100.0 107.0 108.0 108.0 109.0 99.0 106.0 109.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:146.50 stddev:22.00 (15.0%) stddev-sans-first:23.29 05:25:04 INFO - PID 14969 | Values: 147.0 124.0 145.0 181.0 116.0 146.0 162.0 121.0 168.0 125.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#2] audio-dft Cycles:10 Average:165.00 Median:165.00 stddev:2.45 (1.5%) stddev-sans-first:2.60 05:25:04 INFO - PID 14969 | Values: 165.0 164.0 164.0 162.0 163.0 166.0 166.0 165.0 171.0 164.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#3] audio-fft Cycles:10 Average:94.60 Median:75.00 stddev:41.07 (54.8%) stddev-sans-first:42.88 05:25:04 INFO - PID 14969 | Values: 74.0 75.0 75.0 173.0 77.0 75.0 74.0 74.0 172.0 77.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:79.00 stddev:23.21 (29.4%) stddev-sans-first:24.02 05:25:04 INFO - PID 14969 | Values: 79.0 80.0 128.0 79.0 80.0 79.0 126.0 79.0 77.0 127.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 05:25:04 INFO - PID 14969 | Values: 114.0 114.0 113.0 115.0 115.0 114.0 114.0 114.0 114.0 114.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#6] imaging-darkroom Cycles:10 Average:151.70 Median:151.50 stddev:1.64 (1.1%) stddev-sans-first:1.72 05:25:04 INFO - PID 14969 | Values: 151.0 151.0 150.0 154.0 152.0 151.0 155.0 150.0 152.0 151.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.00 stddev:1.62 (1.7%) stddev-sans-first:0.71 05:25:04 INFO - PID 14969 | Values: 99.0 96.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 05:25:04 INFO - PID 14969 | Values: 54.0 57.0 54.0 54.0 54.0 54.0 55.0 54.0 54.0 55.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#9] json-stringify-tinderbox Cycles:10 Average:53.40 Median:53.50 stddev:0.84 (1.6%) stddev-sans-first:0.87 05:25:04 INFO - PID 14969 | Values: 54.0 53.0 54.0 53.0 52.0 55.0 54.0 53.0 53.0 53.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:83.50 stddev:6.52 (7.8%) stddev-sans-first:4.70 05:25:04 INFO - PID 14969 | Values: 97.0 88.0 79.0 80.0 78.0 89.0 77.0 87.0 79.0 80.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#11] stanford-crypto-ccm Cycles:10 Average:136.20 Median:135.50 stddev:11.20 (8.3%) stddev-sans-first:11.58 05:25:04 INFO - PID 14969 | Values: 129.0 137.0 127.0 136.0 126.0 144.0 135.0 129.0 135.0 164.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.90 Median:186.00 stddev:6.38 (3.4%) stddev-sans-first:5.68 05:25:04 INFO - PID 14969 | Values: 174.0 178.0 190.0 190.0 193.0 182.0 184.0 185.0 187.0 176.0 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.00 stddev:2.55 (3.8%) stddev-sans-first:2.69 05:25:04 INFO - PID 14969 | Values: 67.0 67.0 65.0 67.0 67.0 68.0 65.0 73.0 66.0 71.0 05:25:04 INFO - PID 14969 | -------- Summary: end -------- 05:25:04 INFO - PID 14969 | 05:25:04 INFO - PID 14969 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:25:04 INFO - TEST-INFO | 14969: exit 0 05:25:04 INFO - TEST-OK | kraken | took 61893ms 05:25:04 INFO - SUITE-END | took 1174s 05:25:05 INFO - Completed test suite (00:19:34) 05:25:05 INFO - javascript benchmark 05:25:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8529.443627661694, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3159.4470967580683, 3195.5206718992135, 3196.5844440222777, 3196.680331966803, 3260.119767662731, 6115.6636384998865, 6259.624422534651, 6294.118823364733, 6319.368063193679, 6332.240131184253, 6171.3211546729935, 6256.0615907613765, 6278.874422511547, 6282.52881033922, 6294.150289710888, 5702.059160238556, 5717.171010203529, 5731.340895796974, 5761.654300741948, 5821.446962538552, 5749.223854779614, 5752.74112664929, 5776.277965254343, 5798.246227990356, 5801.535877129841, 5641.8589535261535, 5774.711264436762, 5793.971030144843, 5848.795292164775, 5881.617694849842, 5202.713850738208, 5647.124695672177, 5666.829995100154, 5758.769649214027, 5781.219535362714, 5646.2942132233475, 5675.574331925122, 5722.2561067061015, 5751.3961034091235, 5763.394843541451, 6156.415140561684, 6193.752249909998, 6213.968930155365, 6280.434760871543, 6290.559660823744, 6726.730930762764, 6813.1142951415995, 6821.829454263633, 6838.9741538769085, 6842.965785171092, 6793.252742198354, 6796.116504854387, 6799.150106236721, 6922.100126983511, 6935.583864968067, 6121.173641558422, 6241.750329986749, 6242.625442473466, 6315.210598675166, 6393.424591786761, 6936.132983377078, 6944.583325000516, 6945.40964018054, 6948.756793512304, 6965.477589180831, 6518.706658200393, 6583.440407565315, 6592.571482853599, 6596.0, 6598.406143447113, 6725.092112564839, 6729.39435450802, 6744.966275168691, 6752.189737231465, 6763.628000460022, 6685.899711504331, 6691.899621505681, 6701.832454188684, 6706.664666766642, 6717.496187785838, 6717.1267735194115, 6717.899231511531, 6719.798406047827, 6750.797476075725, 6760.1549806274215, 4790.664653474293, 5412.052890744183, 5450.373207081237, 5482.396936337, 5483.945160548423, 4608.262677971508, 4630.305454181832, 4640.071985602893, 4725.4565724941385, 4736.597389221886, 5798.043322851682, 5851.0, 5863.413658634103, 5914.378990206053, 5916.7337469813965, 6826.317368263134, 6832.419244364185, 6848.486363522756, 6850.965745171242, 6855.725770969106, 5838.12428135783, 5853.268341457318, 5859.414058594191, 5872.383399743051, 5877.971355012855, 5917.585768996215, 5919.1713160157615, 5993.370696076936, 6003.219581454383, 6005.969970150121, 6493.512986525935, 6494.350564943468, 6592.538522303393, 6592.80221593353, 6593.868122637617, 6656.400923916871, 6747.055412242388, 6749.257581666111, 6830.385265326145, 6835.282295359115, 6527.249366322933, 6549.901751473636, 6625.370589793962, 6631.734730610721, 6645.9003114954285, 6671.66641667905, 6725.327467253432, 6733.79798606023, 6737.898931515933, 6762.459003279824, 6799.082123913208, 6824.863502729819, 6833.521653484208, 6895.482838787211, 6911.0, 6445.677716114082, 6567.671616419065, 6576.046473261446, 6656.201255849329, 6677.565958212701, 6550.901736473861, 6558.934410655832, 6565.638889860912, 6567.605943643397, 6573.704183311666, 6480.254770701241, 6485.837854053686, 6495.1556297681, 6497.0, 6505.41451269388, 5784.566157538117, 6533.444657204191, 6629.801105966829, 6634.867302653824, 6637.734490620321, 6451.70967306463, 6452.322506136788, 6477.222733272038, 6549.345065493412, 6557.901631475436], "value": 6150.212184976409, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.7183228121737, 817.8200795824916, 838.2539539809569, 858.9141085891412, 866.761640548849, 42132.578674213284, 42939.78530107349, 43054.56945430553, 43062.784686076564, 43234.567654323444, 38344.0, 38499.0, 38549.61450385495, 38947.61052389482, 38950.4157437638, 6729.158855143095, 6810.3189681031745, 6828.556143850634, 6836.726530938757, 6850.725970961155, 6750.696218670171, 6830.453563714916, 6851.143607049119, 6855.657217139148, 6864.41652459539, 7531.887021694679, 7627.199144089874, 7627.923720762777, 7677.923220767804, 7696.846063078735, 7593.696252149907, 7659.731909383173, 7666.195049519832, 7667.118281397655, 7676.577788221645, 7524.172341042481, 7537.133229678548, 7568.659410326549, 7660.578668173248, 7663.5785031823225, 39138.21723565513, 39476.21047579032, 39592.60407395918, 39708.40437393441, 39714.0, 3458.1354661334667, 3471.045462497808, 3481.147118955872, 3481.1645205150835, 3487.232808782064, 31723.84138079318, 31904.361912761848, 31924.0, 32008.679913200802, 32071.679283207104, 5013.473585273567, 5066.240063990394, 5090.211017292326, 5099.286099946011, 5102.770375333119, 198083.0, 198910.99999999854, 199653.0, 199805.0, 200516.0, 50429.0, 50451.0, 50468.74765626148, 50515.0, 50645.0, 51908.740456298234, 52060.47939520632, 52140.0, 52199.0, 52301.47698523042, 16529.090900000614, 16840.494785156465, 16851.41020064292, 16865.578360541083, 16897.408590699266, 13842.515511957234, 13864.653383665287, 13875.583732488041, 13876.306184690928, 13876.93061534706, 14938.551843444715, 15069.698606027818, 15090.849091509164, 15150.393984240727, 15273.465428710164, 5631.746571404296, 5825.533957283407, 5846.479915222073, 5989.401059894063, 6000.639961602318, 976.8192884316283, 1016.8118898003893, 1022.5347466902541, 1032.4992378696327, 1035.4253389368973, 23651.645225321634, 30046.54930176049, 30088.548671769942, 30145.246368840955, 30884.53673194904, 1104.3539529375246, 1109.1293334732359, 1112.6828853776797, 1118.7147277444199, 1119.8264269038311, 12830.80753788694, 16509.0, 16623.6675266494, 16624.00255984645, 16853.578660533338, 258655.0, 260357.0, 260926.0, 261165.0, 263201.0, 50197.49802502001, 51096.0, 58581.70709146512, 58852.11721824176, 60010.69994650086, 1475.0, 1481.2963842175095, 1495.5513345996158, 1498.595379247597, 1502.6243439140214, 27261.0, 27722.584161237195, 28378.00676976296, 28681.569776453787, 28742.281442964093, 39966.2006759869, 39970.0, 40267.597324027556, 40276.0, 40301.39547906842, 226446.0, 229502.0, 229590.0, 229994.0, 230295.0, 33679.831600842816, 34495.482567761, 34519.30961380708, 35000.0, 35070.29859402747, 43548.78225608852, 43931.34102988394, 44230.11539769251, 44243.0, 44425.33361999508, 31986.6801331993, 32091.518627221078, 32211.0, 32319.192020199684, 32324.83837580797, 31294.37411251808, 31477.213069672525, 31639.0, 31706.365872681956, 32304.35391292208, 35144.47283290804, 35298.0, 35305.47041794426, 35480.645193548764, 35555.288894221456, 30669.53995690022, 30756.692433075383, 31215.687843122185, 31328.53007204848, 31599.84200078985, 22383.9927203273, 23922.760772392052, 23953.520929580962, 24067.518649627258, 24177.0], "value": 17680.981938484216, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.7819628541155, 574.6638216643263, 580.7502773807266, 587.4859497939303, 590.2032256453787, 21586.460338491546, 22638.660420093693, 22798.65802012971, 22800.20199293025, 22815.42961425965, 24045.15841945532, 25083.37291567712, 26439.471210575775, 26466.86766566169, 26494.470110597777, 6481.935180648192, 6654.6672666366485, 6655.966720166392, 6656.93343066568, 6666.733330666768, 5940.732667029973, 6461.547691661585, 6525.119108920282, 6576.9148090564995, 6581.670916454182, 6415.101885736, 6422.678866056702, 6423.903641445382, 6424.100625912376, 6424.38968298011, 6406.327335629761, 6415.550911436178, 6420.422162005395, 6426.839329016789, 6428.8071357859335, 5937.554683398719, 5970.13433052205, 5977.432143946318, 5986.102084687288, 6007.128966299862, 28227.57658635122, 28549.429011419863, 28883.0, 28919.56620650692, 30319.24201894948, 1858.1081081081022, 1912.5792325688465, 1914.9138288776899, 1915.186045930474, 1916.0802814648907, 18904.62190756177, 19590.608187836307, 19949.9002504988, 19979.800201998085, 19993.800061999485, 2386.53462574798, 2436.196055301746, 2446.2416650838295, 2447.8041756659422, 2451.742567030472, 131617.0, 134344.0, 135691.0, 136050.0, 136295.31852340774, 14630.780538291934, 15423.161194044633, 15527.767083493756, 15584.532464026097, 15879.52361429159, 24338.0, 24403.511929761306, 24416.26751197467, 24453.510929781303, 24475.632865507032, 10689.9465502672, 10850.67447976562, 10856.45717714127, 10946.233763636468, 10975.341479511255, 10143.644972426076, 10147.238957078249, 10155.492225388702, 10185.08334249921, 10312.742181445525, 9154.31342649304, 9419.728336674461, 9662.855057174149, 9716.757081072888, 9828.803423931624, 2744.4785490756694, 2774.500589893798, 2791.749250520406, 2804.789640776909, 2872.8850845966344, 951.3720944176948, 980.0836218136066, 984.3159004491953, 985.3348989432104, 1001.1390204424189, 19716.42268618507, 19731.0, 19820.70268945967, 20213.797862021485, 20449.0, 977.9657711980046, 997.4065431068502, 1001.1990407673832, 1004.3321191407632, 1020.8570800087994, 11189.552417903193, 11338.60314888975, 11547.076233901267, 11615.6515304541, 13630.386632601358, 529056.0, 544265.0, 547151.0, 560371.0, 566162.0, 126245.36877315554, 127500.0, 130690.34654826856, 131076.3446182763, 131208.0, 1262.7663882181826, 1273.6688461000022, 1306.5296493262417, 1310.8951283897266, 1313.0480401708685, 21576.892115539322, 21673.241436549644, 21695.0, 21819.23632672849, 22008.779912200673, 58744.0, 58774.0, 59759.70120149371, 60074.39925600688, 60435.39564604474, 78400.64798056068, 78593.0, 78606.6069669648, 79047.60476197582, 79661.60169199116, 14827.703445930805, 14931.626709331918, 15098.320575573905, 15174.165420901956, 15176.0, 23194.88402558044, 23321.416964576023, 23481.882590586938, 23492.76507234906, 23641.52716945617, 18094.81905180984, 18858.434246972614, 18930.716039259147, 18969.43091707251, 19117.61764764669, 16697.666046678754, 16750.58123546921, 17040.74438883393, 17329.133543323034, 17401.0, 18482.907585461988, 18727.90636046811, 18997.525061873563, 19149.234030638618, 19295.324663636704, 14778.778318325449, 14841.480548180762, 14855.108693478425, 14865.0, 14882.10707357562, 17648.470545883643, 18503.53741156482, 18525.0737463129, 18536.258549658403, 18583.44249672512], "value": 12312.218774941111, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1335.7795963665997, 1363.1275983370651, 1364.2196933002433, 1378.0766886186261, 1378.7587172244857, 13630.182189068662, 14227.78658320126, 14307.71384572308, 14340.569782906516, 14357.425702971868, 14317.570472885804, 14661.486847960323, 14699.338529766159, 14746.115233086022, 14802.037867538602, 2594.584866421373, 2629.0929629277916, 2652.655154829879, 2656.9070082547114, 2658.694250161237, 2716.1579910227792, 2778.402643431662, 2789.9581506277423, 2804.354998350381, 2805.8737356818892, 2609.921702348923, 2656.335916020995, 2660.414708764069, 2660.946781064378, 2661.3213630524187, 2483.788877945377, 2504.887280072401, 2571.8585477798715, 2599.376149724062, 2639.7492238237337, 2423.9757602423924, 2569.1907049279416, 2574.4979728952862, 2606.1660268714018, 2608.6608740863758, 13763.862361376358, 13768.724625507533, 13790.0, 13801.723965520634, 13803.309834508334, 7640.0, 7957.244061814118, 7974.082980457264, 7981.361491080757, 8019.839603207962, 5003.2995380646735, 5126.436092029911, 5185.585153187736, 5253.789848406059, 5273.815416460444, 1815.6836293687104, 1840.3558754435974, 1861.757971463701, 1879.9436016919378, 1884.6984482482737, 22635.77364226353, 22758.203462878886, 23302.53394932109, 23374.766252337427, 24010.279691609107, 7059.752908648173, 7110.32451917067, 7147.2587762891435, 7163.391111755455, 7304.780856574312, 18202.180901859447, 18203.45389638313, 18223.088845557933, 18288.72566911471, 18326.63346733058, 7555.773326800205, 7826.060872695313, 7889.289963903161, 7891.487053341514, 7917.960410198028, 6955.547889387173, 7042.154941407064, 7070.36366726997, 7086.291370862872, 7186.10173728284, 5454.972725136349, 5560.304961879765, 5598.160275958558, 5640.605157639008, 5688.288963879515, 1766.222861940742, 1812.2750899639987, 1817.5092724964186, 1842.474894655017, 1844.8524118070522, 900.2662829793697, 910.3445519226146, 914.9085091490931, 921.1847514949318, 922.9400088994194, 7433.442491813135, 7447.180810110884, 7490.475666703279, 7539.585322807298, 7547.16981132075, 705.3898377903076, 732.5568629799287, 742.6620887496176, 743.5427212264418, 747.4356860570234, 4634.0, 4949.158643030693, 5209.843704688866, 5258.290130832365, 5542.612017158841, 18667.439976800717, 18849.24603015864, 19219.519512012033, 19226.134823932956, 19560.510987225432, 18980.240790368505, 19095.427137185907, 19264.422067338, 19267.22931082769, 19301.131449084824, 10213.693589192188, 10339.896601034045, 10610.57557697699, 10947.124230061576, 10999.835002474969, 7615.847683046197, 7810.0, 7930.3973664944, 8078.192180781874, 8114.5942702865805, 16600.252988615783, 16882.577935551217, 17059.573510661838, 17240.91379543094, 17398.73901891447, 16880.240389182272, 16969.74545381845, 17139.742903856204, 17505.0, 17670.82329176692, 12887.291198984145, 13142.540011099565, 13155.868441315464, 13179.0, 13221.471141154247, 12677.366131693194, 12696.74606507883, 12709.618711438672, 12716.74566508646, 12773.61679149627, 10347.43089130105, 10498.737531561772, 10544.947275263574, 10654.094401975608, 10799.244052916221, 9817.803643927224, 10146.594136234467, 10329.018743219382, 10508.316959397614, 10712.35725856451, 8809.207171354608, 8945.59744811498, 8964.462132272085, 8979.50612716307, 9016.323775716979, 7369.410447164107, 7371.889421658787, 7424.591647459441, 7433.479656424215, 7520.661570229244, 7522.172561018174, 7540.3213710766295, 7601.847963040597, 7624.199459056719, 7729.304362607392], "value": 6447.133794458162, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.82291786415846, 509.462517044518, 527.8310940499042, 537.0574641504162, 539.1454544546893, 6160.907586386208, 6204.379562043793, 6206.286277078132, 6254.7498100076, 6276.65478398689, 5918.792842250522, 6089.634621922688, 6107.786227482039, 6112.083187521874, 6121.632702037881, 4876.390451193601, 4929.926051109236, 4940.25896115582, 4948.2824990376375, 4948.653594248404, 4909.533594308535, 4924.852254432372, 4952.479989601094, 4962.255661650764, 4986.576141027999, 4784.569388755029, 4843.031393721252, 4871.634627402959, 4907.460179380265, 4917.877053073684, 4867.805287788484, 4922.778474968635, 4927.1870141426625, 4941.654084214107, 4946.505349465061, 4440.511543730187, 4442.355858400514, 4442.711223770477, 4459.665525085599, 4461.553844615545, 6036.396360363972, 6053.606515576517, 6066.939330606681, 6067.848303792396, 6070.696465176724, 5690.857728556778, 5700.2589663343915, 5702.173184888168, 5703.4011428799795, 5728.770849166029, 6521.608703477807, 6538.574992625464, 6553.6395498247575, 6560.835979100513, 6567.211934567835, 4824.710517368969, 4842.564169224786, 4850.6362022848425, 4876.609871210295, 4881.438634557036, 6816.454683625348, 6910.7581234656545, 6917.342852429011, 6917.7232910683515, 6924.065251191126, 4851.466338702739, 4935.802567897315, 4945.908806995628, 4975.154223781966, 4993.176125939252, 5007.197408932781, 5051.292819005343, 5060.620453466005, 5071.518205770446, 5088.847334579968, 5648.350439699487, 5666.943330566724, 5677.120046392815, 5679.545636349082, 5684.005299072645, 6512.023196520559, 6549.639769812611, 6563.540552161303, 6570.310117437601, 6621.437177839937, 1655.6605895791574, 1720.8537274331572, 1728.567858035491, 1761.1722490429477, 1763.206557049333, 1382.2743059893435, 1444.2995147353524, 1444.6893917807722, 1450.100937418807, 1473.6168596165073, 5636.0, 5710.314762228559, 5712.600117991784, 5724.227229324071, 5759.884802303931, 6539.476841852736, 6653.833654158588, 6685.328667833041, 6691.26396096429, 6735.696893639798, 5699.145128230715, 5723.112917497794, 5754.913676294859, 5764.097847710245, 5773.422657734193, 3501.124718820295, 3536.5932917714276, 3568.3576956148145, 3587.461880717913, 3632.3461776879885, 6507.446867016357, 6514.73941042353, 6516.380943810331, 6537.5750576212395, 6539.280679125292, 6849.39039325758, 6861.039454476378, 6864.519483636197, 6921.965390173118, 6937.802629409236, 6423.132877061536, 6500.772472963424, 6517.674116294072, 6535.607863528204, 6569.507286953498, 6649.534532582867, 6649.634270114997, 6694.297098804555, 6744.5616034957575, 6795.38841504267, 6599.835004124935, 6658.866822663423, 6659.800205993828, 6736.5958042517605, 6738.35985581369, 6452.0, 6461.256955450183, 6485.902711459426, 6487.18910136233, 6548.1814773153355, 4940.876478087933, 4952.356193694796, 4973.801047958186, 4986.451490336132, 5011.5489605935645, 6661.600303981777, 6683.131192944886, 6686.53194276396, 6721.395074443323, 6800.693968771318, 6417.839554011187, 6497.415232628897, 6521.608703477807, 6528.37980391862, 6556.8360790980605, 6417.647029413427, 6446.194225721785, 6465.806025819233, 6469.6090340577, 6476.579022363531, 6071.423214794588, 6117.3576774439825, 6294.622322660655, 6305.653189074645, 6349.746010159542, 6407.615543067431, 6475.0, 6481.643509607017, 6500.902486462802, 6527.934720652733, 6190.566660333733, 6258.874822503615, 6279.461531924708, 6306.180196574416, 6310.810675679737], "value": 5051.644495718836, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.7165078048079, 831.4221615976895, 846.5809424334955, 902.2601466797224, 909.354358405532, 18918.716219256723, 19067.0, 19234.519137021565, 19363.709544356847, 20869.37391878242, 19641.116149773257, 19645.50886227845, 19704.211831526758, 19822.70265946012, 19834.107465164067, 984.8965858584853, 1012.7518757904287, 1014.761531040205, 1015.0154350280217, 1015.8019186258682, 1045.028123844824, 1056.461204785561, 1056.5245639462235, 1056.6248981611532, 1057.2704833664784, 965.5944503308592, 1007.652359935821, 1022.1924679503184, 1045.0646671229244, 1058.0688993685585, 1044.5508431374506, 1047.5704960966004, 1053.1522124689623, 1053.4311471805293, 1053.9104176145074, 763.5571368606195, 764.5412752348602, 772.0233904111303, 772.9884051739228, 773.6131934032984, 17666.11669416537, 18154.636907261913, 18264.36074737391, 18483.353082642043, 18649.90675046616, 20033.0, 20311.086001129985, 20313.187472501082, 20579.48551286215, 20603.381898543066, 16382.098984555845, 16628.750568741478, 16791.0, 16791.32834686611, 16814.495565133064, 3668.192997540537, 3673.7612055216327, 3687.7418580699364, 3688.6495782900583, 3702.129999450127, 124837.0, 126680.0, 127267.3636631811, 128522.3573882134, 129443.0, 15794.763078553831, 15990.520284391487, 16457.753133703005, 16459.177041147894, 16507.752383714254, 29999.0, 30482.0, 30507.2373190672, 30529.69470305313, 30657.08028759141, 7509.0, 7634.427417943565, 8160.102388737234, 8978.640854365884, 9314.347995640375, 4233.343831706091, 4338.305871060616, 4345.087531618332, 4346.434963454731, 4350.60844523988, 5205.7397130143345, 5272.841814745564, 5272.868178295574, 5290.497402746733, 5360.892782144335, 1089.9400532970603, 1103.9282446640943, 1110.1396417776289, 1114.643314139484, 1118.5022664914036, 674.7672053141658, 687.8280429892527, 688.0779755128052, 693.2893783871572, 708.8475977664826, 12051.879481205251, 12126.393680315949, 12478.126531142732, 12665.303408312448, 12866.935665321613, 664.8271449423088, 667.9833004174934, 682.4642655515397, 695.7390978383106, 716.3409663109951, 5643.209950606918, 5708.143778433268, 5726.312842458931, 5739.8565035874435, 5863.208466857005, 102076.48961755293, 105397.0, 105922.0, 106102.93897061085, 106860.46569767101, 92939.0, 94690.0, 95402.52298738601, 101138.49430752799, 101231.0, 77077.22922770813, 79719.0, 80516.0, 81556.0, 82266.17733822705, 10686.73283167927, 10856.945715271373, 10879.728006799893, 11231.943840280746, 11234.662960111209, 31984.84007579947, 32823.67176328302, 33018.33963320672, 34716.0, 34867.47698784571, 43961.0, 44173.55826441823, 44315.55684443243, 44808.32787508255, 45876.77061614671, 16277.75583366274, 16465.83534164643, 16577.50267491977, 16711.24799384055, 16858.578535536708, 17661.205245763715, 20266.18935242633, 20661.58676826485, 20779.68830467514, 21115.57768844584, 18016.189271483076, 18031.81968180354, 18222.271109155485, 18407.90796046011, 18438.72341914899, 16753.162341882613, 16864.988100713996, 16878.83121168822, 17008.319667213174, 17056.744148838025, 18328.17523211432, 18412.631747365245, 18417.0, 18725.438236852915, 18739.437816865517, 10096.798064038825, 10137.442440665835, 10360.948195258976, 10574.735631609272, 10634.840477393, 13802.378892950042, 14200.92899535537, 14266.42934282617, 14439.278036098363, 14816.481423150139], "value": 8830.077067616296, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 100.0, 107.0, 108.0, 108.0, 109.0, 99.0, 106.0, 109.0], "value": 105.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 124.0, 145.0, 181.0, 116.0, 146.0, 162.0, 121.0, 168.0, 125.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 164.0, 162.0, 163.0, 166.0, 166.0, 165.0, 171.0, 164.0], "value": 165.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 173.0, 77.0, 75.0, 74.0, 74.0, 172.0, 77.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 128.0, 79.0, 80.0, 79.0, 126.0, 79.0, 77.0, 127.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 115.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 150.0, 154.0, 152.0, 151.0, 155.0, 150.0, 152.0, 151.0], "value": 151.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 96.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 54.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 53.0, 52.0, 55.0, 54.0, 53.0, 53.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 88.0, 79.0, 80.0, 78.0, 89.0, 77.0, 87.0, 79.0, 80.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 137.0, 127.0, 136.0, 126.0, 144.0, 135.0, 129.0, 135.0, 164.0], "value": 136.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 178.0, 190.0, 190.0, 193.0, 182.0, 184.0, 185.0, 187.0, 176.0], "value": 183.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 65.0, 67.0, 67.0, 68.0, 65.0, 73.0, 66.0, 71.0], "value": 67.6, "unit": "score"}]}]} 05:25:05 INFO - Return code: 0 05:25:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:25:05 INFO - # TBPL SUCCESS # 05:25:05 INFO - Running post-action listener: _package_coverage_data 05:25:05 INFO - Running post-action listener: _resource_record_post_action 05:25:05 INFO - [mozharness: 2017-08-08 12:25:05.440873Z] Finished run-tests step (success) 05:25:05 INFO - Running post-run listener: _resource_record_post_run 05:25:05 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 446754816; Read time: 16; Write time: 289420 05:25:05 INFO - TinderboxPrint: CPU usage
13.1% 05:25:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 05:25:05 INFO - TinderboxPrint: I/O write bytes / time
446,754,816 / 289,420 05:25:05 INFO - TinderboxPrint: CPU idle
8,260.6 (86.8%) 05:25:05 INFO - TinderboxPrint: CPU user
1,203.4 (12.7%) 05:25:05 INFO - TinderboxPrint: Swap in / out
0 / 0 05:25:05 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 121131008; Read time: 0; Write time: 111112 05:25:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:25:05 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 325623808; Read time: 0; Write time: 178308 05:25:06 INFO - Running post-run listener: _upload_blobber_files 05:25:06 INFO - Blob upload gear active. 05:25:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:25:06 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:25:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:25:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:25:06 INFO - (blobuploader) - INFO - Open directory for files ... 05:25:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:25:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:25:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:07 INFO - (blobuploader) - INFO - Done attempting. 05:25:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:25:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:25:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:13 INFO - (blobuploader) - INFO - Done attempting. 05:25:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:25:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:25:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:14 INFO - (blobuploader) - INFO - Done attempting. 05:25:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:25:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:25:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:15 INFO - (blobuploader) - INFO - Done attempting. 05:25:15 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:15 INFO - Return code: 0 05:25:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:15 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe798b4edd39e0be1b2c5cd7a2c688b69ce53c75025ab9ca2961165d77c814d15920a041eb40d2945a181677c8f0e48fc9d41f00b75736a2b6aaf2016ff7285d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff4f3be474c941d286770001798939f54b62d439fecaae49955ad28300ebc158891c48bb0703391681e9f83e811c1513d6c627343fcf2f324eadc0c4fe2d804f", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7f5606bd914b37084dae4a09222c8b3660eeb249dc325bf2eb0e77a6170d2f90a0d8736940134986de7adc7708d3fc8323b534a061c529aaae7492bed01e3f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fdb694c9cbd91362dd9e7a38580a3b5476f7325fd4d4112835d2db75e70fc552b822283d4a48ec6573379135993727ea9e5b3e01931113a6b00f39097aabeeb1"} 05:25:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:15 INFO - Contents: 05:25:15 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe798b4edd39e0be1b2c5cd7a2c688b69ce53c75025ab9ca2961165d77c814d15920a041eb40d2945a181677c8f0e48fc9d41f00b75736a2b6aaf2016ff7285d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff4f3be474c941d286770001798939f54b62d439fecaae49955ad28300ebc158891c48bb0703391681e9f83e811c1513d6c627343fcf2f324eadc0c4fe2d804f", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7f5606bd914b37084dae4a09222c8b3660eeb249dc325bf2eb0e77a6170d2f90a0d8736940134986de7adc7708d3fc8323b534a061c529aaae7492bed01e3f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fdb694c9cbd91362dd9e7a38580a3b5476f7325fd4d4112835d2db75e70fc552b822283d4a48ec6573379135993727ea9e5b3e01931113a6b00f39097aabeeb1"} 05:25:15 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:15 INFO - Copying logs to upload dir... 05:25:15 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.089403 ========= master_lag: -0.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-08-08 05:25:14.990743) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 05:25:14.995086) ========= 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=9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe798b4edd39e0be1b2c5cd7a2c688b69ce53c75025ab9ca2961165d77c814d15920a041eb40d2945a181677c8f0e48fc9d41f00b75736a2b6aaf2016ff7285d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff4f3be474c941d286770001798939f54b62d439fecaae49955ad28300ebc158891c48bb0703391681e9f83e811c1513d6c627343fcf2f324eadc0c4fe2d804f", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7f5606bd914b37084dae4a09222c8b3660eeb249dc325bf2eb0e77a6170d2f90a0d8736940134986de7adc7708d3fc8323b534a061c529aaae7492bed01e3f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fdb694c9cbd91362dd9e7a38580a3b5476f7325fd4d4112835d2db75e70fc552b822283d4a48ec6573379135993727ea9e5b3e01931113a6b00f39097aabeeb1"} build_url:https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011423 build_url: 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fe798b4edd39e0be1b2c5cd7a2c688b69ce53c75025ab9ca2961165d77c814d15920a041eb40d2945a181677c8f0e48fc9d41f00b75736a2b6aaf2016ff7285d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff4f3be474c941d286770001798939f54b62d439fecaae49955ad28300ebc158891c48bb0703391681e9f83e811c1513d6c627343fcf2f324eadc0c4fe2d804f", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7f5606bd914b37084dae4a09222c8b3660eeb249dc325bf2eb0e77a6170d2f90a0d8736940134986de7adc7708d3fc8323b534a061c529aaae7492bed01e3f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fdb694c9cbd91362dd9e7a38580a3b5476f7325fd4d4112835d2db75e70fc552b822283d4a48ec6573379135993727ea9e5b3e01931113a6b00f39097aabeeb1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 05:25:15.022908) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:25:15.023236) ========= 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=9f7544b6a75ada7b6b576032000001a7-1502193857.998478-1525836698 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:25:15.103603) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 05:25:15.106959) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 05:25:15.107247) ========= ========= Total master_lag: -0.12 =========