builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-006 starttime: 1494605353.15 results: success (0) revision: bc096a7e31e236620e40d1dc94e0f4b4af63f75c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:13.155147) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:13.156393) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:13.158709) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:13.197955) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:13.198256) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-12 09:09:13-- 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% 45.9M=0s 2017-05-12 09:09:13 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152421 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:13.364560) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:13.364899) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021144 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:13.418239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:13.420591) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bc096a7e31e236620e40d1dc94e0f4b4af63f75c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bc096a7e31e236620e40d1dc94e0f4b4af63f75c --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=e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-12 09:09:13,520 truncating revision to first 12 chars 2017-05-12 09:09:13,520 Setting DEBUG logging. 2017-05-12 09:09:13,520 attempt 1/10 2017-05-12 09:09:13,520 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bc096a7e31e2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-12 09:09:14,033 unpacking tar archive at: mozilla-beta-bc096a7e31e2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.922277 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:14.360185) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:14.360506) ========= script_repo_revision: bc096a7e31e236620e40d1dc94e0f4b4af63f75c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:14.360881) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:14.361236) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 09:09:14.377460) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-05-12 09:09:14.380764) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-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=e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:09:14 INFO - MultiFileLogger online at 20170512 09:09:14 in /builds/slave/test 09:09:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 09:09:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:09:14 INFO - {'append_to_log': False, 09:09:14 INFO - 'base_work_dir': '/builds/slave/test', 09:09:14 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 09:09:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:09:14 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 09:09:14 INFO - 'buildbot_json_path': 'buildprops.json', 09:09:14 INFO - 'config_files': ('talos/linux_config.py',), 09:09:14 INFO - 'default_actions': ('clobber', 09:09:14 INFO - 'read-buildbot-config', 09:09:14 INFO - 'download-and-extract', 09:09:14 INFO - 'populate-webroot', 09:09:14 INFO - 'create-virtualenv', 09:09:14 INFO - 'install', 09:09:14 INFO - 'run-tests'), 09:09:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:09:14 INFO - 'download_minidump_stackwalk': True, 09:09:14 INFO - 'download_symbols': 'ondemand', 09:09:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:09:14 INFO - 'tooltool.py': '/tools/tooltool.py', 09:09:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:09:14 INFO - '/tools/misc-python/virtualenv.py')}, 09:09:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:09:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:09:14 INFO - 'gecko_profile': False, 09:09:14 INFO - 'gecko_profile_interval': 0, 09:09:14 INFO - 'installer_path': 'installer.exe', 09:09:14 INFO - 'log_level': 'info', 09:09:14 INFO - 'log_name': 'talos', 09:09:14 INFO - 'log_to_console': True, 09:09:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:09:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:09:14 INFO - 'opt_config_files': (), 09:09:14 INFO - 'pip_index': False, 09:09:14 INFO - 'suite': 'dromaeojs', 09:09:14 INFO - 'system_bits': '32', 09:09:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:09:14 INFO - 'title': 'talos-linux64-ix-006', 09:09:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:09:14 INFO - 'use_talos_json': True, 09:09:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:09:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:09:14 INFO - 'work_dir': 'build'} 09:09:14 INFO - [mozharness: 2017-05-12 16:09:14.583025Z] Running clobber step. 09:09:14 INFO - Running pre-action listener: _resource_record_pre_action 09:09:14 INFO - Running main action method: clobber 09:09:14 INFO - rmtree: /builds/slave/test/build 09:09:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:09:15 INFO - Running post-action listener: _resource_record_post_action 09:09:15 INFO - [mozharness: 2017-05-12 16:09:15.589233Z] Finished clobber step (success) 09:09:15 INFO - [mozharness: 2017-05-12 16:09:15.589378Z] Running read-buildbot-config step. 09:09:15 INFO - Running pre-action listener: _resource_record_pre_action 09:09:15 INFO - Running main action method: read_buildbot_config 09:09:15 INFO - Using buildbot properties: 09:09:15 INFO - { 09:09:15 INFO - "project": "", 09:09:15 INFO - "product": "firefox", 09:09:15 INFO - "who": "ffxbld@noreply.mozilla.org", 09:09:15 INFO - "installer_path": "public/build/target.tar.bz2", 09:09:15 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:09:15 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 09:09:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 09:09:15 INFO - "stage_platform": "linux64", 09:09:15 INFO - "basedir": "/builds/slave/test", 09:09:15 INFO - "buildnumber": 41, 09:09:15 INFO - "platform": "ubuntu64_hw", 09:09:15 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:09:15 INFO - "slavebuilddir": "test", 09:09:15 INFO - "taskId": "TKuRvDLpR2CoAoq2DzrOOw", 09:09:15 INFO - "branch": "mozilla-beta", 09:09:15 INFO - "script_repo_revision": "production", 09:09:15 INFO - "revision": "bc096a7e31e236620e40d1dc94e0f4b4af63f75c", 09:09:15 INFO - "slavename": "talos-linux64-ix-006", 09:09:15 INFO - "repo_path": "releases/mozilla-beta" 09:09:15 INFO - } 09:09:15 INFO - Finding installer, test and symbols from parent task. 09:09:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TKuRvDLpR2CoAoq2DzrOOw'}, attempt #1 09:09:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TKuRvDLpR2CoAoq2DzrOOw'}, attempt #1 09:09:16 INFO - Task dependencies: aWfPAGdVT5Ky2X_YUeRbNA 09:09:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA'}, attempt #1 09:09:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.tar.bz2 09:09:16 INFO - Set test_url: https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/test_packages.json 09:09:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.crashreporter-symbols.zip 09:09:16 INFO - Running post-action listener: _resource_record_post_action 09:09:16 INFO - [mozharness: 2017-05-12 16:09:16.949156Z] Finished read-buildbot-config step (success) 09:09:16 INFO - [mozharness: 2017-05-12 16:09:16.949416Z] Running download-and-extract step. 09:09:16 INFO - Running pre-action listener: _resource_record_pre_action 09:09:16 INFO - Running main action method: download_and_extract 09:09:16 INFO - mkdir: /builds/slave/test/build/tests 09:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:16 INFO - trying https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.test_packages.json 09:09:16 INFO - Downloading https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:09:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:09:17 INFO - Downloaded 986 bytes. 09:09:17 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:09:17 INFO - Using the following test package requirements: 09:09:17 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:09:17 INFO - u'common': [u'target.common.tests.zip'], 09:09:17 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:09:17 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:09:17 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:09:17 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:09:17 INFO - u'mozbase': [u'target.common.tests.zip'], 09:09:17 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:09:17 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:09:17 INFO - u'web-platform': [u'target.common.tests.zip', 09:09:17 INFO - u'target.web-platform.tests.zip'], 09:09:17 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:09:17 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:09:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.common.tests.zip 09:09:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:09:17 INFO - Fetch https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.common.tests.zip into memory 09:09:18 INFO - Expected file size: 59546641 09:09:18 INFO - Obtained file size: 59546641 09:09:28 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:09:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.common.tests.zip 09:09:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:09:28 INFO - Fetch https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.common.tests.zip into memory 09:09:29 INFO - Expected file size: 59546641 09:09:29 INFO - Obtained file size: 59546641 09:09:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.talos.tests.zip 09:09:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:09:40 INFO - Fetch https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.talos.tests.zip into memory 09:09:40 INFO - Expected file size: 14404449 09:09:40 INFO - Obtained file size: 14404449 09:09:41 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')]} 09:09:41 INFO - trying https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.tar.bz2 09:09:41 INFO - Downloading https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:09:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:09:42 INFO - Downloaded 59745520 bytes. 09:09:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.tar.bz2 09:09:42 INFO - mkdir: /builds/slave/test/properties 09:09:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:09:42 INFO - Writing to file /builds/slave/test/properties/build_url 09:09:42 INFO - Contents: 09:09:42 INFO - build_url:https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.tar.bz2 09:09:42 INFO - Running post-action listener: _resource_record_post_action 09:09:42 INFO - Running post-action listener: set_extra_try_arguments 09:09:42 INFO - [mozharness: 2017-05-12 16:09:42.350753Z] Finished download-and-extract step (success) 09:09:42 INFO - [mozharness: 2017-05-12 16:09:42.350888Z] Running populate-webroot step. 09:09:42 INFO - Running pre-action listener: _resource_record_pre_action 09:09:42 INFO - Running main action method: populate_webroot 09:09:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:09:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:09:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:09:42 INFO - '--tppagecycles', 09:09:42 INFO - '7'], 09:09:42 INFO - 'tests': ['tsvgm']}}, 09:09:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:09:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:09:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:09:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:09:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:09:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:09:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:09:42 INFO - 'talos_options': ['--disable-e10s'], 09:09:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:09:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:09:42 INFO - 'talos_options': ['--disable-e10s'], 09:09:42 INFO - 'tests': ['damp', 'tps']}, 09:09:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:09:42 INFO - 'tests': ['damp', 'tps']}, 09:09:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:09:42 INFO - 'tests': ['dromaeo_dom']}, 09:09:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:09:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:09:42 INFO - 'tests': ['basic_compositor_video']}, 09:09:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:09:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:09:42 INFO - 'tests': ['a11yr', 09:09:42 INFO - 'ts_paint', 09:09:42 INFO - 'tpaint', 09:09:42 INFO - 'sessionrestore', 09:09:42 INFO - 'sessionrestore_no_auto_restore', 09:09:42 INFO - 'tabpaint']}, 09:09:42 INFO - 'other-e10s': {'tests': ['a11yr', 09:09:42 INFO - 'ts_paint', 09:09:42 INFO - 'tpaint', 09:09:42 INFO - 'sessionrestore', 09:09:42 INFO - 'sessionrestore_no_auto_restore', 09:09:42 INFO - 'tabpaint']}, 09:09:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:09:42 INFO - 'ts_paint', 09:09:42 INFO - 'tpaint', 09:09:42 INFO - 'sessionrestore', 09:09:42 INFO - 'sessionrestore_no_auto_restore', 09:09:42 INFO - 'tabpaint']}, 09:09:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:09:42 INFO - 'ts_paint', 09:09:42 INFO - 'tpaint', 09:09:42 INFO - 'sessionrestore', 09:09:42 INFO - 'sessionrestore_no_auto_restore', 09:09:42 INFO - 'tabpaint']}, 09:09:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:09:42 INFO - 'tests': ['a11yr', 09:09:42 INFO - 'ts_paint', 09:09:42 INFO - 'tpaint', 09:09:42 INFO - 'sessionrestore', 09:09:42 INFO - 'sessionrestore_no_auto_restore', 09:09:42 INFO - 'tabpaint']}, 09:09:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:09:42 INFO - 'tests': ['a11yr', 09:09:42 INFO - 'ts_paint', 09:09:42 INFO - 'tpaint', 09:09:42 INFO - 'sessionrestore', 09:09:42 INFO - 'sessionrestore_no_auto_restore', 09:09:42 INFO - 'tabpaint']}, 09:09:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:09:42 INFO - 'tests': ['tsvgx', 09:09:42 INFO - 'tsvgr_opacity', 09:09:42 INFO - 'tart', 09:09:42 INFO - 'tscrollx', 09:09:42 INFO - 'cart', 09:09:42 INFO - 'tsvg_static']}, 09:09:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:09:42 INFO - 'tsvgr_opacity', 09:09:42 INFO - 'tart', 09:09:42 INFO - 'tscrollx', 09:09:42 INFO - 'cart', 09:09:42 INFO - 'tsvg_static']}, 09:09:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:09:42 INFO - 'talos_options': ['--disable-e10s'], 09:09:42 INFO - 'tests': ['tp5o']}, 09:09:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:09:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:09:42 INFO - 'talos_options': ['--disable-e10s', 09:09:42 INFO - '--xperf_path', 09:09:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:42 INFO - 'tests': ['tp5n']}, 09:09:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:09:42 INFO - 'talos_options': ['--xperf_path', 09:09:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:42 INFO - 'tests': ['tp5n']}}, 09:09:42 INFO - 'talos.zip': {'path': '', 09:09:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:09:42 INFO - Running post-action listener: _resource_record_post_action 09:09:42 INFO - [mozharness: 2017-05-12 16:09:42.366116Z] Finished populate-webroot step (success) 09:09:42 INFO - [mozharness: 2017-05-12 16:09:42.366205Z] Running create-virtualenv step. 09:09:42 INFO - Running pre-action listener: _resource_record_pre_action 09:09:42 INFO - Running main action method: create_virtualenv 09:09:42 INFO - Creating virtualenv /builds/slave/test/build/venv 09:09:42 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 09:09:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:09:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:09:42 INFO - Using real prefix '/usr' 09:09:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:09:42 INFO - Installing distribute.............................................................................................................................................................................................done. 09:09:44 INFO - Installing pip.................done. 09:09:44 INFO - Return code: 0 09:09:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:09:45 INFO - Reading from file tmpfile_stdout 09:09:45 INFO - Output received: 09:09:45 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) 09:09:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:45 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 0xdbf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a400>, '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 0x105b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b840>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', '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 09:09:45 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 09:09:45 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 09:09:45 INFO - Using env: {'DISPLAY': ':0', 09:09:45 INFO - 'HOME': '/home/cltbld', 09:09:45 INFO - 'LANG': 'en_US.UTF-8', 09:09:45 INFO - 'LANGUAGE': 'en_US:en', 09:09:45 INFO - 'LOGNAME': 'cltbld', 09:09:45 INFO - 'MAIL': '/var/mail/cltbld', 09:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:45 INFO - 'MOZ_NO_REMOTE': '1', 09:09:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:45 INFO - 'NO_EM_RESTART': '1', 09:09:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:45 INFO - 'PWD': '/builds/slave/test', 09:09:45 INFO - 'SHELL': '/bin/bash', 09:09:45 INFO - 'SHLVL': '1', 09:09:45 INFO - 'TERM': 'linux', 09:09:45 INFO - 'TMOUT': '86400', 09:09:45 INFO - 'USER': 'cltbld', 09:09:45 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', 09:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:45 INFO - '_': '/tools/buildbot/bin/python'} 09:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:45 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 09:09:45 INFO - Cleaning up... 09:09:45 INFO - Return code: 0 09:09:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:45 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 0xdbf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a400>, '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 0x105b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b840>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', '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 09:09:45 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 09:09:45 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 09:09:45 INFO - Using env: {'DISPLAY': ':0', 09:09:45 INFO - 'HOME': '/home/cltbld', 09:09:45 INFO - 'LANG': 'en_US.UTF-8', 09:09:45 INFO - 'LANGUAGE': 'en_US:en', 09:09:45 INFO - 'LOGNAME': 'cltbld', 09:09:45 INFO - 'MAIL': '/var/mail/cltbld', 09:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:45 INFO - 'MOZ_NO_REMOTE': '1', 09:09:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:45 INFO - 'NO_EM_RESTART': '1', 09:09:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:45 INFO - 'PWD': '/builds/slave/test', 09:09:45 INFO - 'SHELL': '/bin/bash', 09:09:45 INFO - 'SHLVL': '1', 09:09:45 INFO - 'TERM': 'linux', 09:09:45 INFO - 'TMOUT': '86400', 09:09:45 INFO - 'USER': 'cltbld', 09:09:45 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', 09:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:45 INFO - '_': '/tools/buildbot/bin/python'} 09:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:45 INFO - Downloading/unpacking psutil>=3.1.1 09:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:09:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:46 INFO - Installing collected packages: psutil 09:09:46 INFO - Running setup.py install for psutil 09:09:46 INFO - building 'psutil._psutil_linux' extension 09:09:46 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 09:09:47 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 09:09:47 INFO - building 'psutil._psutil_posix' extension 09:09:47 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 09:09:47 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 09:09:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:47 INFO - Successfully installed psutil 09:09:47 INFO - Cleaning up... 09:09:47 INFO - Return code: 0 09:09:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a400>, '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 0x105b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b840>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', '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 09:09:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:09:47 INFO - Using env: {'DISPLAY': ':0', 09:09:47 INFO - 'HOME': '/home/cltbld', 09:09:47 INFO - 'LANG': 'en_US.UTF-8', 09:09:47 INFO - 'LANGUAGE': 'en_US:en', 09:09:47 INFO - 'LOGNAME': 'cltbld', 09:09:47 INFO - 'MAIL': '/var/mail/cltbld', 09:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:47 INFO - 'MOZ_NO_REMOTE': '1', 09:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:47 INFO - 'NO_EM_RESTART': '1', 09:09:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:47 INFO - 'PWD': '/builds/slave/test', 09:09:47 INFO - 'SHELL': '/bin/bash', 09:09:47 INFO - 'SHLVL': '1', 09:09:47 INFO - 'TERM': 'linux', 09:09:47 INFO - 'TMOUT': '86400', 09:09:47 INFO - 'USER': 'cltbld', 09:09:47 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', 09:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:47 INFO - '_': '/tools/buildbot/bin/python'} 09:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:09:47 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 09:09:47 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 09:09:47 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 09:09:47 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 09:09:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:09:48 INFO - Installing collected packages: mozsystemmonitor 09:09:48 INFO - Running setup.py install for mozsystemmonitor 09:09:48 INFO - Successfully installed mozsystemmonitor 09:09:48 INFO - Cleaning up... 09:09:48 INFO - Return code: 0 09:09:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:09:48 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')]} 09:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:48 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')]} 09:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:48 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 0xdbf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a400>, '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 0x105b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b840>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', '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 09:09:48 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 09:09:48 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 09:09:48 INFO - Using env: {'DISPLAY': ':0', 09:09:48 INFO - 'HOME': '/home/cltbld', 09:09:48 INFO - 'LANG': 'en_US.UTF-8', 09:09:48 INFO - 'LANGUAGE': 'en_US:en', 09:09:48 INFO - 'LOGNAME': 'cltbld', 09:09:48 INFO - 'MAIL': '/var/mail/cltbld', 09:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:48 INFO - 'MOZ_NO_REMOTE': '1', 09:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:48 INFO - 'NO_EM_RESTART': '1', 09:09:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:48 INFO - 'PWD': '/builds/slave/test', 09:09:48 INFO - 'SHELL': '/bin/bash', 09:09:48 INFO - 'SHLVL': '1', 09:09:48 INFO - 'TERM': 'linux', 09:09:48 INFO - 'TMOUT': '86400', 09:09:48 INFO - 'USER': 'cltbld', 09:09:48 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', 09:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:48 INFO - '_': '/tools/buildbot/bin/python'} 09:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:48 INFO - Downloading/unpacking jsonschema==2.5.1 09:09:48 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 09:09:48 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 09:09:48 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 09:09:48 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 09:09:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:09:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:09:50 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 09:09:50 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 09:09:50 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 09:09:50 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 09:09:50 INFO - Downloading functools32-3.2.3-2.tar.gz 09:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:09:50 INFO - warning: no files found matching '*.txt' 09:09:50 INFO - no previously-included directories found matching 'build' 09:09:50 INFO - no previously-included directories found matching 'dist' 09:09:50 INFO - no previously-included directories found matching '.git*' 09:09:50 INFO - Installing collected packages: jsonschema, functools32 09:09:50 INFO - Running setup.py install for functools32 09:09:50 INFO - warning: no files found matching '*.txt' 09:09:50 INFO - no previously-included directories found matching 'build' 09:09:50 INFO - no previously-included directories found matching 'dist' 09:09:50 INFO - no previously-included directories found matching '.git*' 09:09:50 INFO - Successfully installed jsonschema functools32 09:09:50 INFO - Cleaning up... 09:09:50 INFO - Return code: 0 09:09:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:09: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')]} 09:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09: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')]} 09:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xdbf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a400>, '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 0x105b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b840>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', '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 09:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:09:50 INFO - Using env: {'DISPLAY': ':0', 09:09:50 INFO - 'HOME': '/home/cltbld', 09:09:50 INFO - 'LANG': 'en_US.UTF-8', 09:09:50 INFO - 'LANGUAGE': 'en_US:en', 09:09:50 INFO - 'LOGNAME': 'cltbld', 09:09:50 INFO - 'MAIL': '/var/mail/cltbld', 09:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:50 INFO - 'MOZ_NO_REMOTE': '1', 09:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:50 INFO - 'NO_EM_RESTART': '1', 09:09:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:50 INFO - 'PWD': '/builds/slave/test', 09:09:50 INFO - 'SHELL': '/bin/bash', 09:09:50 INFO - 'SHLVL': '1', 09:09:50 INFO - 'TERM': 'linux', 09:09:50 INFO - 'TMOUT': '86400', 09:09:50 INFO - 'USER': 'cltbld', 09:09:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', 09:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:50 INFO - '_': '/tools/buildbot/bin/python'} 09:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:09:50 INFO - Cleaning up... 09:09:50 INFO - Return code: 0 09:09:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:09: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')]} 09:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09: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')]} 09:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xdbf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a400>, '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 0x105b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b840>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', '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 09:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:09:50 INFO - Using env: {'DISPLAY': ':0', 09:09:50 INFO - 'HOME': '/home/cltbld', 09:09:50 INFO - 'LANG': 'en_US.UTF-8', 09:09:50 INFO - 'LANGUAGE': 'en_US:en', 09:09:50 INFO - 'LOGNAME': 'cltbld', 09:09:50 INFO - 'MAIL': '/var/mail/cltbld', 09:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:50 INFO - 'MOZ_NO_REMOTE': '1', 09:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:50 INFO - 'NO_EM_RESTART': '1', 09:09:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:50 INFO - 'PWD': '/builds/slave/test', 09:09:50 INFO - 'SHELL': '/bin/bash', 09:09:50 INFO - 'SHLVL': '1', 09:09:50 INFO - 'TERM': 'linux', 09:09:50 INFO - 'TMOUT': '86400', 09:09:50 INFO - 'USER': 'cltbld', 09:09:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', 09:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:50 INFO - '_': '/tools/buildbot/bin/python'} 09:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:50 INFO - Downloading/unpacking blobuploader==1.2.4 09:09:50 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 09:09:50 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 09:09:50 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 09:09:50 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 09:09:51 INFO - Downloading blobuploader-1.2.4.tar.gz 09:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:09:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:09:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:52 INFO - Downloading docopt-0.6.1.tar.gz 09:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:09:52 INFO - Installing collected packages: blobuploader, requests, docopt 09:09:52 INFO - Running setup.py install for blobuploader 09:09:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:09:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:09:52 INFO - Running setup.py install for requests 09:09:52 INFO - Running setup.py install for docopt 09:09:53 INFO - Successfully installed blobuploader requests docopt 09:09:53 INFO - Cleaning up... 09:09:53 INFO - Return code: 0 09:09:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:53 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')]} 09:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:53 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')]} 09:09:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:53 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 0xdbf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a400>, '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 0x105b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b840>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', '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 09:09:53 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 09:09:53 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 09:09:53 INFO - Using env: {'DISPLAY': ':0', 09:09:53 INFO - 'HOME': '/home/cltbld', 09:09:53 INFO - 'LANG': 'en_US.UTF-8', 09:09:53 INFO - 'LANGUAGE': 'en_US:en', 09:09:53 INFO - 'LOGNAME': 'cltbld', 09:09:53 INFO - 'MAIL': '/var/mail/cltbld', 09:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:53 INFO - 'MOZ_NO_REMOTE': '1', 09:09:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:53 INFO - 'NO_EM_RESTART': '1', 09:09:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:53 INFO - 'PWD': '/builds/slave/test', 09:09:53 INFO - 'SHELL': '/bin/bash', 09:09:53 INFO - 'SHLVL': '1', 09:09:53 INFO - 'TERM': 'linux', 09:09:53 INFO - 'TMOUT': '86400', 09:09:53 INFO - 'USER': 'cltbld', 09:09:53 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', 09:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:53 INFO - '_': '/tools/buildbot/bin/python'} 09:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:53 INFO - Running setup.py (path:/tmp/pip-FrPE3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:53 INFO - Running setup.py (path:/tmp/pip-7rjhwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:53 INFO - Running setup.py (path:/tmp/pip-F89Mno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:53 INFO - Running setup.py (path:/tmp/pip-tdvDLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:53 INFO - Running setup.py (path:/tmp/pip-oMXgjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:53 INFO - Running setup.py (path:/tmp/pip-_PAxUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:53 INFO - Running setup.py (path:/tmp/pip-8rNmww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:53 INFO - Running setup.py (path:/tmp/pip-TT4m6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:53 INFO - Running setup.py (path:/tmp/pip-otHzSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:53 INFO - Running setup.py (path:/tmp/pip-qAeeOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:54 INFO - Running setup.py (path:/tmp/pip-yLNXCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:54 INFO - Running setup.py (path:/tmp/pip-80UfNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:54 INFO - Running setup.py (path:/tmp/pip-IPaXIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:54 INFO - Running setup.py (path:/tmp/pip-RpssxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:54 INFO - Running setup.py (path:/tmp/pip-NLLNEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:54 INFO - Running setup.py (path:/tmp/pip-He30J8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:54 INFO - Running setup.py (path:/tmp/pip-qlbIOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:09:54 INFO - Running setup.py install for manifestparser 09:09:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:09:54 INFO - Running setup.py install for mozcrash 09:09:54 INFO - Running setup.py install for mozdebug 09:09:54 INFO - Running setup.py install for mozdevice 09:09:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:09:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:09:54 INFO - Running setup.py install for mozfile 09:09:55 INFO - Running setup.py install for mozhttpd 09:09:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:09:55 INFO - Running setup.py install for mozinfo 09:09:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:09:55 INFO - Running setup.py install for mozInstall 09:09:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:09:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:09:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:09:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:09:55 INFO - Running setup.py install for mozleak 09:09:55 INFO - Running setup.py install for mozlog 09:09:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:09:55 INFO - Running setup.py install for moznetwork 09:09:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:09:55 INFO - Running setup.py install for mozprocess 09:09:55 INFO - Running setup.py install for mozprofile 09:09:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:09:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:09:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:09:55 INFO - Running setup.py install for mozrunner 09:09:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:09:56 INFO - Running setup.py install for mozscreenshot 09:09:56 INFO - Running setup.py install for moztest 09:09:56 INFO - Running setup.py install for mozversion 09:09:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:09:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:09:56 INFO - Cleaning up... 09:09:56 INFO - Return code: 0 09:09:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:56 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 0xdbf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a400>, '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 0x105b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b840>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', '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 09:09:56 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 09:09:56 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 09:09:56 INFO - Using env: {'DISPLAY': ':0', 09:09:56 INFO - 'HOME': '/home/cltbld', 09:09:56 INFO - 'LANG': 'en_US.UTF-8', 09:09:56 INFO - 'LANGUAGE': 'en_US:en', 09:09:56 INFO - 'LOGNAME': 'cltbld', 09:09:56 INFO - 'MAIL': '/var/mail/cltbld', 09:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:56 INFO - 'MOZ_NO_REMOTE': '1', 09:09:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:56 INFO - 'NO_EM_RESTART': '1', 09:09:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:56 INFO - 'PWD': '/builds/slave/test', 09:09:56 INFO - 'SHELL': '/bin/bash', 09:09:56 INFO - 'SHLVL': '1', 09:09:56 INFO - 'TERM': 'linux', 09:09:56 INFO - 'TMOUT': '86400', 09:09:56 INFO - 'USER': 'cltbld', 09:09:56 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', 09:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:56 INFO - '_': '/tools/buildbot/bin/python'} 09:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:56 INFO - Running setup.py (path:/tmp/pip-DwdkA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:56 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)) 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:56 INFO - Running setup.py (path:/tmp/pip-ANeyGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:56 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)) 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:56 INFO - Running setup.py (path:/tmp/pip-mz3u2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:56 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)) 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:56 INFO - Running setup.py (path:/tmp/pip-8zhfhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:56 INFO - Running setup.py (path:/tmp/pip-a0QZSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:56 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)) 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:56 INFO - Running setup.py (path:/tmp/pip-QKSxfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:57 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)) 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:57 INFO - Running setup.py (path:/tmp/pip-MHKpLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:57 INFO - Running setup.py (path:/tmp/pip-3cib3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:57 INFO - Running setup.py (path:/tmp/pip-1qr72l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:57 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)) 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:57 INFO - Running setup.py (path:/tmp/pip-Rg4QlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:57 INFO - Running setup.py (path:/tmp/pip-roCznf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:57 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)) 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:57 INFO - Running setup.py (path:/tmp/pip-B6SIFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:57 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)) 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:57 INFO - Running setup.py (path:/tmp/pip-uauwj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:57 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)) 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:57 INFO - Running setup.py (path:/tmp/pip-cWyVG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:57 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)) 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:57 INFO - Running setup.py (path:/tmp/pip-N521Rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:57 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)) 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:57 INFO - Running setup.py (path:/tmp/pip-NtMJAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:57 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)) 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:57 INFO - Running setup.py (path:/tmp/pip-pFqTVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:57 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)) 09:09:57 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)) 09:09:57 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)) 09:09:57 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)) 09:09:57 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:09:57 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:09:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:09: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 09:09: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 09:09: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 09:09: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 09:09:58 INFO - Downloading blessings-1.6.tar.gz 09:09:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:09:59 INFO - Installing collected packages: blessings 09:09:59 INFO - Running setup.py install for blessings 09:09:59 INFO - Successfully installed blessings 09:09:59 INFO - Cleaning up... 09:09:59 INFO - Return code: 0 09:09:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:09:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:09:59 INFO - Reading from file tmpfile_stdout 09:09:59 INFO - Current package versions: 09:09:59 INFO - argparse == 1.2.1 09:09:59 INFO - blessings == 1.6 09:09:59 INFO - blobuploader == 1.2.4 09:09:59 INFO - docopt == 0.6.1 09:09:59 INFO - functools32 == 3.2.3-2 09:09:59 INFO - jsonschema == 2.5.1 09:09:59 INFO - manifestparser == 1.1 09:09:59 INFO - mozInstall == 1.12 09:09:59 INFO - mozcrash == 1.0 09:09:59 INFO - mozdebug == 0.1 09:09:59 INFO - mozdevice == 0.49 09:09:59 INFO - mozfile == 1.2 09:09:59 INFO - mozhttpd == 0.7 09:09:59 INFO - mozinfo == 0.9 09:09:59 INFO - mozleak == 0.1 09:09:59 INFO - mozlog == 3.4 09:09:59 INFO - moznetwork == 0.27 09:09:59 INFO - mozprocess == 0.25 09:09:59 INFO - mozprofile == 0.28 09:09:59 INFO - mozrunner == 6.13 09:09:59 INFO - mozscreenshot == 0.1 09:09:59 INFO - mozsystemmonitor == 0.3 09:09:59 INFO - moztest == 0.8 09:09:59 INFO - mozversion == 1.4 09:09:59 INFO - psutil == 3.1.1 09:09:59 INFO - requests == 1.2.3 09:09:59 INFO - wsgiref == 0.1.2 09:09:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:59 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 0xdbf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a400>, '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 0x105b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b840>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', '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 09:09:59 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 09:09:59 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 09:09:59 INFO - Using env: {'DISPLAY': ':0', 09:09:59 INFO - 'HOME': '/home/cltbld', 09:09:59 INFO - 'LANG': 'en_US.UTF-8', 09:09:59 INFO - 'LANGUAGE': 'en_US:en', 09:09:59 INFO - 'LOGNAME': 'cltbld', 09:09:59 INFO - 'MAIL': '/var/mail/cltbld', 09:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:59 INFO - 'MOZ_NO_REMOTE': '1', 09:09:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:59 INFO - 'NO_EM_RESTART': '1', 09:09:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:59 INFO - 'PWD': '/builds/slave/test', 09:09:59 INFO - 'SHELL': '/bin/bash', 09:09:59 INFO - 'SHLVL': '1', 09:09:59 INFO - 'TERM': 'linux', 09:09:59 INFO - 'TMOUT': '86400', 09:09:59 INFO - 'USER': 'cltbld', 09:09:59 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', 09:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:59 INFO - '_': '/tools/buildbot/bin/python'} 09:09:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:59 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)) 09:09:59 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)) 09:09:59 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)) 09:09:59 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)) 09:09:59 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)) 09:09:59 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)) 09:09:59 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)) 09:09:59 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)) 09:09:59 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)) 09:09:59 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)) 09:09:59 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)) 09:09:59 INFO - Cleaning up... 09:09:59 INFO - Return code: 0 09:09:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdfda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105d310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a400>, '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 0x105b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b840>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', '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 09:09:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:09:59 INFO - Using env: {'DISPLAY': ':0', 09:09:59 INFO - 'HOME': '/home/cltbld', 09:09:59 INFO - 'LANG': 'en_US.UTF-8', 09:09:59 INFO - 'LANGUAGE': 'en_US:en', 09:09:59 INFO - 'LOGNAME': 'cltbld', 09:09:59 INFO - 'MAIL': '/var/mail/cltbld', 09:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:59 INFO - 'MOZ_NO_REMOTE': '1', 09:09:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:59 INFO - 'NO_EM_RESTART': '1', 09:09:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:59 INFO - 'PWD': '/builds/slave/test', 09:09:59 INFO - 'SHELL': '/bin/bash', 09:09:59 INFO - 'SHLVL': '1', 09:09:59 INFO - 'TERM': 'linux', 09:09:59 INFO - 'TMOUT': '86400', 09:09:59 INFO - 'USER': 'cltbld', 09:09:59 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', 09:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:59 INFO - '_': '/tools/buildbot/bin/python'} 09:09:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:09:59 INFO - Cleaning up... 09:09:59 INFO - Return code: 0 09:09:59 INFO - Running post-action listener: _resource_record_post_action 09:09:59 INFO - Running post-action listener: _start_resource_monitoring 09:09:59 INFO - Starting resource monitoring. 09:09:59 INFO - [mozharness: 2017-05-12 16:09:59.904805Z] Finished create-virtualenv step (success) 09:09:59 INFO - [mozharness: 2017-05-12 16:09:59.905281Z] Running install step. 09:09:59 INFO - Running pre-action listener: _resource_record_pre_action 09:09:59 INFO - Running main action method: install 09:09:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:00 INFO - Reading from file tmpfile_stdout 09:10:00 INFO - Detecting whether we're running mozinstall >=1.0... 09:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:10:00 INFO - Reading from file tmpfile_stdout 09:10:00 INFO - Output received: 09:10:00 INFO - Usage: mozinstall [options] installer 09:10:00 INFO - Options: 09:10:00 INFO - -h, --help show this help message and exit 09:10:00 INFO - -d DEST, --destination=DEST 09:10:00 INFO - Directory to install application into. [default: 09:10:00 INFO - "/builds/slave/test"] 09:10:00 INFO - --app=APP Application being installed. [default: firefox] 09:10:00 INFO - mkdir: /builds/slave/test/build/application 09:10:00 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'] 09:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:10:14 INFO - Reading from file tmpfile_stdout 09:10:14 INFO - Output received: 09:10:14 INFO - /builds/slave/test/build/application/firefox/firefox 09:10:14 INFO - Running post-action listener: _resource_record_post_action 09:10:14 INFO - [mozharness: 2017-05-12 16:10:14.290323Z] Finished install step (success) 09:10:14 INFO - [mozharness: 2017-05-12 16:10:14.290565Z] Running run-tests step. 09:10:14 INFO - Running pre-action listener: _resource_record_pre_action 09:10:14 INFO - Running main action method: run_tests 09:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:10:14 INFO - Python 2.7.3 09:10:14 INFO - Return code: 0 09:10:14 INFO - grabbing minidump binary from tooltool 09:10:14 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')]} 09:10:14 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 0x105a400>, '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 0x105b3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105b840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:10:14 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 09:10:14 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 09:10:14 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 09:10:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:10:14 INFO - Return code: 0 09:10:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:10:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:10:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:10:14 INFO - ENV: RUST_BACKTRACE is now 1 09:10:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:10:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:10:14 INFO - Using env: {'DISPLAY': ':0', 09:10:14 INFO - 'HOME': '/home/cltbld', 09:10:14 INFO - 'LANG': 'en_US.UTF-8', 09:10:14 INFO - 'LANGUAGE': 'en_US:en', 09:10:14 INFO - 'LOGNAME': 'cltbld', 09:10:14 INFO - 'MAIL': '/var/mail/cltbld', 09:10:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:10:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:14 INFO - 'MOZ_NO_REMOTE': '1', 09:10:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:10:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:14 INFO - 'NO_EM_RESTART': '1', 09:10:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:14 INFO - 'PWD': '/builds/slave/test', 09:10:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:10:14 INFO - 'RUST_BACKTRACE': '1', 09:10:14 INFO - 'SHELL': '/bin/bash', 09:10:14 INFO - 'SHLVL': '1', 09:10:14 INFO - 'TERM': 'linux', 09:10:14 INFO - 'TMOUT': '86400', 09:10:14 INFO - 'USER': 'cltbld', 09:10:14 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723', 09:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:14 INFO - '_': '/tools/buildbot/bin/python'} 09:10:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:10:14 INFO - mozversion application_buildid: 20170512151322 09:10:14 INFO - mozversion application_changeset: bc096a7e31e236620e40d1dc94e0f4b4af63f75c 09:10:14 INFO - mozversion application_display_name: Firefox 09:10:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:10:14 INFO - mozversion application_name: Firefox 09:10:14 INFO - mozversion application_remotingname: firefox 09:10:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:10:14 INFO - mozversion application_vendor: Mozilla 09:10:14 INFO - mozversion application_version: 54.0 09:10:14 INFO - mozversion platform_buildid: 20170512151322 09:10:14 INFO - mozversion platform_changeset: bc096a7e31e236620e40d1dc94e0f4b4af63f75c 09:10:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:10:14 INFO - mozversion platform_version: 54.0 09:10:14 INFO - using testdate: 1494605414 09:10:14 INFO - actual date: 1494605414 09:10:14 INFO - starting webserver on 'localhost:36682' 09:10:14 INFO - SUITE-START | Running 2 tests 09:10:14 INFO - TEST-START | dromaeo_css 09:10:14 INFO - operating with platform_type : linux_ 09:10:14 INFO - Initialising browser for dromaeo_css test... 09:10:14 INFO - TEST-INFO | started process 20300 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNoIDF2/profile http://localhost:36682/getInfo.html) 09:10:18 INFO - PROCESS | 20300 | __metrics Screen width/height:1600/1200 09:10:18 INFO - PROCESS | 20300 | colorDepth:24 09:10:18 INFO - PROCESS | 20300 | Browser inner width/height: 1024/697 09:10:18 INFO - PROCESS | 20300 | __metrics 09:10:19 INFO - PROCESS | 20300 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:10:19 INFO - PROCESS | 20300 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:10:20 INFO - TEST-INFO | 20300: exit 0 09:10:20 INFO - Browser initialized. 09:10:20 INFO - Running cycle 1/1 for dromaeo_css test... 09:10:20 INFO - TEST-INFO | started process 20395 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNoIDF2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:32 INFO - PROCESS | 20395 | RSS: Main: 141422592 09:10:32 INFO - PROCESS | 20395 | 09:13:33 INFO - PROCESS | 20395 | Cycle 1(1): loaded http://localhost:36682/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36682/tests/dromaeo/cssquery-ext.html) 09:13:35 INFO - PROCESS | 20395 | RSS: Main: 276807680 09:13:35 INFO - PROCESS | 20395 | 09:16:37 INFO - PROCESS | 20395 | Cycle 1(1): loaded http://localhost:36682/tests/dromaeo/cssquery-ext.html (next: http://localhost:36682/tests/dromaeo/cssquery-jquery.html) 09:16:38 INFO - PROCESS | 20395 | RSS: Main: 223989760 09:16:38 INFO - PROCESS | 20395 | 09:19:39 INFO - PROCESS | 20395 | Cycle 1(1): loaded http://localhost:36682/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36682/tests/dromaeo/cssquery-mootools.html) 09:19:40 INFO - PROCESS | 20395 | RSS: Main: 171515904 09:19:40 INFO - PROCESS | 20395 | 09:22:42 INFO - PROCESS | 20395 | Cycle 1(1): loaded http://localhost:36682/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36682/tests/dromaeo/cssquery-prototype.html) 09:22:43 INFO - PROCESS | 20395 | RSS: Main: 172711936 09:22:43 INFO - PROCESS | 20395 | 09:25:45 INFO - PROCESS | 20395 | Cycle 1(1): loaded http://localhost:36682/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36682/tests/dromaeo/cssquery-yui.html) 09:25:46 INFO - PROCESS | 20395 | RSS: Main: 171622400 09:25:46 INFO - PROCESS | 20395 | 09:28:48 INFO - PROCESS | 20395 | Cycle 1(1): loaded http://localhost:36682/tests/dromaeo/cssquery-yui.html (next: http://localhost:36682/tests/dromaeo/cssquery-dojo.html) 09:28:49 INFO - PROCESS | 20395 | RSS: Main: 265183232 09:28:49 INFO - PROCESS | 20395 | 09:28:49 INFO - PROCESS | 20395 | __start_tp_report 09:28:49 INFO - PROCESS | 20395 | _x_x_mozilla_page_load 09:28:49 INFO - PROCESS | 20395 | _x_x_mozilla_page_load_details 09:28:49 INFO - PROCESS | 20395 | |i|pagename|runs| 09:28:49 INFO - PROCESS | 20395 | |0;dojo.html;3336.6329703732595;3370.2248482848936;3376.3922493951095;3394.083597428696;3419.777714448561;5610.551155907528;5649.124385720214;5662.292213473316;5680.914786278199;5693.373728889829;5684.772609095631;5747.080467125261;5750.453706897838;5753.395893431192;5755.107958266465;5201.739913004353;5203.089459344618;5211.973940130313;5235.685858848462;5279.260903473498;5022.522860328263;5056.140456122468;5285.233641122035;5291.920621190666;5324.787008519674;5143.12566863634;5148.871278218037;5171.3535808024;5175.171972484403;5188.377394712621;5257.447967963366;5265.526102650741;5270.420253772101;5271.815486457956;5298.337707786527;4996.525330093636;5105.33630628016;5163.690178589259;5199.688018718851;5214.713190774506;5532.446755324436;5540.806071787509;5608.327000759935;5622.409646987089;5629.183768353607;6019.036954087325;6077.24034495688;6079.969600151971;6089.604175728564;6090.786822461193;6137.723802428902;6150.477209437203;6150.7232174552255;6154.138420621117;6156.3843615638525;5472.097103977839;5502.394736578973;5657.32112146545;5682.090865461589;5709.628874123168;6243.125962365272;6250.093736408196;6254.186955695822;6258.311585725566;6266.811995640138;5957.3744756799915;6011.789587364421;6019.638821670714;6024.036154215392;6052.243469566304;5853;5887.793927212527;6000.729967151488;6119.96940015306;6127.325994140641;6087.847803804941;6093.360197179321;6094.481969032593;6094.847628809315;6094.908576371358;5859.707014649251;5886.470217680429;5887.499562537147;6015.789447369321;6056.121862329937;4567.908641827211;4659.44086709597;4660.067986402665;4678.929816052762;4682.157211701859;4173.7078404512;4179.582041795796;4182.853600124042;4185.937210941838;4204.201201771653;5638.379778224474;5681.801136960269;5774.364819869811;5794.797182098607;5809.50619197373;6103.816885493442;6106.236720409949;6116.701002199504;6117.553376797925;6141.7543298268465;5846;5853.502452291517;5854.151148083504;5859.853503662443;5862.472377485961;5008.774605142778;5045.646804723708;5144.536991670768;5168.870778230575;5183.481651834862;6333.1766870306565;6344.397282258178;6349.936500635119;6351.841203969938;6390.616563006234;6137.110119032626;6144.754209831557;6148.692565371624;6151.6924153793025;6154.3845615438095;6124.075808605094;6130.601510901777;6213.9067913980425;6228.159198508325;6320.336364681826;6021;6023.397660233942;6025.0359942409;6047.304559975659;6055.394460553909;6130.356312587114;6131.877362452815;6138.109974053648;6143.385661433821;6147.262328520606;5677.233573467529;5798.159266906359;5907.2911250650195;5988.610740301867;5997.820065398045;5989.401059893976;5994.520438364833;6008.429199226067;6014.63912165254;6029.186059881859;5924.229850119457;5972.671503067199;5975.880482390415;5976.282846058501;5976.641401515923;5665.405132461032;5690.544756419394;5732.140178973187;5924.940750592439;5970.373110823475;5854.531637469077;5865.296164460292;5867.295924488918;5896;5909.497692695997 09:28:49 INFO - PROCESS | 20395 | |1;ext.html;790.1308560583359;853.1042405474252;858.1890113842418;938.4744543055893;939.8402271613825;31069.689303106963;42418.78790606047;43248;47712;48010.27984580234;45433.77283113588;45460;45566.772166139126;45573;45709.542904570946;7764.456488045839;7891.368690504746;9252.722418327461;9393.154616084552;10738.140948724084;10764.946175269151;10875.347479151236;10892.346459212473;10904.236703430763;10905.781884362308;10940.890591094107;10951.83572246413;11001.724956876062;11003.834942475869;11011.504482298316;10898;10909.563617455293;10941.070009049201;10972;10974.012338889499;10485.10876575492;10551.47242637865;10560.155187585051;10560.419176945265;10563.947180264126;42956.3556546652;43133.784331078146;43174.78412607948;43219;43255.35116973217;4279.165562715271;4294.055307832303;4304.182205380999;4406.493253275882;4444.022315090675;30765.846170769226;30825;30997.38005239905;31132.8443357784;32060.198495037577;4394.099209662044;4420.955790442086;4487.708298960557;4552.408186935711;4574.336721175444;228089;229290;229640;230222.84888575616;230263;51487;51632.22551661728;51725.48274517206;51729.741351293;51804;54576.72711636496;54819.17771233435;54853.72573137109;54862.45137548573;54872.176917346274;14936.477223297132;15307.846921530641;15345.46290879814;15348;15355.692886142439;11311.095112390989;11491.942540287413;13051.477940882258;13110.803337949937;13305.667358315926;13285.13646612967;13344.933275333562;13348.799768003488;13434.865651343556;13436.059475836793;5435.320584926884;5443.918341224885;5506.697131657797;5565.499105080562;5588.524975377139;1014.7108074198818;1026.8973102689672;1029.9330543514798;1033.121846430528;1035.1046344911597;28147.43705125886;28356.574651380244;28406.431871362456;28576.428471430456;28652.283692907844;1077.2674581284803;1081.2377274021796;1095.0254273696416;1111.6276047524009;1128.971775705614;14536.200508972108;15012.249387530579;15123.773143402857;15207.467738629095;15240.390384384722;306950;307119;308398;308444;310477;53867;53993.73003135038;54096.18855717168;54131.45868541343;54283.45716542863;1496.513633069257;1502.3389708528211;1533.4479587348546;1536.377767004365;1549.3260431712242;28019.57970630483;28519.42961140807;29117.272068198465;29415.11754647364;29515.85242073776;40353.39469908013;40470.392944106454;40498.797506012284;40526.79736601298;40577.391339129346;297035;297952;299043;299137;299214;25700;26104.477910441306;27983;29712.702872970993;30942.845285773426;39184.21631567296;39347.60652393554;39382.409263861635;39422.60577394189;40436.79781601191;30616;30624.693753062184;30782.538261926537;30883.536746948364;30976.845115775177;30692.846535767178;30727;30931;31325;31525.842370788;35731.642683572834;35896.4615530762;36012.4598131023;36303;36438.4534231992;28824.85587572049;29125;29217.85391073031;29284.41431171322;29372.853135734185;23475;24896;24910;24910.501789963735;25304.240872773848 09:28:49 INFO - PROCESS | 20395 | |2;jquery.html;464.99302510462326;474.79583778975046;476.71158948835955;479.2715073088907;493.7506559187611;20853.27013554524;21371.57256854862;22395.32814015578;22457.32628021162;22624.773752262474;22674.54650906981;22791;22900.541989160247;22990.770092299073;23124.884375578098;6603.042558828967;6619.371159739817;6786.796396108125;6806.931930680704;6829;6723.361280678352;6837.760678376258;7645.082590089197;7957.084935232465;7987.600619969007;7832.334251588622;8000.31997280229;8160.428769986073;8171.428000039969;8211.671533138668;7973.521588704696;8254.54599997;8297.336213102937;8298.958505207465;8304.958475207615;7909.090454597737;7949.046114466243;7950.045994480642;7954.164812694699;7961.840763184704;29109.41781164365;29435.852820735974;30128.24679383011;30192.396152076835;30425.391492170034;3283.7506308178736;3348.631650518441;3363.899083027513;3419.81191034493;3468.7745296555645;17180.22688978999;17833.910830445766;18226.27094916202;18634.778770279223;18655;2295.1507942061544;2332.6267797152373;2338.590746619335;2349.060375849657;2388.9163879264315;112369;116472;117185.41407292994;117352.41323793412;117781;19629.21483140687;20283;20762.754234745964;21215.802128478623;21765.23821666234;42015.7899210502;42223.57776422258;42248;42253.36619950703;42563.7871810639;10952.561897524169;10992.560297588167;11169.162312826571;11368.94315528417;11476.655700329004;8883.37816352862;8930.776730581787;8948.776280592907;9060.682876099305;9109.407888487227;10652.946735266274;11188.517626363024;11302.491387887565;11362.375069371099;11475;4312.676549258818;4383.561643835591;4432.84485043022;4479.395973822907;4602.7008244464005;964.286428043243;964.5804075227298;965.2471072563412;967.0812727908375;973.9464329461806;18956.715649265272;20107;20479.07844147017;20535.4866128348;20911.477213069797;972.2853702528636;981.474910922653;982.1651596143364;987.7283746969641;992.6178421307849;10718.464076796128;11173.759609713092;11525.88474115248;12089.758204835854;12552.058595605366;474559;483229;504550;753305;768123;69863.91887796356;109324.4533777326;116296;122301.38849305696;123117;1415.1721243072918;1429.6068581140269;1439.8848092152602;1480.5558332500207;1482.9629259268604;18080.819191807914;18628;18648;18944.810551894858;19082.713759293343;49625.75187124186;49653.503464966336;51242.13199746018;51489.22766158436;52172.217416739535;67116;67910;68175.3182468169;69406.30593693999;70565.64717176382;12020.459079341275;12161.756764864476;12206.633800985985;12365.443555039865;12573.245605263714;13916.095453795471;14080.436782528584;14193.716125677634;14479.203643799692;14527.983041187015;11371.829422558834;11387.829182562435;11445;11566;11865.762684746083;11548.769024619292;11630.592929247436;11653.242539234923;11761.353125578175;12032.097592680759;11776.175667703179;12047.879521205026;12290.692732681755;12299.569515066929;12433.316167610868;12105.757884842078;12117.939410302892;12211.084168687206;12284.877151228373;12346.444410001752;10176.592936282466;10351.171906247331;11788.528458861892;12152.331621760888;12169.756604868031 09:28:49 INFO - PROCESS | 20395 | |3;mootools.html;1547.3114464063497;1562.3047743754028;1595.0350038226866;1600.8319126491717;1603.3907115296186;10856.131509479243;11381.772364552715;11383.829242561347;11396.829047564293;11417.372044537558;11298.604548840793;11454.48454819533;11743.41282935852;11760.647180584574;11778.05775537957;2443.560159171344;2491.700995880497;2492.8753562321813;2496.5131799299143;2497.063601149569;2414.2274472168915;2475.9133430329944;2520.6975162980375;2540.7840333571667;2541.8474891506567;2499.950000999979;2508.824382293231;2509.6988361396566;2549.7450254974538;2553.5531282025663;2387.961236861962;2410.95178096438;2422.3701837522194;2445.364205306615;2478.0211816332626;2412.963805542918;2426.405530645003;2439.0609615298063;2441.865697386643;2443.5357282116343;11688.94155529217;11980.760384792342;11993.340366279852;12010.579629712918;12018.218815777034;5813.593048486608;5946.286445626509;5960.523158147338;5965.194698715662;5965.701714914237;3453.4819777033395;3534.4874993125854;3568.6074531801482;3594.820258987066;3625.7099432045375;1244.4213440750095;1270.1299609767339;1275.2667216350635;1285.4344088600985;1336.0647546717337;15492.612684682861;15820.76268855968;16807.999999999876;17901.552461188443;18060.819391806046;4659.673822832437;4783.6651434399955;4809.975950120227;4900.460949295574;4914.557689807934;11362;11623.360715160747;12457.626271211879;13365.131266467648;13439.664008399868;5964.701764911737;6118.724657390429;6131.816045518642;6135;6141.140240366352;5586.525145362609;5682.176084467729;5692.117721753135;5702.48677619008;5731.713414329267;3971.543272523639;4019.0756126091164;4043.3934909763184;4088.8773336799945;4104.589541045872;956.7751578379075;960.8558716192628;975.0931633580838;977.2719324103447;977.6333874796951;1012.6202673997251;1030.2788048366174;1034.384541197986;1039.3244391145815;1042.5673345561506;6691.46468282546;6939.861202776017;6940.757073502403;7057.329553692391;7075.221725610281;1043.546057465005;1062.6493257225097;1073.7100982734619;1092.9726756830985;1101.2016288190998;4376.912461750747;4592.816287348535;4603.654725895572;4617.746023968714;4671.906561868744;14903.627409314853;15012.699746005019;15470.5358839235;15522.301496432689;15529.611759705871;14739.55781326562;14864.851351486346;15108.244587770787;15200;15205.847941520664;10949.343039417661;11148.665540033811;11155.05182059534;11179.72050698739;11198.944005279922;7081.787546373617;7305.232950540117;7382.5939573323985;7536.171021187774;7546.509476883985;12830.973522118022;13077;13180.736385272048;13196.538121165713;13257;13438.932805335911;13485.190888546718;13493.662658433224;13549.729005420033;13617.65955851072;8751.824963500821;9035.186833185046;9676.951615241878;9811.460369679737;9877.654282100091;11354.318740875575;11397.316161030365;11512.481938312629;11522.596709115476;11656.766864662828;9527.237820974444;9536.189423898768;9622.278329125484;9760.951195243979;9921.107100361001;9575.329726919048;9576.377535460173;9607.855882161633;9695.272854536079;9775.413475191512;8343.45767525109;8422.073571906963;8478.067412584734;8478.32173426112;8541.743747687578;6245.375462453719;6344.746210151542;6346.4605508532295;6404.711787969646;6438.484921206386;6102.816915492543;6252.906206406817;6266.68666566679;6407.775727849503;6427.164468619049 09:28:49 INFO - PROCESS | 20395 | |4;prototype.html;416.3795944043375;427.4187105536471;440.2471773267716;444.85097492340066;450.80390030336787;5102.601777164736;5327.4406187350305;5335.653182543132;5354.277172581706;5372.382176049756;5155.793768249266;5163.845084647466;5178.326817513727;5196.662216955895;5211.42674305827;4335.913281734363;4417;4420.823167073313;4426.77866106695;4441.2893936970095;4422.734635921839;4433.179861725575;4435.580614203456;4448.199324121657;4468.285074388098;4457.7072648931935;4460.821567137311;4469.7541635210055;4476.8885624490995;4482.305242687388;4338.414314067592;4362.825486980517;4367.847125350598;4384.276594361926;4426.897223818661;4149.937750933738;4159.729617574878;4165.667226353252;4165.770882601456;4182.64447521961;5101.081805275049;5102;5170.638055336128;5170.9141080373165;5193.170980161114;4886.682365646222;5075.467075957045;5122.615803814729;5132.228596557854;5167.896642067138;5504.862378440531;5505.403433004424;5543.334799824007;5553;5583.944160558383;4502.167099086647;4571.497135315112;4597.747123908183;4623.283391074355;4652.74409907455;5416.458354164592;5488.914212599227;5611.915821262725;5704.258446401983;5806.242035033368;4637.9536204637525;4712.410948631421;4716.221823399101;4772.284157376422;4782.593479554207;4789.808407663724;4816.759162041954;4822;4825.938293575407;4853.417589889236;4945.9258111128365;5092.879566495365;5134.640575159778;5152.355955505562;5160.48395160481;5530.446955304437;5546.833594992157;5548.4174161712435;5549.306336707911;5577.350758389814;1617.3583812430393;1619.2065887714934;1621.4487074394763;1623.1965177237344;1635.8118816336037;1250;1286.2411177405188;1296.3388671777273;1297.7534268488957;1299.1166007115253;5318.361796584435;5511.972440137773;5532.419095994943;5546.417626149277;5556.972215138899;5711.22951884917;5740.799072032459;5741.167530708024;5756.654600723886;5766.336871259775;5427.43011983744;5447.0858090921165;5456.608564815947;5467.507924286833;5521.585881058937;2885.8845646174336;2928.3996780659913;2962.674105848355;2972.4246149540345;2979.5977543031413;5844.298684157843;5874.295084589791;5883.411658834168;5896.174535565025;5932.525397968152;5482.616216864861;5736.1682556029145;5745.2818397700285;5775.768969241183;5811.88376232473;5828.475437210672;5832.387599302097;5854.887571361428;5876.324222714272;5919.6744179070565;5655.773769049357;5655.868826234687;5674.319081710056;5680.346760122638;5693.28833895763;5226.921596175985;5589.972050139723;5603.32760068778;5617.466340697627;5620.325560932714;5566.858793947312;5737.196792449144;5820.650760954356;5832.358440571618;5862.853428664317;4900.62319774916;5001.64988450805;5021.61905475997;5044.54599086084;5063.1645778446145;5446.591505637014;5649.6327738696855;5655.208270842003;5679.744411501575;5736.68448235351;5520.254765606753;5525.778968841201;5527.8894422110525;5537.307836520435;5564.777408903598;5461.617686761889;5502.312210973629;5593.776248950159;5621.566500542335;5662.631928924607;5295;5541.196526503554;5549.861253468534;5571.220029195835;5575.275214222125;5510.484616730271;5590.329160500766;5640.32316122068;5656.123300888369;5657.858553536194;5113.718745469035;5515.117581186991;5527.5577953764405;5535.280413546213;5536.833894983157 09:28:49 INFO - PROCESS | 20395 | |5;yui.html;896.5606852642203;923.5433746235016;929.4098247612764;946.522006386775;958.7075941837738;18198.90900545497;18712.25150993962;18748.343807966725;18753.437396878082;18756.718649220238;16815.663686726257;16989.320427182927;16991.405300814473;17020.914895425536;17036.91481542594;879.406400679542;899.6401439424217;901.062894589626;901.8016036471965;904.1771987491351;901.5582364641311;901.6123067081153;901.9819603607924;902.5893218585528;903.327021369081;949.8717673114145;950.8050849575854;951.029949451559;952.1478276942132;952.7903861150536;948.9715308540755;950.3300173377752;950.7575568230092;951.0442005784178;951.2009911674157;694.4652617484509;696.554205308603;698.4761428928304;698.5913240754165;698.849747304332;17922.731159032624;18052.54868628282;18079;18153.7276940846;18187.27250909962;16014.839851601568;16370.427035053715;16488;16853.494395168163;17370.21834017472;13060.673483162902;13420.396082176228;13648.454061837514;13896.722065558733;14120.223387713671;4060.959390406117;4147.937780933288;4174.645155161815;4175.895602609929;4261.637760790337;63348;85440;87164;87878.5606071972;89489.55255223683;12880;12955.935220324027;13504.324783760772;13820.489284431513;13833.377498012613;24649.63025554618;25775.355616109748;25869.482610347688;26169.73830261673;26480;9227.123423274777;9381.390209636353;9392.67125650599;9408;9454.48000359973;5086.491350864884;5197.688138711689;5199.142141546602;5291.232771248225;5303.178007408857;3700.740948133605;3790.639889210521;3851.326017946848;3854.7879866607045;3872.0319920019997;624.709510077816;628.3402427451249;629.7921685843661;629.9874002519924;638.2277444292458;783.8628240057967;792.5680504125265;797.8165022044963;800.7837883771349;803.42555073123;11230.831537526943;11358.488868000959;11460.369679667698;11704.765904681859;12081.637550873487;755.7014979083232;763.5533213070416;766.3294617209941;777.7316825695126;836.1095433363448;5234;5312.415634280226;5323.547498462596;5326.573874090063;5358.651687640291;77086.6145669268;78408.60795695985;78685.60657196677;79491.60254198808;80076.59961700154;56148.43851561514;58345;70895;72759.27240727525;73102;57264.427355726744;57725.42274577284;57970;58270;58587.414125858195;11145.164112691418;11847.76304473923;12336.996627260227;12690.809637855624;12865.871341286467;40642.79678601588;40744.79627601843;41418.37872431856;41542.376864347665;41698.791506042275;53580.73209633927;53977;54054;55323;55405.16892246539;14006.229657368942;14098.929505352407;14229.359678814271;14354.569362918712;14469.78295325592;16494.917525412297;16558.00651960886;16955.406560770312;17159.27669061184;17354.305827766748;15235.238238088274;16171;16295.266712997707;16471.094089825176;16824.747628785823;14713.411463541339;14753.114813111248;15521.301541430888;15541.222938853238;15563.377464901278;14884.18137002432;15477.226138693246;15984.280707367963;16309.918450407671;16339.264733087277;10979.670609881716;11324.716882078013;11433.542658293907;11726.472308745957;12013.879861201276;12885.115024339055;13670.17978921268;13670.52153174634;13807.516771828075;13867.4453021878 09:28:49 INFO - PROCESS | 20395 | __end_tp_report 09:28:49 INFO - PROCESS | 20395 | __start_cc_report 09:28:49 INFO - PROCESS | 20395 | _x_x_mozilla_cycle_collect,1345 09:28:49 INFO - PROCESS | 20395 | __end_cc_report 09:28:49 INFO - PROCESS | 20395 | __startTimestamp1494606529219__endTimestamp 09:28:49 INFO - PROCESS | 20395 | 09:28:49 INFO - PROCESS | 20395 | ------- Summary: start ------- 09:28:49 INFO - PROCESS | 20395 | Number of tests: 6 09:28:49 INFO - PROCESS | 20395 | 09:28:49 INFO - PROCESS | 20395 | [#0] dojo.html Cycles:165 Average:5652.78 Median:5862.47 stddev:632.52 (10.8%) stddev-sans-first:607.80 09:28:49 INFO - PROCESS | 20395 | Values: 3336.6 3370.2 3376.4 3394.1 3419.8 5610.6 5649.1 5662.3 5680.9 5693.4 5684.8 5747.1 5750.5 5753.4 5755.1 5201.7 5203.1 5212.0 5235.7 5279.3 5022.5 5056.1 5285.2 5291.9 5324.8 5143.1 5148.9 5171.4 5175.2 5188.4 5257.4 5265.5 5270.4 5271.8 5298.3 4996.5 5105.3 5163.7 5199.7 5214.7 5532.4 5540.8 5608.3 5622.4 5629.2 6019.0 6077.2 6080.0 6089.6 6090.8 6137.7 6150.5 6150.7 6154.1 6156.4 5472.1 5502.4 5657.3 5682.1 5709.6 6243.1 6250.1 6254.2 6258.3 6266.8 5957.4 6011.8 6019.6 6024.0 6052.2 5853.0 5887.8 6000.7 6120.0 6127.3 6087.8 6093.4 6094.5 6094.8 6094.9 5859.7 5886.5 5887.5 6015.8 6056.1 4567.9 4659.4 4660.1 4678.9 4682.2 4173.7 4179.6 4182.9 4185.9 4204.2 5638.4 5681.8 5774.4 5794.8 5809.5 6103.8 6106.2 6116.7 6117.6 6141.8 5846.0 5853.5 5854.2 5859.9 5862.5 5008.8 5045.6 5144.5 5168.9 5183.5 6333.2 6344.4 6349.9 6351.8 6390.6 6137.1 6144.8 6148.7 6151.7 6154.4 6124.1 6130.6 6213.9 6228.2 6320.3 6021.0 6023.4 6025.0 6047.3 6055.4 6130.4 6131.9 6138.1 6143.4 6147.3 5677.2 5798.2 5907.3 5988.6 5997.8 5989.4 5994.5 6008.4 6014.6 6029.2 5924.2 5972.7 5975.9 5976.3 5976.6 5665.4 5690.5 5732.1 5924.9 5970.4 5854.5 5865.3 5867.3 5896.0 5909.5 09:28:49 INFO - PROCESS | 20395 | 09:28:49 INFO - PROCESS | 20395 | [#1] ext.html Cycles:180 Average:44547.00 Median:30620.35 stddev:73317.23 (239.4%) stddev-sans-first:73449.30 09:28:49 INFO - PROCESS | 20395 | Values: 790.1 853.1 858.2 938.5 939.8 31069.7 42418.8 43248.0 47712.0 48010.3 45433.8 45460.0 45566.8 45573.0 45709.5 7764.5 7891.4 9252.7 9393.2 10738.1 10764.9 10875.3 10892.3 10904.2 10905.8 10940.9 10951.8 11001.7 11003.8 11011.5 10898.0 10909.6 10941.1 10972.0 10974.0 10485.1 10551.5 10560.2 10560.4 10563.9 42956.4 43133.8 43174.8 43219.0 43255.4 4279.2 4294.1 4304.2 4406.5 4444.0 30765.8 30825.0 30997.4 31132.8 32060.2 4394.1 4421.0 4487.7 4552.4 4574.3 228089.0 229290.0 229640.0 230222.8 230263.0 51487.0 51632.2 51725.5 51729.7 51804.0 54576.7 54819.2 54853.7 54862.5 54872.2 14936.5 15307.8 15345.5 15348.0 15355.7 11311.1 11491.9 13051.5 13110.8 13305.7 13285.1 13344.9 13348.8 13434.9 13436.1 5435.3 5443.9 5506.7 5565.5 5588.5 1014.7 1026.9 1029.9 1033.1 1035.1 28147.4 28356.6 28406.4 28576.4 28652.3 1077.3 1081.2 1095.0 1111.6 1129.0 14536.2 15012.2 15123.8 15207.5 15240.4 306950.0 307119.0 308398.0 308444.0 310477.0 53867.0 53993.7 54096.2 54131.5 54283.5 1496.5 1502.3 1533.4 1536.4 1549.3 28019.6 28519.4 29117.3 29415.1 29515.9 40353.4 40470.4 40498.8 40526.8 40577.4 297035.0 297952.0 299043.0 299137.0 299214.0 25700.0 26104.5 27983.0 29712.7 30942.8 39184.2 39347.6 39382.4 39422.6 40436.8 30616.0 30624.7 30782.5 30883.5 30976.8 30692.8 30727.0 30931.0 31325.0 31525.8 35731.6 35896.5 36012.5 36303.0 36438.5 28824.9 29125.0 29217.9 29284.4 29372.9 23475.0 24896.0 24910.0 24910.5 25304.2 09:28:49 INFO - PROCESS | 20395 | 09:28:49 INFO - PROCESS | 20395 | [#2] jquery.html Cycles:180 Average:36561.19 Median:22845.77 stddev:101110.05 (442.6%) stddev-sans-first:101357.36 09:28:49 INFO - PROCESS | 20395 | Values: 465.0 474.8 476.7 479.3 493.8 20853.3 21371.6 22395.3 22457.3 22624.8 22674.5 22791.0 22900.5 22990.8 23124.9 6603.0 6619.4 6786.8 6806.9 6829.0 6723.4 6837.8 7645.1 7957.1 7987.6 7832.3 8000.3 8160.4 8171.4 8211.7 7973.5 8254.5 8297.3 8299.0 8305.0 7909.1 7949.0 7950.0 7954.2 7961.8 29109.4 29435.9 30128.2 30192.4 30425.4 3283.8 3348.6 3363.9 3419.8 3468.8 17180.2 17833.9 18226.3 18634.8 18655.0 2295.2 2332.6 2338.6 2349.1 2388.9 112369.0 116472.0 117185.4 117352.4 117781.0 19629.2 20283.0 20762.8 21215.8 21765.2 42015.8 42223.6 42248.0 42253.4 42563.8 10952.6 10992.6 11169.2 11368.9 11476.7 8883.4 8930.8 8948.8 9060.7 9109.4 10652.9 11188.5 11302.5 11362.4 11475.0 4312.7 4383.6 4432.8 4479.4 4602.7 964.3 964.6 965.2 967.1 973.9 18956.7 20107.0 20479.1 20535.5 20911.5 972.3 981.5 982.2 987.7 992.6 10718.5 11173.8 11525.9 12089.8 12552.1 474559.0 483229.0 504550.0 753305.0 768123.0 69863.9 109324.5 116296.0 122301.4 123117.0 1415.2 1429.6 1439.9 1480.6 1483.0 18080.8 18628.0 18648.0 18944.8 19082.7 49625.8 49653.5 51242.1 51489.2 52172.2 67116.0 67910.0 68175.3 69406.3 70565.6 12020.5 12161.8 12206.6 12365.4 12573.2 13916.1 14080.4 14193.7 14479.2 14528.0 11371.8 11387.8 11445.0 11566.0 11865.8 11548.8 11630.6 11653.2 11761.4 12032.1 11776.2 12047.9 12290.7 12299.6 12433.3 12105.8 12117.9 12211.1 12284.9 12346.4 10176.6 10351.2 11788.5 12152.3 12169.8 09:28:49 INFO - PROCESS | 20395 | 09:28:49 INFO - PROCESS | 20395 | [#3] mootools.html Cycles:180 Average:7244.91 Median:2496.79 stddev:4638.11 (185.8%) stddev-sans-first:4631.37 09:28:49 INFO - PROCESS | 20395 | Values: 1547.3 1562.3 1595.0 1600.8 1603.4 10856.1 11381.8 11383.8 11396.8 11417.4 11298.6 11454.5 11743.4 11760.6 11778.1 2443.6 2491.7 2492.9 2496.5 2497.1 2414.2 2475.9 2520.7 2540.8 2541.8 2500.0 2508.8 2509.7 2549.7 2553.6 2388.0 2411.0 2422.4 2445.4 2478.0 2413.0 2426.4 2439.1 2441.9 2443.5 11688.9 11980.8 11993.3 12010.6 12018.2 5813.6 5946.3 5960.5 5965.2 5965.7 3453.5 3534.5 3568.6 3594.8 3625.7 1244.4 1270.1 1275.3 1285.4 1336.1 15492.6 15820.8 16808.0 17901.6 18060.8 4659.7 4783.7 4810.0 4900.5 4914.6 11362.0 11623.4 12457.6 13365.1 13439.7 5964.7 6118.7 6131.8 6135.0 6141.1 5586.5 5682.2 5692.1 5702.5 5731.7 3971.5 4019.1 4043.4 4088.9 4104.6 956.8 960.9 975.1 977.3 977.6 1012.6 1030.3 1034.4 1039.3 1042.6 6691.5 6939.9 6940.8 7057.3 7075.2 1043.5 1062.6 1073.7 1093.0 1101.2 4376.9 4592.8 4603.7 4617.7 4671.9 14903.6 15012.7 15470.5 15522.3 15529.6 14739.6 14864.9 15108.2 15200.0 15205.8 10949.3 11148.7 11155.1 11179.7 11198.9 7081.8 7305.2 7382.6 7536.2 7546.5 12831.0 13077.0 13180.7 13196.5 13257.0 13438.9 13485.2 13493.7 13549.7 13617.7 8751.8 9035.2 9677.0 9811.5 9877.7 11354.3 11397.3 11512.5 11522.6 11656.8 9527.2 9536.2 9622.3 9761.0 9921.1 9575.3 9576.4 9607.9 9695.3 9775.4 8343.5 8422.1 8478.1 8478.3 8541.7 6245.4 6344.7 6346.5 6404.7 6438.5 6102.8 6252.9 6266.7 6407.8 6427.2 09:28:49 INFO - PROCESS | 20395 | 09:28:49 INFO - PROCESS | 20395 | [#4] prototype.html Cycles:180 Average:4839.33 Median:5331.55 stddev:1285.11 (24.1%) stddev-sans-first:1245.09 09:28:49 INFO - PROCESS | 20395 | Values: 416.4 427.4 440.2 444.9 450.8 5102.6 5327.4 5335.7 5354.3 5372.4 5155.8 5163.8 5178.3 5196.7 5211.4 4335.9 4417.0 4420.8 4426.8 4441.3 4422.7 4433.2 4435.6 4448.2 4468.3 4457.7 4460.8 4469.8 4476.9 4482.3 4338.4 4362.8 4367.8 4384.3 4426.9 4149.9 4159.7 4165.7 4165.8 4182.6 5101.1 5102.0 5170.6 5170.9 5193.2 4886.7 5075.5 5122.6 5132.2 5167.9 5504.9 5505.4 5543.3 5553.0 5583.9 4502.2 4571.5 4597.7 4623.3 4652.7 5416.5 5488.9 5611.9 5704.3 5806.2 4638.0 4712.4 4716.2 4772.3 4782.6 4789.8 4816.8 4822.0 4825.9 4853.4 4945.9 5092.9 5134.6 5152.4 5160.5 5530.4 5546.8 5548.4 5549.3 5577.4 1617.4 1619.2 1621.4 1623.2 1635.8 1250.0 1286.2 1296.3 1297.8 1299.1 5318.4 5512.0 5532.4 5546.4 5557.0 5711.2 5740.8 5741.2 5756.7 5766.3 5427.4 5447.1 5456.6 5467.5 5521.6 2885.9 2928.4 2962.7 2972.4 2979.6 5844.3 5874.3 5883.4 5896.2 5932.5 5482.6 5736.2 5745.3 5775.8 5811.9 5828.5 5832.4 5854.9 5876.3 5919.7 5655.8 5655.9 5674.3 5680.3 5693.3 5226.9 5590.0 5603.3 5617.5 5620.3 5566.9 5737.2 5820.7 5832.4 5862.9 4900.6 5001.6 5021.6 5044.5 5063.2 5446.6 5649.6 5655.2 5679.7 5736.7 5520.3 5525.8 5527.9 5537.3 5564.8 5461.6 5502.3 5593.8 5621.6 5662.6 5295.0 5541.2 5549.9 5571.2 5575.3 5510.5 5590.3 5640.3 5656.1 5657.9 5113.7 5515.1 5527.6 5535.3 5536.8 09:28:49 INFO - PROCESS | 20395 | 09:28:49 INFO - PROCESS | 20395 | [#5] yui.html Cycles:180 Average:18528.08 Median:41620.58 stddev:22125.64 (53.2%) stddev-sans-first:22148.09 09:28:49 INFO - PROCESS | 20395 | Values: 896.6 923.5 929.4 946.5 958.7 18198.9 18712.3 18748.3 18753.4 18756.7 16815.7 16989.3 16991.4 17020.9 17036.9 879.4 899.6 901.1 901.8 904.2 901.6 901.6 902.0 902.6 903.3 949.9 950.8 951.0 952.1 952.8 949.0 950.3 950.8 951.0 951.2 694.5 696.6 698.5 698.6 698.8 17922.7 18052.5 18079.0 18153.7 18187.3 16014.8 16370.4 16488.0 16853.5 17370.2 13060.7 13420.4 13648.5 13896.7 14120.2 4061.0 4147.9 4174.6 4175.9 4261.6 63348.0 85440.0 87164.0 87878.6 89489.6 12880.0 12955.9 13504.3 13820.5 13833.4 24649.6 25775.4 25869.5 26169.7 26480.0 9227.1 9381.4 9392.7 9408.0 9454.5 5086.5 5197.7 5199.1 5291.2 5303.2 3700.7 3790.6 3851.3 3854.8 3872.0 624.7 628.3 629.8 630.0 638.2 783.9 792.6 797.8 800.8 803.4 11230.8 11358.5 11460.4 11704.8 12081.6 755.7 763.6 766.3 777.7 836.1 5234.0 5312.4 5323.5 5326.6 5358.7 77086.6 78408.6 78685.6 79491.6 80076.6 56148.4 58345.0 70895.0 72759.3 73102.0 57264.4 57725.4 57970.0 58270.0 58587.4 11145.2 11847.8 12337.0 12690.8 12865.9 40642.8 40744.8 41418.4 41542.4 41698.8 53580.7 53977.0 54054.0 55323.0 55405.2 14006.2 14098.9 14229.4 14354.6 14469.8 16494.9 16558.0 16955.4 17159.3 17354.3 15235.2 16171.0 16295.3 16471.1 16824.7 14713.4 14753.1 15521.3 15541.2 15563.4 14884.2 15477.2 15984.3 16309.9 16339.3 10979.7 11324.7 11433.5 11726.5 12013.9 12885.1 13670.2 13670.5 13807.5 13867.4 09:28:49 INFO - PROCESS | 20395 | -------- Summary: end -------- 09:28:49 INFO - PROCESS | 20395 | 09:28:49 INFO - TEST-INFO | 20395: exit 0 09:28:49 INFO - TEST-OK | dromaeo_css | took 1115406ms 09:28:49 INFO - TEST-START | kraken 09:28:49 INFO - operating with platform_type : linux_ 09:28:49 INFO - Initialising browser for kraken test... 09:28:50 INFO - TEST-INFO | started process 20704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphZUmr0/profile http://localhost:36682/getInfo.html) 09:28:53 INFO - PROCESS | 20704 | __metrics Screen width/height:1600/1200 09:28:53 INFO - PROCESS | 20704 | colorDepth:24 09:28:53 INFO - PROCESS | 20704 | Browser inner width/height: 1024/697 09:28:53 INFO - PROCESS | 20704 | __metrics 09:28:54 INFO - PROCESS | 20704 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:28:54 INFO - PROCESS | 20704 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:28:55 INFO - TEST-INFO | 20704: exit 0 09:28:55 INFO - Browser initialized. 09:28:55 INFO - Running cycle 1/1 for kraken test... 09:28:55 INFO - TEST-INFO | started process 20797 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphZUmr0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:29:07 INFO - PROCESS | 20797 | RSS: Main: 141758464 09:29:07 INFO - PROCESS | 20797 | 09:29:09 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36682/tests/kraken/driver.html?testName=audio-beat-detection) 09:29:10 INFO - PROCESS | 20797 | RSS: Main: 184107008 09:29:10 INFO - PROCESS | 20797 | 09:29:13 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36682/tests/kraken/driver.html?testName=audio-dft) 09:29:13 INFO - PROCESS | 20797 | RSS: Main: 173449216 09:29:13 INFO - PROCESS | 20797 | 09:29:16 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36682/tests/kraken/driver.html?testName=audio-fft) 09:29:17 INFO - PROCESS | 20797 | RSS: Main: 265678848 09:29:17 INFO - PROCESS | 20797 | 09:29:19 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36682/tests/kraken/driver.html?testName=audio-oscillator) 09:29:19 INFO - PROCESS | 20797 | RSS: Main: 171560960 09:29:19 INFO - PROCESS | 20797 | 09:29:22 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36682/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:29:22 INFO - PROCESS | 20797 | RSS: Main: 174325760 09:29:22 INFO - PROCESS | 20797 | 09:29:25 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36682/tests/kraken/driver.html?testName=imaging-darkroom) 09:29:26 INFO - PROCESS | 20797 | RSS: Main: 212787200 09:29:26 INFO - PROCESS | 20797 | 09:29:29 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36682/tests/kraken/driver.html?testName=imaging-desaturate) 09:29:30 INFO - PROCESS | 20797 | RSS: Main: 213065728 09:29:30 INFO - PROCESS | 20797 | 09:29:32 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36682/tests/kraken/driver.html?testName=json-parse-financial) 09:29:33 INFO - PROCESS | 20797 | RSS: Main: 211329024 09:29:33 INFO - PROCESS | 20797 | 09:29:35 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36682/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:29:35 INFO - PROCESS | 20797 | RSS: Main: 173289472 09:29:35 INFO - PROCESS | 20797 | 09:29:37 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36682/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:29:37 INFO - PROCESS | 20797 | RSS: Main: 180269056 09:29:37 INFO - PROCESS | 20797 | 09:29:39 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36682/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:29:40 INFO - PROCESS | 20797 | RSS: Main: 191303680 09:29:40 INFO - PROCESS | 20797 | 09:29:43 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:29:43 INFO - PROCESS | 20797 | RSS: Main: 199106560 09:29:43 INFO - PROCESS | 20797 | 09:29:46 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:29:47 INFO - PROCESS | 20797 | RSS: Main: 195485696 09:29:47 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | Cycle 1(1): loaded http://localhost:36682/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36682/tests/kraken/driver.html?testName=ai-astar) 09:29:48 INFO - PROCESS | 20797 | RSS: Main: 247181312 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | __start_tp_report 09:29:48 INFO - PROCESS | 20797 | _x_x_mozilla_page_load 09:29:48 INFO - PROCESS | 20797 | _x_x_mozilla_page_load_details 09:29:48 INFO - PROCESS | 20797 | |i|pagename|runs| 09:29:48 INFO - PROCESS | 20797 | |0;ai-astar;104;112;111;113;114;112;111;111;111;111 09:29:48 INFO - PROCESS | 20797 | |1;audio-beat-detection;145;114;114;159;115;115;123;113;147;116 09:29:48 INFO - PROCESS | 20797 | |2;audio-dft;161;162;160;159;160;160;161;159;161;160 09:29:48 INFO - PROCESS | 20797 | |3;audio-fft;74;78;75;110;75;76;76;77;102;76 09:29:48 INFO - PROCESS | 20797 | |4;audio-oscillator;82;82;113;81;82;81;100;82;81;114 09:29:48 INFO - PROCESS | 20797 | |5;imaging-gaussian-blur;113;113;113;113;112;113;113;113;112;113 09:29:48 INFO - PROCESS | 20797 | |6;imaging-darkroom;147;147;147;146;146;146;146;146;146;146 09:29:48 INFO - PROCESS | 20797 | |7;imaging-desaturate;94;93;94;93;94;94;94;94;93;94 09:29:48 INFO - PROCESS | 20797 | |8;json-parse-financial;57;53;54;54;55;54;55;55;54;55 09:29:48 INFO - PROCESS | 20797 | |9;json-stringify-tinderbox;55;54;55;55;58;55;55;55;55;55 09:29:48 INFO - PROCESS | 20797 | |10;stanford-crypto-aes;79;79;77;80;85;76;84;81;75;80 09:29:48 INFO - PROCESS | 20797 | |11;stanford-crypto-ccm;130;128;123;122;130;135;118;118;159;133 09:29:48 INFO - PROCESS | 20797 | |12;stanford-crypto-pbkdf2;163;167;159;163;164;163;162;162;158;161 09:29:48 INFO - PROCESS | 20797 | |13;stanford-crypto-sha256-iterative;66;58;63;64;61;60;58;58;58;58 09:29:48 INFO - PROCESS | 20797 | __end_tp_report 09:29:48 INFO - PROCESS | 20797 | __start_cc_report 09:29:48 INFO - PROCESS | 20797 | _x_x_mozilla_cycle_collect,1663 09:29:48 INFO - PROCESS | 20797 | __end_cc_report 09:29:48 INFO - PROCESS | 20797 | __startTimestamp1494606588950__endTimestamp 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | ------- Summary: start ------- 09:29:48 INFO - PROCESS | 20797 | Number of tests: 14 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.50 stddev:2.67 (2.4%) stddev-sans-first:1.09 09:29:48 INFO - PROCESS | 20797 | Values: 104.0 112.0 111.0 113.0 114.0 112.0 111.0 111.0 111.0 111.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#1] audio-beat-detection Cycles:10 Average:126.10 Median:119.50 stddev:17.32 (14.5%) stddev-sans-first:16.96 09:29:48 INFO - PROCESS | 20797 | Values: 145.0 114.0 114.0 159.0 115.0 115.0 123.0 113.0 147.0 116.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 09:29:48 INFO - PROCESS | 20797 | Values: 161.0 162.0 160.0 159.0 160.0 160.0 161.0 159.0 161.0 160.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#3] audio-fft Cycles:10 Average:81.90 Median:76.00 stddev:12.89 (17.0%) stddev-sans-first:13.35 09:29:48 INFO - PROCESS | 20797 | Values: 74.0 78.0 75.0 110.0 75.0 76.0 76.0 77.0 102.0 76.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:81.50 stddev:13.76 (16.9%) stddev-sans-first:14.30 09:29:48 INFO - PROCESS | 20797 | Values: 82.0 82.0 113.0 81.0 82.0 81.0 100.0 82.0 81.0 114.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:29:48 INFO - PROCESS | 20797 | Values: 113.0 113.0 113.0 113.0 112.0 113.0 113.0 113.0 112.0 113.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 09:29:48 INFO - PROCESS | 20797 | Values: 147.0 147.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 09:29:48 INFO - PROCESS | 20797 | Values: 94.0 93.0 94.0 93.0 94.0 94.0 94.0 94.0 93.0 94.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.07 (2.0%) stddev-sans-first:0.71 09:29:48 INFO - PROCESS | 20797 | Values: 57.0 53.0 54.0 54.0 55.0 54.0 55.0 55.0 54.0 55.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:55.00 stddev:1.03 (1.9%) stddev-sans-first:1.09 09:29:48 INFO - PROCESS | 20797 | Values: 55.0 54.0 55.0 55.0 58.0 55.0 55.0 55.0 55.0 55.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:80.00 stddev:3.20 (4.0%) stddev-sans-first:3.39 09:29:48 INFO - PROCESS | 20797 | Values: 79.0 79.0 77.0 80.0 85.0 76.0 84.0 81.0 75.0 80.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#11] stanford-crypto-ccm Cycles:10 Average:129.60 Median:130.00 stddev:11.92 (9.2%) stddev-sans-first:12.64 09:29:48 INFO - PROCESS | 20797 | Values: 130.0 128.0 123.0 122.0 130.0 135.0 118.0 118.0 159.0 133.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:163.00 stddev:2.53 (1.6%) stddev-sans-first:2.67 09:29:48 INFO - PROCESS | 20797 | Values: 163.0 167.0 159.0 163.0 164.0 163.0 162.0 162.0 158.0 161.0 09:29:48 INFO - PROCESS | 20797 | 09:29:48 INFO - PROCESS | 20797 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:60.50 stddev:2.99 (4.9%) stddev-sans-first:2.39 09:29:48 INFO - PROCESS | 20797 | Values: 66.0 58.0 63.0 64.0 61.0 60.0 58.0 58.0 58.0 58.0 09:29:48 INFO - PROCESS | 20797 | -------- Summary: end -------- 09:29:48 INFO - PROCESS | 20797 | 09:29:49 INFO - TEST-INFO | 20797: exit 0 09:29:49 INFO - TEST-OK | kraken | took 59983ms 09:29:49 INFO - SUITE-END | took 1175s 09:29:50 INFO - Completed test suite (00:19:35) 09:29:50 INFO - javascript benchmark 09:29:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7922.5906462562, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3336.6329703732595, 3370.2248482848936, 3376.3922493951095, 3394.083597428696, 3419.777714448561, 5610.551155907528, 5649.124385720214, 5662.292213473316, 5680.914786278199, 5693.373728889829, 5684.772609095631, 5747.080467125261, 5750.453706897838, 5753.395893431192, 5755.107958266465, 5201.739913004353, 5203.089459344618, 5211.973940130313, 5235.685858848462, 5279.260903473498, 5022.522860328263, 5056.140456122468, 5285.233641122035, 5291.920621190666, 5324.787008519674, 5143.12566863634, 5148.871278218037, 5171.3535808024, 5175.171972484403, 5188.377394712621, 5257.447967963366, 5265.526102650741, 5270.420253772101, 5271.815486457956, 5298.337707786527, 4996.525330093636, 5105.33630628016, 5163.690178589259, 5199.688018718851, 5214.713190774506, 5532.446755324436, 5540.806071787509, 5608.327000759935, 5622.409646987089, 5629.183768353607, 6019.036954087325, 6077.24034495688, 6079.969600151971, 6089.604175728564, 6090.786822461193, 6137.723802428902, 6150.477209437203, 6150.7232174552255, 6154.138420621117, 6156.3843615638525, 5472.097103977839, 5502.394736578973, 5657.32112146545, 5682.090865461589, 5709.628874123168, 6243.125962365272, 6250.093736408196, 6254.186955695822, 6258.311585725566, 6266.811995640138, 5957.3744756799915, 6011.789587364421, 6019.638821670714, 6024.036154215392, 6052.243469566304, 5853.0, 5887.793927212527, 6000.729967151488, 6119.96940015306, 6127.325994140641, 6087.847803804941, 6093.360197179321, 6094.481969032593, 6094.847628809315, 6094.908576371358, 5859.707014649251, 5886.470217680429, 5887.499562537147, 6015.789447369321, 6056.121862329937, 4567.908641827211, 4659.44086709597, 4660.067986402665, 4678.929816052762, 4682.157211701859, 4173.7078404512, 4179.582041795796, 4182.853600124042, 4185.937210941838, 4204.201201771653, 5638.379778224474, 5681.801136960269, 5774.364819869811, 5794.797182098607, 5809.50619197373, 6103.816885493442, 6106.236720409949, 6116.701002199504, 6117.553376797925, 6141.7543298268465, 5846.0, 5853.502452291517, 5854.151148083504, 5859.853503662443, 5862.472377485961, 5008.774605142778, 5045.646804723708, 5144.536991670768, 5168.870778230575, 5183.481651834862, 6333.1766870306565, 6344.397282258178, 6349.936500635119, 6351.841203969938, 6390.616563006234, 6137.110119032626, 6144.754209831557, 6148.692565371624, 6151.6924153793025, 6154.3845615438095, 6124.075808605094, 6130.601510901777, 6213.9067913980425, 6228.159198508325, 6320.336364681826, 6021.0, 6023.397660233942, 6025.0359942409, 6047.304559975659, 6055.394460553909, 6130.356312587114, 6131.877362452815, 6138.109974053648, 6143.385661433821, 6147.262328520606, 5677.233573467529, 5798.159266906359, 5907.2911250650195, 5988.610740301867, 5997.820065398045, 5989.401059893976, 5994.520438364833, 6008.429199226067, 6014.63912165254, 6029.186059881859, 5924.229850119457, 5972.671503067199, 5975.880482390415, 5976.282846058501, 5976.641401515923, 5665.405132461032, 5690.544756419394, 5732.140178973187, 5924.940750592439, 5970.373110823475, 5854.531637469077, 5865.296164460292, 5867.295924488918, 5896.0, 5909.497692695997], "value": 5611.323207636506, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.1308560583359, 853.1042405474252, 858.1890113842418, 938.4744543055893, 939.8402271613825, 31069.689303106963, 42418.78790606047, 43248.0, 47712.0, 48010.27984580234, 45433.77283113588, 45460.0, 45566.772166139126, 45573.0, 45709.542904570946, 7764.456488045839, 7891.368690504746, 9252.722418327461, 9393.154616084552, 10738.140948724084, 10764.946175269151, 10875.347479151236, 10892.346459212473, 10904.236703430763, 10905.781884362308, 10940.890591094107, 10951.83572246413, 11001.724956876062, 11003.834942475869, 11011.504482298316, 10898.0, 10909.563617455293, 10941.070009049201, 10972.0, 10974.012338889499, 10485.10876575492, 10551.47242637865, 10560.155187585051, 10560.419176945265, 10563.947180264126, 42956.3556546652, 43133.784331078146, 43174.78412607948, 43219.0, 43255.35116973217, 4279.165562715271, 4294.055307832303, 4304.182205380999, 4406.493253275882, 4444.022315090675, 30765.846170769226, 30825.0, 30997.38005239905, 31132.8443357784, 32060.198495037577, 4394.099209662044, 4420.955790442086, 4487.708298960557, 4552.408186935711, 4574.336721175444, 228089.0, 229290.0, 229640.0, 230222.84888575616, 230263.0, 51487.0, 51632.22551661728, 51725.48274517206, 51729.741351293, 51804.0, 54576.72711636496, 54819.17771233435, 54853.72573137109, 54862.45137548573, 54872.176917346274, 14936.477223297132, 15307.846921530641, 15345.46290879814, 15348.0, 15355.692886142439, 11311.095112390989, 11491.942540287413, 13051.477940882258, 13110.803337949937, 13305.667358315926, 13285.13646612967, 13344.933275333562, 13348.799768003488, 13434.865651343556, 13436.059475836793, 5435.320584926884, 5443.918341224885, 5506.697131657797, 5565.499105080562, 5588.524975377139, 1014.7108074198818, 1026.8973102689672, 1029.9330543514798, 1033.121846430528, 1035.1046344911597, 28147.43705125886, 28356.574651380244, 28406.431871362456, 28576.428471430456, 28652.283692907844, 1077.2674581284803, 1081.2377274021796, 1095.0254273696416, 1111.6276047524009, 1128.971775705614, 14536.200508972108, 15012.249387530579, 15123.773143402857, 15207.467738629095, 15240.390384384722, 306950.0, 307119.0, 308398.0, 308444.0, 310477.0, 53867.0, 53993.73003135038, 54096.18855717168, 54131.45868541343, 54283.45716542863, 1496.513633069257, 1502.3389708528211, 1533.4479587348546, 1536.377767004365, 1549.3260431712242, 28019.57970630483, 28519.42961140807, 29117.272068198465, 29415.11754647364, 29515.85242073776, 40353.39469908013, 40470.392944106454, 40498.797506012284, 40526.79736601298, 40577.391339129346, 297035.0, 297952.0, 299043.0, 299137.0, 299214.0, 25700.0, 26104.477910441306, 27983.0, 29712.702872970993, 30942.845285773426, 39184.21631567296, 39347.60652393554, 39382.409263861635, 39422.60577394189, 40436.79781601191, 30616.0, 30624.693753062184, 30782.538261926537, 30883.536746948364, 30976.845115775177, 30692.846535767178, 30727.0, 30931.0, 31325.0, 31525.842370788, 35731.642683572834, 35896.4615530762, 36012.4598131023, 36303.0, 36438.4534231992, 28824.85587572049, 29125.0, 29217.85391073031, 29284.41431171322, 29372.853135734185, 23475.0, 24896.0, 24910.0, 24910.501789963735, 25304.240872773848], "value": 18684.98844481509, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.99302510462326, 474.79583778975046, 476.71158948835955, 479.2715073088907, 493.7506559187611, 20853.27013554524, 21371.57256854862, 22395.32814015578, 22457.32628021162, 22624.773752262474, 22674.54650906981, 22791.0, 22900.541989160247, 22990.770092299073, 23124.884375578098, 6603.042558828967, 6619.371159739817, 6786.796396108125, 6806.931930680704, 6829.0, 6723.361280678352, 6837.760678376258, 7645.082590089197, 7957.084935232465, 7987.600619969007, 7832.334251588622, 8000.31997280229, 8160.428769986073, 8171.428000039969, 8211.671533138668, 7973.521588704696, 8254.54599997, 8297.336213102937, 8298.958505207465, 8304.958475207615, 7909.090454597737, 7949.046114466243, 7950.045994480642, 7954.164812694699, 7961.840763184704, 29109.41781164365, 29435.852820735974, 30128.24679383011, 30192.396152076835, 30425.391492170034, 3283.7506308178736, 3348.631650518441, 3363.899083027513, 3419.81191034493, 3468.7745296555645, 17180.22688978999, 17833.910830445766, 18226.27094916202, 18634.778770279223, 18655.0, 2295.1507942061544, 2332.6267797152373, 2338.590746619335, 2349.060375849657, 2388.9163879264315, 112369.0, 116472.0, 117185.41407292994, 117352.41323793412, 117781.0, 19629.21483140687, 20283.0, 20762.754234745964, 21215.802128478623, 21765.23821666234, 42015.7899210502, 42223.57776422258, 42248.0, 42253.36619950703, 42563.7871810639, 10952.561897524169, 10992.560297588167, 11169.162312826571, 11368.94315528417, 11476.655700329004, 8883.37816352862, 8930.776730581787, 8948.776280592907, 9060.682876099305, 9109.407888487227, 10652.946735266274, 11188.517626363024, 11302.491387887565, 11362.375069371099, 11475.0, 4312.676549258818, 4383.561643835591, 4432.84485043022, 4479.395973822907, 4602.7008244464005, 964.286428043243, 964.5804075227298, 965.2471072563412, 967.0812727908375, 973.9464329461806, 18956.715649265272, 20107.0, 20479.07844147017, 20535.4866128348, 20911.477213069797, 972.2853702528636, 981.474910922653, 982.1651596143364, 987.7283746969641, 992.6178421307849, 10718.464076796128, 11173.759609713092, 11525.88474115248, 12089.758204835854, 12552.058595605366, 474559.0, 483229.0, 504550.0, 753305.0, 768123.0, 69863.91887796356, 109324.4533777326, 116296.0, 122301.38849305696, 123117.0, 1415.1721243072918, 1429.6068581140269, 1439.8848092152602, 1480.5558332500207, 1482.9629259268604, 18080.819191807914, 18628.0, 18648.0, 18944.810551894858, 19082.713759293343, 49625.75187124186, 49653.503464966336, 51242.13199746018, 51489.22766158436, 52172.217416739535, 67116.0, 67910.0, 68175.3182468169, 69406.30593693999, 70565.64717176382, 12020.459079341275, 12161.756764864476, 12206.633800985985, 12365.443555039865, 12573.245605263714, 13916.095453795471, 14080.436782528584, 14193.716125677634, 14479.203643799692, 14527.983041187015, 11371.829422558834, 11387.829182562435, 11445.0, 11566.0, 11865.762684746083, 11548.769024619292, 11630.592929247436, 11653.242539234923, 11761.353125578175, 12032.097592680759, 11776.175667703179, 12047.879521205026, 12290.692732681755, 12299.569515066929, 12433.316167610868, 12105.757884842078, 12117.939410302892, 12211.084168687206, 12284.877151228373, 12346.444410001752, 10176.592936282466, 10351.171906247331, 11788.528458861892, 12152.331621760888, 12169.756604868031], "value": 12018.404999226428, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1547.3114464063497, 1562.3047743754028, 1595.0350038226866, 1600.8319126491717, 1603.3907115296186, 10856.131509479243, 11381.772364552715, 11383.829242561347, 11396.829047564293, 11417.372044537558, 11298.604548840793, 11454.48454819533, 11743.41282935852, 11760.647180584574, 11778.05775537957, 2443.560159171344, 2491.700995880497, 2492.8753562321813, 2496.5131799299143, 2497.063601149569, 2414.2274472168915, 2475.9133430329944, 2520.6975162980375, 2540.7840333571667, 2541.8474891506567, 2499.950000999979, 2508.824382293231, 2509.6988361396566, 2549.7450254974538, 2553.5531282025663, 2387.961236861962, 2410.95178096438, 2422.3701837522194, 2445.364205306615, 2478.0211816332626, 2412.963805542918, 2426.405530645003, 2439.0609615298063, 2441.865697386643, 2443.5357282116343, 11688.94155529217, 11980.760384792342, 11993.340366279852, 12010.579629712918, 12018.218815777034, 5813.593048486608, 5946.286445626509, 5960.523158147338, 5965.194698715662, 5965.701714914237, 3453.4819777033395, 3534.4874993125854, 3568.6074531801482, 3594.820258987066, 3625.7099432045375, 1244.4213440750095, 1270.1299609767339, 1275.2667216350635, 1285.4344088600985, 1336.0647546717337, 15492.612684682861, 15820.76268855968, 16807.999999999876, 17901.552461188443, 18060.819391806046, 4659.673822832437, 4783.6651434399955, 4809.975950120227, 4900.460949295574, 4914.557689807934, 11362.0, 11623.360715160747, 12457.626271211879, 13365.131266467648, 13439.664008399868, 5964.701764911737, 6118.724657390429, 6131.816045518642, 6135.0, 6141.140240366352, 5586.525145362609, 5682.176084467729, 5692.117721753135, 5702.48677619008, 5731.713414329267, 3971.543272523639, 4019.0756126091164, 4043.3934909763184, 4088.8773336799945, 4104.589541045872, 956.7751578379075, 960.8558716192628, 975.0931633580838, 977.2719324103447, 977.6333874796951, 1012.6202673997251, 1030.2788048366174, 1034.384541197986, 1039.3244391145815, 1042.5673345561506, 6691.46468282546, 6939.861202776017, 6940.757073502403, 7057.329553692391, 7075.221725610281, 1043.546057465005, 1062.6493257225097, 1073.7100982734619, 1092.9726756830985, 1101.2016288190998, 4376.912461750747, 4592.816287348535, 4603.654725895572, 4617.746023968714, 4671.906561868744, 14903.627409314853, 15012.699746005019, 15470.5358839235, 15522.301496432689, 15529.611759705871, 14739.55781326562, 14864.851351486346, 15108.244587770787, 15200.0, 15205.847941520664, 10949.343039417661, 11148.665540033811, 11155.05182059534, 11179.72050698739, 11198.944005279922, 7081.787546373617, 7305.232950540117, 7382.5939573323985, 7536.171021187774, 7546.509476883985, 12830.973522118022, 13077.0, 13180.736385272048, 13196.538121165713, 13257.0, 13438.932805335911, 13485.190888546718, 13493.662658433224, 13549.729005420033, 13617.65955851072, 8751.824963500821, 9035.186833185046, 9676.951615241878, 9811.460369679737, 9877.654282100091, 11354.318740875575, 11397.316161030365, 11512.481938312629, 11522.596709115476, 11656.766864662828, 9527.237820974444, 9536.189423898768, 9622.278329125484, 9760.951195243979, 9921.107100361001, 9575.329726919048, 9576.377535460173, 9607.855882161633, 9695.272854536079, 9775.413475191512, 8343.45767525109, 8422.073571906963, 8478.067412584734, 8478.32173426112, 8541.743747687578, 6245.375462453719, 6344.746210151542, 6346.4605508532295, 6404.711787969646, 6438.484921206386, 6102.816915492543, 6252.906206406817, 6266.68666566679, 6407.775727849503, 6427.164468619049], "value": 5458.0212067342, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.3795944043375, 427.4187105536471, 440.2471773267716, 444.85097492340066, 450.80390030336787, 5102.601777164736, 5327.4406187350305, 5335.653182543132, 5354.277172581706, 5372.382176049756, 5155.793768249266, 5163.845084647466, 5178.326817513727, 5196.662216955895, 5211.42674305827, 4335.913281734363, 4417.0, 4420.823167073313, 4426.77866106695, 4441.2893936970095, 4422.734635921839, 4433.179861725575, 4435.580614203456, 4448.199324121657, 4468.285074388098, 4457.7072648931935, 4460.821567137311, 4469.7541635210055, 4476.8885624490995, 4482.305242687388, 4338.414314067592, 4362.825486980517, 4367.847125350598, 4384.276594361926, 4426.897223818661, 4149.937750933738, 4159.729617574878, 4165.667226353252, 4165.770882601456, 4182.64447521961, 5101.081805275049, 5102.0, 5170.638055336128, 5170.9141080373165, 5193.170980161114, 4886.682365646222, 5075.467075957045, 5122.615803814729, 5132.228596557854, 5167.896642067138, 5504.862378440531, 5505.403433004424, 5543.334799824007, 5553.0, 5583.944160558383, 4502.167099086647, 4571.497135315112, 4597.747123908183, 4623.283391074355, 4652.74409907455, 5416.458354164592, 5488.914212599227, 5611.915821262725, 5704.258446401983, 5806.242035033368, 4637.9536204637525, 4712.410948631421, 4716.221823399101, 4772.284157376422, 4782.593479554207, 4789.808407663724, 4816.759162041954, 4822.0, 4825.938293575407, 4853.417589889236, 4945.9258111128365, 5092.879566495365, 5134.640575159778, 5152.355955505562, 5160.48395160481, 5530.446955304437, 5546.833594992157, 5548.4174161712435, 5549.306336707911, 5577.350758389814, 1617.3583812430393, 1619.2065887714934, 1621.4487074394763, 1623.1965177237344, 1635.8118816336037, 1250.0, 1286.2411177405188, 1296.3388671777273, 1297.7534268488957, 1299.1166007115253, 5318.361796584435, 5511.972440137773, 5532.419095994943, 5546.417626149277, 5556.972215138899, 5711.22951884917, 5740.799072032459, 5741.167530708024, 5756.654600723886, 5766.336871259775, 5427.43011983744, 5447.0858090921165, 5456.608564815947, 5467.507924286833, 5521.585881058937, 2885.8845646174336, 2928.3996780659913, 2962.674105848355, 2972.4246149540345, 2979.5977543031413, 5844.298684157843, 5874.295084589791, 5883.411658834168, 5896.174535565025, 5932.525397968152, 5482.616216864861, 5736.1682556029145, 5745.2818397700285, 5775.768969241183, 5811.88376232473, 5828.475437210672, 5832.387599302097, 5854.887571361428, 5876.324222714272, 5919.6744179070565, 5655.773769049357, 5655.868826234687, 5674.319081710056, 5680.346760122638, 5693.28833895763, 5226.921596175985, 5589.972050139723, 5603.32760068778, 5617.466340697627, 5620.325560932714, 5566.858793947312, 5737.196792449144, 5820.650760954356, 5832.358440571618, 5862.853428664317, 4900.62319774916, 5001.64988450805, 5021.61905475997, 5044.54599086084, 5063.1645778446145, 5446.591505637014, 5649.6327738696855, 5655.208270842003, 5679.744411501575, 5736.68448235351, 5520.254765606753, 5525.778968841201, 5527.8894422110525, 5537.307836520435, 5564.777408903598, 5461.617686761889, 5502.312210973629, 5593.776248950159, 5621.566500542335, 5662.631928924607, 5295.0, 5541.196526503554, 5549.861253468534, 5571.220029195835, 5575.275214222125, 5510.484616730271, 5590.329160500766, 5640.32316122068, 5656.123300888369, 5657.858553536194, 5113.718745469035, 5515.117581186991, 5527.5577953764405, 5535.280413546213, 5536.833894983157], "value": 4468.02419325562, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [896.5606852642203, 923.5433746235016, 929.4098247612764, 946.522006386775, 958.7075941837738, 18198.90900545497, 18712.25150993962, 18748.343807966725, 18753.437396878082, 18756.718649220238, 16815.663686726257, 16989.320427182927, 16991.405300814473, 17020.914895425536, 17036.91481542594, 879.406400679542, 899.6401439424217, 901.062894589626, 901.8016036471965, 904.1771987491351, 901.5582364641311, 901.6123067081153, 901.9819603607924, 902.5893218585528, 903.327021369081, 949.8717673114145, 950.8050849575854, 951.029949451559, 952.1478276942132, 952.7903861150536, 948.9715308540755, 950.3300173377752, 950.7575568230092, 951.0442005784178, 951.2009911674157, 694.4652617484509, 696.554205308603, 698.4761428928304, 698.5913240754165, 698.849747304332, 17922.731159032624, 18052.54868628282, 18079.0, 18153.7276940846, 18187.27250909962, 16014.839851601568, 16370.427035053715, 16488.0, 16853.494395168163, 17370.21834017472, 13060.673483162902, 13420.396082176228, 13648.454061837514, 13896.722065558733, 14120.223387713671, 4060.959390406117, 4147.937780933288, 4174.645155161815, 4175.895602609929, 4261.637760790337, 63348.0, 85440.0, 87164.0, 87878.5606071972, 89489.55255223683, 12880.0, 12955.935220324027, 13504.324783760772, 13820.489284431513, 13833.377498012613, 24649.63025554618, 25775.355616109748, 25869.482610347688, 26169.73830261673, 26480.0, 9227.123423274777, 9381.390209636353, 9392.67125650599, 9408.0, 9454.48000359973, 5086.491350864884, 5197.688138711689, 5199.142141546602, 5291.232771248225, 5303.178007408857, 3700.740948133605, 3790.639889210521, 3851.326017946848, 3854.7879866607045, 3872.0319920019997, 624.709510077816, 628.3402427451249, 629.7921685843661, 629.9874002519924, 638.2277444292458, 783.8628240057967, 792.5680504125265, 797.8165022044963, 800.7837883771349, 803.42555073123, 11230.831537526943, 11358.488868000959, 11460.369679667698, 11704.765904681859, 12081.637550873487, 755.7014979083232, 763.5533213070416, 766.3294617209941, 777.7316825695126, 836.1095433363448, 5234.0, 5312.415634280226, 5323.547498462596, 5326.573874090063, 5358.651687640291, 77086.6145669268, 78408.60795695985, 78685.60657196677, 79491.60254198808, 80076.59961700154, 56148.43851561514, 58345.0, 70895.0, 72759.27240727525, 73102.0, 57264.427355726744, 57725.42274577284, 57970.0, 58270.0, 58587.414125858195, 11145.164112691418, 11847.76304473923, 12336.996627260227, 12690.809637855624, 12865.871341286467, 40642.79678601588, 40744.79627601843, 41418.37872431856, 41542.376864347665, 41698.791506042275, 53580.73209633927, 53977.0, 54054.0, 55323.0, 55405.16892246539, 14006.229657368942, 14098.929505352407, 14229.359678814271, 14354.569362918712, 14469.78295325592, 16494.917525412297, 16558.00651960886, 16955.406560770312, 17159.27669061184, 17354.305827766748, 15235.238238088274, 16171.0, 16295.266712997707, 16471.094089825176, 16824.747628785823, 14713.411463541339, 14753.114813111248, 15521.301541430888, 15541.222938853238, 15563.377464901278, 14884.18137002432, 15477.226138693246, 15984.280707367963, 16309.918450407671, 16339.264733087277, 10979.670609881716, 11324.716882078013, 11433.542658293907, 11726.472308745957, 12013.879861201276, 12885.115024339055, 13670.17978921268, 13670.52153174634, 13807.516771828075, 13867.4453021878], "value": 8046.5369226197745, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1463.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 111.0, 113.0, 114.0, 112.0, 111.0, 111.0, 111.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 114.0, 114.0, 159.0, 115.0, 115.0, 123.0, 113.0, 147.0, 116.0], "value": 126.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 159.0, 160.0, 160.0, 161.0, 159.0, 161.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 75.0, 110.0, 75.0, 76.0, 76.0, 77.0, 102.0, 76.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 113.0, 81.0, 82.0, 81.0, 100.0, 82.0, 81.0, 114.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 112.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 54.0, 54.0, 55.0, 54.0, 55.0, 55.0, 54.0, 55.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 55.0, 58.0, 55.0, 55.0, 55.0, 55.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 77.0, 80.0, 85.0, 76.0, 84.0, 81.0, 75.0, 80.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 128.0, 123.0, 122.0, 130.0, 135.0, 118.0, 118.0, 159.0, 133.0], "value": 129.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 167.0, 159.0, 163.0, 164.0, 163.0, 162.0, 162.0, 158.0, 161.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 58.0, 63.0, 64.0, 61.0, 60.0, 58.0, 58.0, 58.0, 58.0], "value": 60.4, "unit": "score"}]}]} 09:29:50 INFO - Return code: 0 09:29:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:29:50 INFO - # TBPL SUCCESS # 09:29:50 INFO - Running post-action listener: _resource_record_post_action 09:29:50 INFO - [mozharness: 2017-05-12 16:29:50.288998Z] Finished run-tests step (success) 09:29:50 INFO - Running post-run listener: _resource_record_post_run 09:29:50 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 8192; Write bytes: 327131136; Read time: 24; Write time: 191424 09:29:50 INFO - TinderboxPrint: CPU usage
13.1% 09:29:50 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 09:29:50 INFO - TinderboxPrint: I/O write bytes / time
327,131,136 / 191,424 09:29:50 INFO - TinderboxPrint: CPU idle
8,254.5 (86.9%) 09:29:50 INFO - TinderboxPrint: CPU user
1,198.3 (12.6%) 09:29:50 INFO - TinderboxPrint: Swap in / out
0 / 0 09:29:50 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 98844672; Read time: 0; Write time: 76756 09:29:50 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 227700736; Read time: 8; Write time: 114644 09:29:50 INFO - Running post-run listener: _upload_blobber_files 09:29:50 INFO - Blob upload gear active. 09:29:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:29:50 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 09:29:50 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:29:50 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:29:51 INFO - (blobuploader) - INFO - Open directory for files ... 09:29:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:29:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:29:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:54 INFO - (blobuploader) - INFO - Done attempting. 09:29:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:29:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:29:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:55 INFO - (blobuploader) - INFO - Done attempting. 09:29:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:29:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:29:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:57 INFO - (blobuploader) - INFO - Done attempting. 09:29:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:29:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:29:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:58 INFO - (blobuploader) - INFO - Done attempting. 09:29:58 INFO - (blobuploader) - INFO - Iteration through files over. 09:29:58 INFO - Return code: 0 09:29:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:29:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:29:58 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8ffadcc149122d5d0290639f0d224ded18a8a809a06758e6afc91fb45792ccfbc22b19750e4bc07e801f8192070ada2f339cd84e85befde47ee0fcc99dbb8aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9b007a740c933b37f71b35d0b4a19aa21817403556819cedd1311e6806ecbf91807715675723f0306b3d74ecad6fa54b2cf77891e43506aeb011a56d952cf211", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10c814e18037c60a7bde18918e3e0fdef23034d1947964b06fed59b623314b25b84c31babdf04cac0263c8360b2e9045149e90567b5f23ddd70a3367f456189f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c01d1050be20fc8506379ba4bfa28c77bc41ff1d718583d6afbd00706a7622ef08f048f14e0e71c9949e6eb912aa320971ee0ceeb380d1e68de1ed123b7c0ac1"} 09:29:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:29:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:29:58 INFO - Contents: 09:29:58 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8ffadcc149122d5d0290639f0d224ded18a8a809a06758e6afc91fb45792ccfbc22b19750e4bc07e801f8192070ada2f339cd84e85befde47ee0fcc99dbb8aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9b007a740c933b37f71b35d0b4a19aa21817403556819cedd1311e6806ecbf91807715675723f0306b3d74ecad6fa54b2cf77891e43506aeb011a56d952cf211", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10c814e18037c60a7bde18918e3e0fdef23034d1947964b06fed59b623314b25b84c31babdf04cac0263c8360b2e9045149e90567b5f23ddd70a3367f456189f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c01d1050be20fc8506379ba4bfa28c77bc41ff1d718583d6afbd00706a7622ef08f048f14e0e71c9949e6eb912aa320971ee0ceeb380d1e68de1ed123b7c0ac1"} 09:29:58 INFO - Running post-run listener: copy_logs_to_upload_dir 09:29:58 INFO - Copying logs to upload dir... 09:29:58 INFO - mkdir: /builds/slave/test/build/upload/logs 09:29:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.832550 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-05-12 09:29:58.239298) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 09:29:58.244093) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8ffadcc149122d5d0290639f0d224ded18a8a809a06758e6afc91fb45792ccfbc22b19750e4bc07e801f8192070ada2f339cd84e85befde47ee0fcc99dbb8aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9b007a740c933b37f71b35d0b4a19aa21817403556819cedd1311e6806ecbf91807715675723f0306b3d74ecad6fa54b2cf77891e43506aeb011a56d952cf211", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10c814e18037c60a7bde18918e3e0fdef23034d1947964b06fed59b623314b25b84c31babdf04cac0263c8360b2e9045149e90567b5f23ddd70a3367f456189f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c01d1050be20fc8506379ba4bfa28c77bc41ff1d718583d6afbd00706a7622ef08f048f14e0e71c9949e6eb912aa320971ee0ceeb380d1e68de1ed123b7c0ac1"} build_url:https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011530 build_url: 'https://queue.taskcluster.net/v1/task/aWfPAGdVT5Ky2X_YUeRbNA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8ffadcc149122d5d0290639f0d224ded18a8a809a06758e6afc91fb45792ccfbc22b19750e4bc07e801f8192070ada2f339cd84e85befde47ee0fcc99dbb8aab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9b007a740c933b37f71b35d0b4a19aa21817403556819cedd1311e6806ecbf91807715675723f0306b3d74ecad6fa54b2cf77891e43506aeb011a56d952cf211", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/10c814e18037c60a7bde18918e3e0fdef23034d1947964b06fed59b623314b25b84c31babdf04cac0263c8360b2e9045149e90567b5f23ddd70a3367f456189f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c01d1050be20fc8506379ba4bfa28c77bc41ff1d718583d6afbd00706a7622ef08f048f14e0e71c9949e6eb912aa320971ee0ceeb380d1e68de1ed123b7c0ac1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 09:29:58.272774) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:29:58.273118) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1494603758.817930-1407694723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:29:58.352448) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 09:29:58.355803) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 09:29:58.356124) ========= ========= Total master_lag: 0.18 =========