builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1474475850.65 results: success (0) buildid: 20160921064707 builduid: d59c220249f648a89aec3b17d9729364 revision: 1563e99859383adaf22a8c285c8ec70e1db2e49b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:30.650183) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:30.650703) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:30.651026) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:30.665826) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:30.666132) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 09:37:30-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.8M=0s 2016-09-21 09:37:31 (42.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080446 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:30.760829) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:30.761203) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019883 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:30.810555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:30.810990) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1563e99859383adaf22a8c285c8ec70e1db2e49b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1563e99859383adaf22a8c285c8ec70e1db2e49b --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=9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 09:37:31,178 truncating revision to first 12 chars 2016-09-21 09:37:31,178 Setting DEBUG logging. 2016-09-21 09:37:31,178 attempt 1/10 2016-09-21 09:37:31,178 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1563e9985938?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 09:37:31,654 unpacking tar archive at: mozilla-aurora-1563e9985938/testing/mozharness/ program finished with exit code 0 elapsedTime=0.814636 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:31.638870) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:31.639185) ========= script_repo_revision: 1563e99859383adaf22a8c285c8ec70e1db2e49b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:31.639550) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:31.639824) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 09:37:31.647289) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-21 09:37:31.647554) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:37:32 INFO - MultiFileLogger online at 20160921 09:37:32 in /builds/slave/test 09:37:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:37:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:37:32 INFO - {'append_to_log': False, 09:37:32 INFO - 'base_work_dir': '/builds/slave/test', 09:37:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:37:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:37:32 INFO - 'branch': 'Mozilla-Aurora', 09:37:32 INFO - 'buildbot_json_path': 'buildprops.json', 09:37:32 INFO - 'config_files': ('talos/linux_config.py',), 09:37:32 INFO - 'default_actions': ('clobber', 09:37:32 INFO - 'read-buildbot-config', 09:37:32 INFO - 'download-and-extract', 09:37:32 INFO - 'populate-webroot', 09:37:32 INFO - 'create-virtualenv', 09:37:32 INFO - 'install', 09:37:32 INFO - 'run-tests'), 09:37:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:37:32 INFO - 'download_minidump_stackwalk': True, 09:37:32 INFO - 'download_symbols': 'ondemand', 09:37:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:37:32 INFO - 'tooltool.py': '/tools/tooltool.py', 09:37:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:37:32 INFO - '/tools/misc-python/virtualenv.py')}, 09:37:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:37:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:37:32 INFO - 'installer_path': 'installer.exe', 09:37:32 INFO - 'log_level': 'info', 09:37:32 INFO - 'log_name': 'talos', 09:37:32 INFO - 'log_to_console': True, 09:37:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:37:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:37:32 INFO - 'opt_config_files': (), 09:37:32 INFO - 'pip_index': False, 09:37:32 INFO - 'sps_profile': False, 09:37:32 INFO - 'sps_profile_interval': 0, 09:37:32 INFO - 'suite': 'dromaeojs', 09:37:32 INFO - 'system_bits': '32', 09:37:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:37:32 INFO - 'title': 'talos-linux64-ix-022', 09:37:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:37:32 INFO - 'use_talos_json': True, 09:37:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:37:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:37:32 INFO - 'work_dir': 'build'} 09:37:32 INFO - [mozharness: 2016-09-21 16:37:32.107284Z] Running clobber step. 09:37:32 INFO - Running pre-action listener: _resource_record_pre_action 09:37:32 INFO - Running main action method: clobber 09:37:32 INFO - rmtree: /builds/slave/test/build 09:37:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:37:33 INFO - Running post-action listener: _resource_record_post_action 09:37:33 INFO - [mozharness: 2016-09-21 16:37:33.114911Z] Finished clobber step (success) 09:37:33 INFO - [mozharness: 2016-09-21 16:37:33.115009Z] Running read-buildbot-config step. 09:37:33 INFO - Running pre-action listener: _resource_record_pre_action 09:37:33 INFO - Running main action method: read_buildbot_config 09:37:33 INFO - Using buildbot properties: 09:37:33 INFO - { 09:37:33 INFO - "project": "", 09:37:33 INFO - "product": "firefox", 09:37:33 INFO - "script_repo_revision": "production", 09:37:33 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:37:33 INFO - "repository": "", 09:37:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:37:33 INFO - "buildid": "20160921064707", 09:37:33 INFO - "pgo_build": "True", 09:37:33 INFO - "basedir": "/builds/slave/test", 09:37:33 INFO - "buildnumber": 527, 09:37:33 INFO - "slavename": "talos-linux64-ix-022", 09:37:33 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:37:33 INFO - "platform": "ubuntu64_hw", 09:37:33 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:37:33 INFO - "branch": "mozilla-aurora", 09:37:33 INFO - "repo_path": "releases/mozilla-aurora", 09:37:33 INFO - "revision": "1563e99859383adaf22a8c285c8ec70e1db2e49b", 09:37:33 INFO - "stage_platform": "linux64-pgo", 09:37:33 INFO - "builduid": "d59c220249f648a89aec3b17d9729364", 09:37:33 INFO - "slavebuilddir": "test" 09:37:33 INFO - } 09:37:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 09:37:33 INFO - Running post-action listener: _resource_record_post_action 09:37:33 INFO - [mozharness: 2016-09-21 16:37:33.117481Z] Finished read-buildbot-config step (success) 09:37:33 INFO - [mozharness: 2016-09-21 16:37:33.117572Z] Running download-and-extract step. 09:37:33 INFO - Running pre-action listener: _resource_record_pre_action 09:37:33 INFO - Running main action method: download_and_extract 09:37:33 INFO - mkdir: /builds/slave/test/build/tests 09:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:33 INFO - trying https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:37:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:37:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:37:34 INFO - Downloaded 1361 bytes. 09:37:34 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:37:34 INFO - Using the following test package requirements: 09:37:34 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:37:34 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:37:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:37:34 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:37:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:37:34 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:37:34 INFO - u'jsshell-linux-x86_64.zip'], 09:37:34 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:37:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:37:34 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:37:34 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:37:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:37:34 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:37:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:37:34 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:37:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:37:34 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:37:34 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:37:34 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:37:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:37:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:37:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:37:35 INFO - Expected file size: 21893703 09:37:39 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:37:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:37:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:37:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:37:40 INFO - Expected file size: 21893703 09:37:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 09:37:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:37:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:37:45 INFO - Expected file size: 14414059 09:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:45 INFO - trying https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:37:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:37:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:37:46 INFO - Downloaded 63951270 bytes. 09:37:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:37:46 INFO - mkdir: /builds/slave/test/properties 09:37:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:37:46 INFO - Writing to file /builds/slave/test/properties/build_url 09:37:46 INFO - Contents: 09:37:46 INFO - build_url:https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:37:47 INFO - Running post-action listener: _resource_record_post_action 09:37:47 INFO - Running post-action listener: set_extra_try_arguments 09:37:47 INFO - [mozharness: 2016-09-21 16:37:47.656578Z] Finished download-and-extract step (success) 09:37:47 INFO - [mozharness: 2016-09-21 16:37:47.656809Z] Running populate-webroot step. 09:37:47 INFO - Running pre-action listener: _resource_record_pre_action 09:37:47 INFO - Running main action method: populate_webroot 09:37:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:37:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:37:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:37:47 INFO - '--tppagecycles', 09:37:47 INFO - '7'], 09:37:47 INFO - 'tests': ['tsvgm']}}, 09:37:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:37:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:37:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:37:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:37:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:37:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:37:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:47 INFO - 'talos_options': ['--disable-e10s'], 09:37:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:47 INFO - 'talos_options': ['--disable-e10s'], 09:37:47 INFO - 'tests': ['damp', 'tps']}, 09:37:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:47 INFO - 'tests': ['damp', 'tps']}, 09:37:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:37:47 INFO - 'tests': ['dromaeo_dom']}, 09:37:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:37:47 INFO - 'tests': ['basic_compositor_video']}, 09:37:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:37:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:37:47 INFO - 'tests': ['a11yr', 09:37:47 INFO - 'ts_paint', 09:37:47 INFO - 'tpaint', 09:37:47 INFO - 'sessionrestore', 09:37:47 INFO - 'sessionrestore_no_auto_restore', 09:37:47 INFO - 'tabpaint']}, 09:37:47 INFO - 'other-e10s': {'tests': ['a11yr', 09:37:47 INFO - 'ts_paint', 09:37:47 INFO - 'tpaint', 09:37:47 INFO - 'sessionrestore', 09:37:47 INFO - 'sessionrestore_no_auto_restore', 09:37:47 INFO - 'tabpaint']}, 09:37:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:37:47 INFO - 'ts_paint', 09:37:47 INFO - 'tpaint', 09:37:47 INFO - 'sessionrestore', 09:37:47 INFO - 'sessionrestore_no_auto_restore', 09:37:47 INFO - 'tabpaint']}, 09:37:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:37:47 INFO - 'ts_paint', 09:37:47 INFO - 'tpaint', 09:37:47 INFO - 'sessionrestore', 09:37:47 INFO - 'sessionrestore_no_auto_restore', 09:37:47 INFO - 'tabpaint']}, 09:37:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:37:47 INFO - 'tests': ['a11yr', 09:37:47 INFO - 'ts_paint', 09:37:47 INFO - 'tpaint', 09:37:47 INFO - 'sessionrestore', 09:37:47 INFO - 'sessionrestore_no_auto_restore', 09:37:47 INFO - 'tabpaint']}, 09:37:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:37:47 INFO - 'tests': ['a11yr', 09:37:47 INFO - 'ts_paint', 09:37:47 INFO - 'tpaint', 09:37:47 INFO - 'sessionrestore', 09:37:47 INFO - 'sessionrestore_no_auto_restore', 09:37:47 INFO - 'tabpaint']}, 09:37:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:37:47 INFO - 'tests': ['tsvgx', 09:37:47 INFO - 'tsvgr_opacity', 09:37:47 INFO - 'tart', 09:37:47 INFO - 'tscrollx', 09:37:47 INFO - 'cart']}, 09:37:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:37:47 INFO - 'tsvgr_opacity', 09:37:47 INFO - 'tart', 09:37:47 INFO - 'tscrollx', 09:37:47 INFO - 'cart']}, 09:37:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:47 INFO - 'talos_options': ['--disable-e10s'], 09:37:47 INFO - 'tests': ['tp5o']}, 09:37:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:47 INFO - 'tests': ['tp5o']}, 09:37:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:47 INFO - 'talos_options': ['--disable-e10s', 09:37:47 INFO - '--xperf_path', 09:37:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:47 INFO - 'tests': ['tp5n']}, 09:37:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:47 INFO - 'talos_options': ['--xperf_path', 09:37:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:47 INFO - 'tests': ['tp5n']}}, 09:37:47 INFO - 'talos.zip': {'path': '', 09:37:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:37:47 INFO - Running post-action listener: _resource_record_post_action 09:37:47 INFO - [mozharness: 2016-09-21 16:37:47.677311Z] Finished populate-webroot step (success) 09:37:47 INFO - [mozharness: 2016-09-21 16:37:47.677400Z] Running create-virtualenv step. 09:37:47 INFO - Running pre-action listener: _resource_record_pre_action 09:37:47 INFO - Running main action method: create_virtualenv 09:37:47 INFO - Creating virtualenv /builds/slave/test/build/venv 09:37:47 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:37:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:37:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:47 INFO - Using real prefix '/usr' 09:37:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:37:48 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:50 INFO - Installing pip.................done. 09:37:50 INFO - Return code: 0 09:37:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f5582b53f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23eeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ebf60>, '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 0x23ecd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ed1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', '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:37:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:37:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:37:50 INFO - Using env: {'DISPLAY': ':0', 09:37:50 INFO - 'HOME': '/home/cltbld', 09:37:50 INFO - 'LANG': 'en_US.UTF-8', 09:37:50 INFO - 'LANGUAGE': 'en_US:en', 09:37:50 INFO - 'LOGNAME': 'cltbld', 09:37:50 INFO - 'MAIL': '/var/mail/cltbld', 09:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:50 INFO - 'MOZ_NO_REMOTE': '1', 09:37:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:50 INFO - 'NO_EM_RESTART': '1', 09:37:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:50 INFO - 'PWD': '/builds/slave/test', 09:37:50 INFO - 'SHELL': '/bin/bash', 09:37:50 INFO - 'SHLVL': '1', 09:37:50 INFO - 'TERM': 'linux', 09:37:50 INFO - 'TMOUT': '86400', 09:37:50 INFO - 'USER': 'cltbld', 09:37:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', 09:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:50 INFO - '_': '/tools/buildbot/bin/python'} 09:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:50 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:37:50 INFO - Cleaning up... 09:37:50 INFO - Return code: 0 09:37:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f5582b53f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23eeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ebf60>, '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 0x23ecd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ed1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', '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:37:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:37:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:37:50 INFO - Using env: {'DISPLAY': ':0', 09:37:50 INFO - 'HOME': '/home/cltbld', 09:37:50 INFO - 'LANG': 'en_US.UTF-8', 09:37:50 INFO - 'LANGUAGE': 'en_US:en', 09:37:50 INFO - 'LOGNAME': 'cltbld', 09:37:50 INFO - 'MAIL': '/var/mail/cltbld', 09:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:50 INFO - 'MOZ_NO_REMOTE': '1', 09:37:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:50 INFO - 'NO_EM_RESTART': '1', 09:37:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:50 INFO - 'PWD': '/builds/slave/test', 09:37:50 INFO - 'SHELL': '/bin/bash', 09:37:50 INFO - 'SHLVL': '1', 09:37:50 INFO - 'TERM': 'linux', 09:37:50 INFO - 'TMOUT': '86400', 09:37:50 INFO - 'USER': 'cltbld', 09:37:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', 09:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:50 INFO - '_': '/tools/buildbot/bin/python'} 09:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:50 INFO - Downloading/unpacking psutil>=3.1.1 09:37: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:37: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:37:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37: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:37: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:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:37:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:52 INFO - Installing collected packages: psutil 09:37:52 INFO - Running setup.py install for psutil 09:37:52 INFO - building 'psutil._psutil_linux' extension 09:37:52 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:37:52 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:37:52 INFO - building 'psutil._psutil_posix' extension 09:37:52 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:37:53 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:37:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:53 INFO - Successfully installed psutil 09:37:53 INFO - Cleaning up... 09:37:53 INFO - Return code: 0 09:37:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:53 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f5582b53f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23eeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ebf60>, '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 0x23ecd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ed1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', '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:37:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:37:53 INFO - Using env: {'DISPLAY': ':0', 09:37:53 INFO - 'HOME': '/home/cltbld', 09:37:53 INFO - 'LANG': 'en_US.UTF-8', 09:37:53 INFO - 'LANGUAGE': 'en_US:en', 09:37:53 INFO - 'LOGNAME': 'cltbld', 09:37:53 INFO - 'MAIL': '/var/mail/cltbld', 09:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:53 INFO - 'MOZ_NO_REMOTE': '1', 09:37:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:53 INFO - 'NO_EM_RESTART': '1', 09:37:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:53 INFO - 'PWD': '/builds/slave/test', 09:37:53 INFO - 'SHELL': '/bin/bash', 09:37:53 INFO - 'SHLVL': '1', 09:37:53 INFO - 'TERM': 'linux', 09:37:53 INFO - 'TMOUT': '86400', 09:37:53 INFO - 'USER': 'cltbld', 09:37:53 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', 09:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:53 INFO - '_': '/tools/buildbot/bin/python'} 09:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:37:53 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:37:53 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:37:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:53 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:37:53 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:37:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:37:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:37:55 INFO - Installing collected packages: mozsystemmonitor 09:37:55 INFO - Running setup.py install for mozsystemmonitor 09:37:55 INFO - Successfully installed mozsystemmonitor 09:37:55 INFO - Cleaning up... 09:37:55 INFO - Return code: 0 09:37:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f5582b53f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23eeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ebf60>, '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 0x23ecd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ed1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', '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:37:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:37:55 INFO - Using env: {'DISPLAY': ':0', 09:37:55 INFO - 'HOME': '/home/cltbld', 09:37:55 INFO - 'LANG': 'en_US.UTF-8', 09:37:55 INFO - 'LANGUAGE': 'en_US:en', 09:37:55 INFO - 'LOGNAME': 'cltbld', 09:37:55 INFO - 'MAIL': '/var/mail/cltbld', 09:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:55 INFO - 'MOZ_NO_REMOTE': '1', 09:37:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:55 INFO - 'NO_EM_RESTART': '1', 09:37:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:55 INFO - 'PWD': '/builds/slave/test', 09:37:55 INFO - 'SHELL': '/bin/bash', 09:37:55 INFO - 'SHLVL': '1', 09:37:55 INFO - 'TERM': 'linux', 09:37:55 INFO - 'TMOUT': '86400', 09:37:55 INFO - 'USER': 'cltbld', 09:37:55 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', 09:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:55 INFO - '_': '/tools/buildbot/bin/python'} 09:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:55 INFO - Downloading/unpacking jsonschema==2.5.1 09:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:37: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:37: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:37:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37: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:37: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:37:57 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:57 INFO - warning: no files found matching '*.txt' 09:37:57 INFO - no previously-included directories found matching 'build' 09:37:57 INFO - no previously-included directories found matching 'dist' 09:37:57 INFO - no previously-included directories found matching '.git*' 09:37:57 INFO - Installing collected packages: jsonschema, functools32 09:37:57 INFO - Running setup.py install for functools32 09:37:58 INFO - warning: no files found matching '*.txt' 09:37:58 INFO - no previously-included directories found matching 'build' 09:37:58 INFO - no previously-included directories found matching 'dist' 09:37:58 INFO - no previously-included directories found matching '.git*' 09:37:58 INFO - Successfully installed jsonschema functools32 09:37:58 INFO - Cleaning up... 09:37:58 INFO - Return code: 0 09:37:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f5582b53f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23eeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ebf60>, '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 0x23ecd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ed1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', '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:37:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:37:58 INFO - Using env: {'DISPLAY': ':0', 09:37:58 INFO - 'HOME': '/home/cltbld', 09:37:58 INFO - 'LANG': 'en_US.UTF-8', 09:37:58 INFO - 'LANGUAGE': 'en_US:en', 09:37:58 INFO - 'LOGNAME': 'cltbld', 09:37:58 INFO - 'MAIL': '/var/mail/cltbld', 09:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:58 INFO - 'MOZ_NO_REMOTE': '1', 09:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:58 INFO - 'NO_EM_RESTART': '1', 09:37:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:58 INFO - 'PWD': '/builds/slave/test', 09:37:58 INFO - 'SHELL': '/bin/bash', 09:37:58 INFO - 'SHLVL': '1', 09:37:58 INFO - 'TERM': 'linux', 09:37:58 INFO - 'TMOUT': '86400', 09:37:58 INFO - 'USER': 'cltbld', 09:37:58 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', 09:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:58 INFO - '_': '/tools/buildbot/bin/python'} 09:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:37:58 INFO - Cleaning up... 09:37:58 INFO - Return code: 0 09:37:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f5582b53f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23eeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ebf60>, '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 0x23ecd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ed1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', '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:37:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:37:58 INFO - Using env: {'DISPLAY': ':0', 09:37:58 INFO - 'HOME': '/home/cltbld', 09:37:58 INFO - 'LANG': 'en_US.UTF-8', 09:37:58 INFO - 'LANGUAGE': 'en_US:en', 09:37:58 INFO - 'LOGNAME': 'cltbld', 09:37:58 INFO - 'MAIL': '/var/mail/cltbld', 09:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:58 INFO - 'MOZ_NO_REMOTE': '1', 09:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:58 INFO - 'NO_EM_RESTART': '1', 09:37:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:58 INFO - 'PWD': '/builds/slave/test', 09:37:58 INFO - 'SHELL': '/bin/bash', 09:37:58 INFO - 'SHLVL': '1', 09:37:58 INFO - 'TERM': 'linux', 09:37:58 INFO - 'TMOUT': '86400', 09:37:58 INFO - 'USER': 'cltbld', 09:37:58 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', 09:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:58 INFO - '_': '/tools/buildbot/bin/python'} 09:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:58 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:00 INFO - Downloading blobuploader-1.2.4.tar.gz 09:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:38:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:38:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:01 INFO - Downloading docopt-0.6.1.tar.gz 09:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:38:01 INFO - Installing collected packages: blobuploader, requests, docopt 09:38:01 INFO - Running setup.py install for blobuploader 09:38:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:38:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:38:01 INFO - Running setup.py install for requests 09:38:01 INFO - Running setup.py install for docopt 09:38:01 INFO - Successfully installed blobuploader requests docopt 09:38:01 INFO - Cleaning up... 09:38:01 INFO - Return code: 0 09:38:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:38:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:01 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f5582b53f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23eeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ebf60>, '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 0x23ecd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ed1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', '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:38:01 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:38:01 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:01 INFO - Using env: {'DISPLAY': ':0', 09:38:01 INFO - 'HOME': '/home/cltbld', 09:38:01 INFO - 'LANG': 'en_US.UTF-8', 09:38:01 INFO - 'LANGUAGE': 'en_US:en', 09:38:01 INFO - 'LOGNAME': 'cltbld', 09:38:01 INFO - 'MAIL': '/var/mail/cltbld', 09:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:01 INFO - 'MOZ_NO_REMOTE': '1', 09:38:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:01 INFO - 'NO_EM_RESTART': '1', 09:38:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:01 INFO - 'PWD': '/builds/slave/test', 09:38:01 INFO - 'SHELL': '/bin/bash', 09:38:01 INFO - 'SHLVL': '1', 09:38:01 INFO - 'TERM': 'linux', 09:38:01 INFO - 'TMOUT': '86400', 09:38:01 INFO - 'USER': 'cltbld', 09:38:01 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', 09:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:01 INFO - '_': '/tools/buildbot/bin/python'} 09:38:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:02 INFO - Running setup.py (path:/tmp/pip-RJweGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:02 INFO - Running setup.py (path:/tmp/pip-WBcT_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:02 INFO - Running setup.py (path:/tmp/pip-e5Tbfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:02 INFO - Running setup.py (path:/tmp/pip-9sHxy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:02 INFO - Running setup.py (path:/tmp/pip-1_K62M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:02 INFO - Running setup.py (path:/tmp/pip-xUqA73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:02 INFO - Running setup.py (path:/tmp/pip-g3mvrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:02 INFO - Running setup.py (path:/tmp/pip-hWDNJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:02 INFO - Running setup.py (path:/tmp/pip-f0OOX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:02 INFO - Running setup.py (path:/tmp/pip-tb3a9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:02 INFO - Running setup.py (path:/tmp/pip-ufS99Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:02 INFO - Running setup.py (path:/tmp/pip-uNdLzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:02 INFO - Running setup.py (path:/tmp/pip-nwr0sb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:02 INFO - Running setup.py (path:/tmp/pip-2Fz1uu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:03 INFO - Running setup.py (path:/tmp/pip-n3GL10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:03 INFO - Running setup.py (path:/tmp/pip-_WNw5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:03 INFO - Running setup.py (path:/tmp/pip-Z_nsTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:38:03 INFO - Running setup.py install for manifestparser 09:38:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:38:03 INFO - Running setup.py install for mozcrash 09:38:03 INFO - Running setup.py install for mozdebug 09:38:03 INFO - Running setup.py install for mozdevice 09:38:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:38:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:38:03 INFO - Running setup.py install for mozfile 09:38:03 INFO - Running setup.py install for mozhttpd 09:38:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:38:03 INFO - Running setup.py install for mozinfo 09:38:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:38:03 INFO - Running setup.py install for mozInstall 09:38:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:38:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:38:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:38:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:38:04 INFO - Running setup.py install for mozleak 09:38:04 INFO - Running setup.py install for mozlog 09:38:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:38:04 INFO - Running setup.py install for moznetwork 09:38:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:38:04 INFO - Running setup.py install for mozprocess 09:38:04 INFO - Running setup.py install for mozprofile 09:38:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:38:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:38:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:38:04 INFO - Running setup.py install for mozrunner 09:38:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:38:04 INFO - Running setup.py install for mozscreenshot 09:38:04 INFO - Running setup.py install for moztest 09:38:04 INFO - Running setup.py install for mozversion 09:38:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:38:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:38:05 INFO - Cleaning up... 09:38:05 INFO - Return code: 0 09:38:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f5582b53f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23eeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ebf60>, '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 0x23ecd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ed1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', '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:38:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:38:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:05 INFO - Using env: {'DISPLAY': ':0', 09:38:05 INFO - 'HOME': '/home/cltbld', 09:38:05 INFO - 'LANG': 'en_US.UTF-8', 09:38:05 INFO - 'LANGUAGE': 'en_US:en', 09:38:05 INFO - 'LOGNAME': 'cltbld', 09:38:05 INFO - 'MAIL': '/var/mail/cltbld', 09:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:05 INFO - 'MOZ_NO_REMOTE': '1', 09:38:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:05 INFO - 'NO_EM_RESTART': '1', 09:38:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:05 INFO - 'PWD': '/builds/slave/test', 09:38:05 INFO - 'SHELL': '/bin/bash', 09:38:05 INFO - 'SHLVL': '1', 09:38:05 INFO - 'TERM': 'linux', 09:38:05 INFO - 'TMOUT': '86400', 09:38:05 INFO - 'USER': 'cltbld', 09:38:05 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', 09:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:05 INFO - '_': '/tools/buildbot/bin/python'} 09:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:05 INFO - Running setup.py (path:/tmp/pip-PNiEUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:05 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:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:05 INFO - Running setup.py (path:/tmp/pip-yyUHP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:05 INFO - Running setup.py (path:/tmp/pip-LPu9yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:05 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:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:05 INFO - Running setup.py (path:/tmp/pip-3RDDGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:05 INFO - Running setup.py (path:/tmp/pip-Szf3K3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:05 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:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:05 INFO - Running setup.py (path:/tmp/pip-06ubGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:05 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:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:05 INFO - Running setup.py (path:/tmp/pip-xIF8f6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:05 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:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:05 INFO - Running setup.py (path:/tmp/pip-h5fO_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:05 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:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:05 INFO - Running setup.py (path:/tmp/pip-zjQyG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:05 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:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:05 INFO - Running setup.py (path:/tmp/pip-xHk_un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:06 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:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:06 INFO - Running setup.py (path:/tmp/pip-KdVoM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:06 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:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:06 INFO - Running setup.py (path:/tmp/pip-sVoAm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:06 INFO - Running setup.py (path:/tmp/pip-febfX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:06 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:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:06 INFO - Running setup.py (path:/tmp/pip-efSQid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:06 INFO - Running setup.py (path:/tmp/pip-iKEwD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:06 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:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:06 INFO - Running setup.py (path:/tmp/pip-yXuGFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:06 INFO - Running setup.py (path:/tmp/pip-d_Zm_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:06 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:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:06 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:38:06 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:06 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:08 INFO - Downloading blessings-1.6.tar.gz 09:38:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:38:08 INFO - Installing collected packages: blessings 09:38:08 INFO - Running setup.py install for blessings 09:38:08 INFO - Successfully installed blessings 09:38:08 INFO - Cleaning up... 09:38:08 INFO - Return code: 0 09:38:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:38:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:08 INFO - Reading from file tmpfile_stdout 09:38:08 INFO - Current package versions: 09:38:08 INFO - argparse == 1.2.1 09:38:08 INFO - blessings == 1.6 09:38:08 INFO - blobuploader == 1.2.4 09:38:08 INFO - docopt == 0.6.1 09:38:08 INFO - functools32 == 3.2.3-2 09:38:08 INFO - jsonschema == 2.5.1 09:38:08 INFO - manifestparser == 1.1 09:38:08 INFO - mozInstall == 1.12 09:38:08 INFO - mozcrash == 0.17 09:38:08 INFO - mozdebug == 0.1 09:38:08 INFO - mozdevice == 0.48 09:38:08 INFO - mozfile == 1.2 09:38:08 INFO - mozhttpd == 0.7 09:38:08 INFO - mozinfo == 0.9 09:38:08 INFO - mozleak == 0.1 09:38:08 INFO - mozlog == 3.4 09:38:08 INFO - moznetwork == 0.27 09:38:08 INFO - mozprocess == 0.23 09:38:08 INFO - mozprofile == 0.28 09:38:08 INFO - mozrunner == 6.12 09:38:08 INFO - mozscreenshot == 0.1 09:38:08 INFO - mozsystemmonitor == 0.3 09:38:08 INFO - moztest == 0.7 09:38:08 INFO - mozversion == 1.4 09:38:08 INFO - psutil == 3.1.1 09:38:08 INFO - requests == 1.2.3 09:38:08 INFO - wsgiref == 0.1.2 09:38:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:38:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f5582b53f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23eeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ebf60>, '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 0x23ecd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ed1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', '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:38:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:38:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:08 INFO - Using env: {'DISPLAY': ':0', 09:38:08 INFO - 'HOME': '/home/cltbld', 09:38:08 INFO - 'LANG': 'en_US.UTF-8', 09:38:08 INFO - 'LANGUAGE': 'en_US:en', 09:38:08 INFO - 'LOGNAME': 'cltbld', 09:38:08 INFO - 'MAIL': '/var/mail/cltbld', 09:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:08 INFO - 'MOZ_NO_REMOTE': '1', 09:38:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:08 INFO - 'NO_EM_RESTART': '1', 09:38:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:08 INFO - 'PWD': '/builds/slave/test', 09:38:08 INFO - 'SHELL': '/bin/bash', 09:38:08 INFO - 'SHLVL': '1', 09:38:08 INFO - 'TERM': 'linux', 09:38:08 INFO - 'TMOUT': '86400', 09:38:08 INFO - 'USER': 'cltbld', 09:38:08 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', 09:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:08 INFO - '_': '/tools/buildbot/bin/python'} 09:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:38:09 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:38:09 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:38:09 INFO - Cleaning up... 09:38:09 INFO - Return code: 0 09:38:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:09 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f5582b53f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23eeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ebf60>, '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 0x23ecd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ed1d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', '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:38:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:38:09 INFO - Using env: {'DISPLAY': ':0', 09:38:09 INFO - 'HOME': '/home/cltbld', 09:38:09 INFO - 'LANG': 'en_US.UTF-8', 09:38:09 INFO - 'LANGUAGE': 'en_US:en', 09:38:09 INFO - 'LOGNAME': 'cltbld', 09:38:09 INFO - 'MAIL': '/var/mail/cltbld', 09:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:09 INFO - 'MOZ_NO_REMOTE': '1', 09:38:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:09 INFO - 'NO_EM_RESTART': '1', 09:38:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:09 INFO - 'PWD': '/builds/slave/test', 09:38:09 INFO - 'SHELL': '/bin/bash', 09:38:09 INFO - 'SHLVL': '1', 09:38:09 INFO - 'TERM': 'linux', 09:38:09 INFO - 'TMOUT': '86400', 09:38:09 INFO - 'USER': 'cltbld', 09:38:09 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', 09:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:09 INFO - '_': '/tools/buildbot/bin/python'} 09:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:38:09 INFO - Cleaning up... 09:38:09 INFO - Return code: 0 09:38:09 INFO - Running post-action listener: _resource_record_post_action 09:38:09 INFO - Running post-action listener: _start_resource_monitoring 09:38:09 INFO - Starting resource monitoring. 09:38:09 INFO - [mozharness: 2016-09-21 16:38:09.331909Z] Finished create-virtualenv step (success) 09:38:09 INFO - [mozharness: 2016-09-21 16:38:09.332515Z] Running install step. 09:38:09 INFO - Running pre-action listener: _resource_record_pre_action 09:38:09 INFO - Running main action method: install 09:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:09 INFO - Reading from file tmpfile_stdout 09:38:09 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:09 INFO - Reading from file tmpfile_stdout 09:38:09 INFO - Output received: 09:38:09 INFO - Usage: mozinstall [options] installer 09:38:09 INFO - Options: 09:38:09 INFO - -h, --help show this help message and exit 09:38:09 INFO - -d DEST, --destination=DEST 09:38:09 INFO - Directory to install application into. [default: 09:38:09 INFO - "/builds/slave/test"] 09:38:09 INFO - --app=APP Application being installed. [default: firefox] 09:38:09 INFO - mkdir: /builds/slave/test/build/application 09:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:38:25 INFO - Reading from file tmpfile_stdout 09:38:25 INFO - Output received: 09:38:25 INFO - /builds/slave/test/build/application/firefox/firefox 09:38:25 INFO - Running post-action listener: _resource_record_post_action 09:38:25 INFO - [mozharness: 2016-09-21 16:38:25.581501Z] Finished install step (success) 09:38:25 INFO - [mozharness: 2016-09-21 16:38:25.581747Z] Running run-tests step. 09:38:25 INFO - Running pre-action listener: _resource_record_pre_action 09:38:25 INFO - Running main action method: run_tests 09:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:25 INFO - Python 2.7.3 09:38:25 INFO - Return code: 0 09:38:25 INFO - grabbing minidump binary from tooltool 09:38:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:38:25 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 0x23ebf60>, '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 0x23ecd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ed1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:25 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:38:25 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:38:25 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:38:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:25 INFO - Return code: 0 09:38:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:38:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:38:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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:38:25 INFO - Using env: {'DISPLAY': ':0', 09:38:25 INFO - 'HOME': '/home/cltbld', 09:38:25 INFO - 'LANG': 'en_US.UTF-8', 09:38:25 INFO - 'LANGUAGE': 'en_US:en', 09:38:25 INFO - 'LOGNAME': 'cltbld', 09:38:25 INFO - 'MAIL': '/var/mail/cltbld', 09:38:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:25 INFO - 'MOZ_NO_REMOTE': '1', 09:38:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:25 INFO - 'NO_EM_RESTART': '1', 09:38:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:25 INFO - 'PWD': '/builds/slave/test', 09:38:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:25 INFO - 'SHELL': '/bin/bash', 09:38:25 INFO - 'SHLVL': '1', 09:38:25 INFO - 'TERM': 'linux', 09:38:25 INFO - 'TMOUT': '86400', 09:38:25 INFO - 'USER': 'cltbld', 09:38:25 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462', 09:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:25 INFO - '_': '/tools/buildbot/bin/python'} 09:38:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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:38:25 INFO - mozversion application_buildid: 20160921064707 09:38:25 INFO - mozversion application_changeset: 1563e99859383adaf22a8c285c8ec70e1db2e49b 09:38:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:38:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:25 INFO - mozversion application_name: Firefox 09:38:25 INFO - mozversion application_remotingname: firefox-dev 09:38:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:38:25 INFO - mozversion application_vendor: Mozilla 09:38:25 INFO - mozversion application_version: 51.0a2 09:38:25 INFO - mozversion platform_buildid: 20160921064707 09:38:25 INFO - mozversion platform_changeset: 1563e99859383adaf22a8c285c8ec70e1db2e49b 09:38:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:38:25 INFO - mozversion platform_version: 51.0a2 09:38:25 INFO - using testdate: 1474475905 09:38:25 INFO - actual date: 1474475905 09:38:25 INFO - starting webserver on 'localhost:49078' 09:38:25 INFO - SUITE-START | Running 2 tests 09:38:25 INFO - TEST-START | dromaeo_css 09:38:25 INFO - operating with platform_type : linux_ 09:38:25 INFO - Initialising browser for dromaeo_css test... 09:38:25 INFO - TEST-INFO | started process 8963 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu_47A7/profile http://localhost:49078/getInfo.html) 09:38:30 INFO - PROCESS | 8963 | __metrics Screen width/height:1600/1200 09:38:30 INFO - PROCESS | 8963 | colorDepth:24 09:38:30 INFO - PROCESS | 8963 | Browser inner width/height: 1024/697 09:38:30 INFO - PROCESS | 8963 | __metrics 09:38:30 INFO - PROCESS | 8963 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:38:30 INFO - PROCESS | 8963 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:38:31 INFO - PROCESS | 8963 | console.error: PushService: 09:38:31 INFO - PROCESS | 8963 | stateChangeProcessEnqueue: Error transitioning state 09:38:31 INFO - PROCESS | 8963 | UnknownError 09:38:31 INFO - TEST-INFO | 8963: exit 0 09:38:31 INFO - Browser initialized. 09:38:31 INFO - Running cycle 1/1 for dromaeo_css test... 09:38:31 INFO - TEST-INFO | started process 9057 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu_47A7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:43 INFO - PROCESS | 9057 | RSS: Main: 147673088 09:38:43 INFO - PROCESS | 9057 | 09:38:56 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00021 seconds. 09:39:30 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.0328 seconds. 09:39:34 INFO - PROCESS | 9057 | 1474475974717 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:39:46 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.03366 seconds. 09:39:50 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.03274 seconds. 09:39:57 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.16678 seconds. 09:40:02 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.0499 seconds. 09:40:07 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.03373 seconds. 09:40:10 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 09:40:18 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.04983 seconds. 09:40:25 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.03375 seconds. 09:40:41 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 09:41:00 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 09:41:04 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 09:41:10 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.03274 seconds. 09:41:27 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.05059 seconds. 09:41:32 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.03283 seconds. 09:41:44 INFO - PROCESS | 9057 | Cycle 1(1): loaded http://localhost:49078/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49078/tests/dromaeo/cssquery-ext.html) 09:41:45 INFO - PROCESS | 9057 | RSS: Main: 252715008 09:41:45 INFO - PROCESS | 9057 | 09:41:48 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.03404 seconds. 09:42:06 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.0337 seconds. 09:42:15 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 09:42:26 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 09:42:57 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01681 seconds. 09:43:24 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 09:43:30 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.0171 seconds. 09:43:42 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00031 seconds. 09:43:54 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.99936 seconds. 09:44:05 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.0006 seconds. 09:44:47 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01632 seconds. 09:44:47 INFO - PROCESS | 9057 | Cycle 1(1): loaded http://localhost:49078/tests/dromaeo/cssquery-ext.html (next: http://localhost:49078/tests/dromaeo/cssquery-jquery.html) 09:44:48 INFO - PROCESS | 9057 | RSS: Main: 227622912 09:44:48 INFO - PROCESS | 9057 | 09:44:51 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01637 seconds. 09:44:55 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 09:45:10 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 09:45:36 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01643 seconds. 09:45:51 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01692 seconds. 09:46:15 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 09:46:20 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01686 seconds. 09:46:47 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 09:46:53 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 09:47:01 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00049 seconds. 09:47:09 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00026 seconds. 09:47:29 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.0004 seconds. 09:47:50 INFO - PROCESS | 9057 | Cycle 1(1): loaded http://localhost:49078/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49078/tests/dromaeo/cssquery-mootools.html) 09:47:50 INFO - PROCESS | 9057 | RSS: Main: 177922048 09:47:50 INFO - PROCESS | 9057 | 09:47:54 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 09:48:13 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 09:48:15 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00056 seconds. 09:48:33 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01642 seconds. 09:48:53 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00031 seconds. 09:49:01 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 09:50:08 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 09:50:18 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 09:50:22 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01633 seconds. 09:50:50 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 09:50:52 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01658 seconds. 09:50:52 INFO - PROCESS | 9057 | Cycle 1(1): loaded http://localhost:49078/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49078/tests/dromaeo/cssquery-prototype.html) 09:50:53 INFO - PROCESS | 9057 | RSS: Main: 201220096 09:50:53 INFO - PROCESS | 9057 | 09:50:56 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.03278 seconds. 09:51:03 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00042 seconds. 09:51:15 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00056 seconds. 09:51:28 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 09:51:30 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00026 seconds. 09:51:32 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00053 seconds. 09:51:38 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.04962 seconds. 09:51:40 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00059 seconds. 09:52:10 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00028 seconds. 09:52:12 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00017 seconds. 09:52:14 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 09:52:16 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00063 seconds. 09:52:23 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01742 seconds. 09:52:32 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 09:52:53 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.0003 seconds. 09:52:57 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 09:53:09 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00018 seconds. 09:53:17 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00017 seconds. 09:53:21 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01691 seconds. 09:53:24 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 09:53:27 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 09:53:37 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01633 seconds. 09:53:55 INFO - PROCESS | 9057 | Cycle 1(1): loaded http://localhost:49078/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49078/tests/dromaeo/cssquery-yui.html) 09:53:56 INFO - PROCESS | 9057 | RSS: Main: 177250304 09:53:56 INFO - PROCESS | 9057 | 09:54:01 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.03281 seconds. 09:54:03 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 09:54:12 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.03372 seconds. 09:54:45 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 09:54:48 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01644 seconds. 09:55:01 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 09:55:22 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 09:55:35 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00061 seconds. 09:55:46 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01679 seconds. 09:55:48 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01615 seconds. 09:55:50 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 09:56:08 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00062 seconds. 09:56:20 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 09:56:38 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01579 seconds. 09:56:59 INFO - PROCESS | 9057 | [GFX1-]: Refresh driver waiting for the compositor for 1.01707 seconds. 09:56:59 INFO - PROCESS | 9057 | Cycle 1(1): loaded http://localhost:49078/tests/dromaeo/cssquery-yui.html (next: http://localhost:49078/tests/dromaeo/cssquery-dojo.html) 09:56:59 INFO - PROCESS | 9057 | RSS: Main: 265711616 09:56:59 INFO - PROCESS | 9057 | 09:56:59 INFO - PROCESS | 9057 | __start_tp_report 09:56:59 INFO - PROCESS | 9057 | _x_x_mozilla_page_load 09:56:59 INFO - PROCESS | 9057 | _x_x_mozilla_page_load_details 09:56:59 INFO - PROCESS | 9057 | |i|pagename|runs| 09:56:59 INFO - PROCESS | 9057 | |0;dojo.html;3108.8445577721113;3147.826869522177;3157.494800831866;3176.4917613181897;3193.5209718542214;5629.183768353586;5652.717364131792;5687.573431992607;5689.8577535561635;5703.914441283378;5680.517156041731;5743.712814359275;5756.712164391784;5759.308882934054;5793.768249270024;5164.715940623264;5168.457311982234;5188.040212560688;5224.582033437335;5247.816326428576;5037.093323201822;5266.447023062581;5276.973615131919;5289.127293996486;5318.627696061278;5038.622103342264;5076.314697515843;5097.158968770148;5132.769025393847;5179.611529135311;5193.402758682743;5196;5267.315249017642;5269.2886460327745;5295.629305948585;4870.488598697121;4971.776270067856;5145.51117643827;5183.377994640668;5231.633785635007;5483.533899618537;5492.5605951524185;5507.752151153208;5548.4174161713245;5621.297337832771;5863.560232982501;5966.850828729273;6001.369856165127;6002.069679199687;6003.009503431928;5911.822645320647;5979.1031345298115;6031.095335699636;6037.7584896604085;6038.486728628071;5564;5589.552835773128;5608.242887210175;5612.382637909785;5651.321841379061;6175.6912154393;6180.505559555225;6185.474234690012;6185.907211391833;6189.164462797555;5673.290838645169;5708.40061793514;5933.703314834327;5933.970330148321;5936;5957.493613042853;6020.397960203944;6027.45752882234;6088.939110608837;6131.570790044654;5990;5997.910031349533;6007.639541627516;6014.338422773491;6062.575619706578;5800.56495762811;6017.398260174035;6076.726547305382;6082.513398928075;6104.084387341845;4537.319402089713;4569.040501494657;4602.355670206107;4614.907701845944;4616.192166370939;4076.5719599442223;4099.651529619956;4104.651104656137;4114.444549985774;4128.649064829463;5656.490915817514;5680.204771332017;5685.943140568624;5688.488036076773;5705.115707065412;5907.232059832194;5928.881422371528;5951.1073338999495;5979.043353063489;6022.849428764316;5654.462826031603;5656.660600363992;5664.51851592611;5665.1502274658305;5692.23154874095;5191.558717508979;5200.323957885451;5202.4277329493725;5204.642031758609;5235.31940847695;5885.852853678607;6001.339852616296;6011.579189456696;6011.909821352596;6090.817275481654;6044.15381846533;6051.12258722474;6060.303065147388;6068.51451883857;6095.329513753572;6103.206583144163;6112.17485639433;6113.510919126548;6113.694315284129;6117.785877494266;5714.485696287354;5718.714064296852;5916.645001299936;5923.970380148072;5935.169076329231;6079.2704875413465;6145.569810113428;6152.138700582012;6154;6163.568550201622;5917.763289468373;5970.492508136857;5973.104034394875;5997.970010150096;5998.070299103646;5658.660480371191;5761.8559536011935;5964.105384192406;5971.731272092679;5976.641401515923;5799.3040835100055;5852.882942341214;5871.031279838779;5894.440028197486;5925.585209035326;5964.850878728066;5996.70016499182;5998.730057147526;6006.489448396936;6008.459238668541;5588.86027849307;5632.7183640816975;5848.766049358148;5853.356130825691;5856.209411729362 09:56:59 INFO - PROCESS | 9057 | |1;ext.html;572.3875453265006;621.5245337316952;638.6998110887881;645.9160309159812;656.6158797103694;43569.78215108924;44934.55065449345;45047.77476112623;45254.7737261314;49559.50440495594;48438.27342589855;48831.75584122083;49018.75490622542;49097;49113.50886491134;8414.621342039623;8630.309575233967;9089.727308180765;9223.861642075375;9227.769305767377;10715.410652414113;11006;11043;11416.771664566704;11439.59961401351;11271.661850144508;11321.094312455025;11343.886561134366;11345.546178152905;11351.659450216466;11031.779364412707;11044.282121662067;11089.2237543372;11090.611828585961;11100.555977760961;10934.179936504714;10974.176936729697;11002.944985275102;11010.944945275303;11012.119030477543;45208.77395613034;45244;45463.54536454659;45570.772146139054;45583;3851.8266677999277;3973.8807835764974;4035.2131334389805;4049.7367671101283;4227.852025179135;34314;34338.82830585856;34400.483992740126;34706.479402808975;35908.46137307942;4644.396228490275;4672.15901137795;4689.788959496796;4703.835365762182;4751.216049351853;390286;390680.04659976804;392216;392280;392661;63061;63421.68289158617;63447.36552634415;63462.682686586275;63526;40902;41627.79186104111;41878;44223.77888110539;44329.77835110804;18338.174782134836;18412.171452284678;18569.350072747387;18591.34930277434;18634.906825466056;12468.5012599495;12563.309018004098;15040.32318545668;15423.074615523105;15549.611259718598;20168.394948151577;20941.685874711588;20942.79057209439;20996.47508812292;21119.366419007456;7471.887921681179;7551.320381165721;7610.657520411595;7753.224677532202;7922.326602238759;986.6546708651914;1024.4939938290702;1074.6023971130733;1127.8928501792316;1138.4876805437586;34283.48574771381;35644.64355356465;35782.40589441197;37442.812785935894;38118.23763524769;1125.3922881643985;1132.428123797477;1141.783061218366;1171.3674615707594;1202.1825158892038;17307.653846922993;18035.098245087956;18042.368517102102;18065.54836129081;18126.909365453088;621613;645459;654510;655674;988387;63824.04263936045;64697;64715;64719.676401618635;64834.675826620565;1339.2834833364298;1381.109184575943;1422.615893708693;1445.7614493608437;1478.4234148682024;32341.35317293688;34680.30639387148;34808.82595587006;37247.62752372442;37441.4383784238;59288.407115930015;60106.39893601008;60227.698861505414;60255.69872150611;60353;556163;559863;560866;562392;562680;27677.308067297672;31721.206969825016;31810.522842156923;32048.198795030312;33125.66874331226;49419.258711118644;49817.75091124521;50741.746291268304;50909.74545127251;51179.48820511896;43132.56867431285;43195.56804432041;43578.56421435872;44431;45056;43306;43556.782216088715;44065;44545.33182002338;45162.77418612886;43241.783791080845;43889.561104388544;44429.555704442544;44439;44568.33147502855;35722;38378.232435351696;38925.2214955705;39055.41416878806;39099.9930677287;29070.56394154047;29342.266443339086;29418.411631767674;29514;31635.683643163273 09:56:59 INFO - PROCESS | 9057 | |2;jquery.html;409.7091065343606;413.08707755859547;424.43126210877426;424.5690624016623;436.7139523612035;19040;19520.31678891239;20637.071331790066;20652.586948261025;21032.89483552584;18997.715034274497;19544.70682939757;19824.306149284777;19862.801371986276;19921.701174482394;5757.596968212217;6001.369856165105;6014.969925150368;6015.157877897098;6015.6992150392525;5915.260592425947;5928.970355148218;5992.370801065869;5998.4001599839885;6145.969270153643;6152.477039451652;6159.846003849895;6179.165812615284;6181.536384771138;6196.938030619704;6029.939700602981;6186.907196392058;6208.906866397007;6245.718942647592;6247.437730604267;5885.823425297248;6047.969760151171;6059.606125601822;6069.02895536712;6069.998450255703;14757.631059223499;15107.169105699182;15113.848861511355;15127.848721512753;17468.388606398843;2328.52265285616;2368.9052437902465;2515.5094756522485;2533.6579561759113;2760.8343499390103;10828.512716927757;11007.06439952605;11452.255603385753;11522.596709115225;13800.585982420544;1987.1058023889163;1996.6523194643705;2069.2136987944637;2088.885111318877;2091.435312465626;75466.62266688686;76654.61672691656;77615.6119219406;88932.11067889303;91000;11134.38341989648;11384.601538946097;11477.770444591062;11614.245074070159;11986;16773;16786.496405107868;16809;16823.57941051459;16835.41076062332;8381.203785640355;8486.618102185417;8551.700690475804;8598.355123365773;8759.124087591317;8157.428979971463;8173.18268173178;8364.749057528285;8374.665013399395;8395.832083358298;4796.832110876103;5007.974960125176;5044.646874718808;5101.923471147936;5169.4830516948;2056.7326247587716;2065.5662310914577;2118.5551034282666;2132.893355332258;2137.615229258717;911.8267529169569;968.3221744778683;973.1630797514132;974.3910056214866;1022.5143057047992;18869.962152081767;19183.712244316346;19680.60638787216;20249.493762656053;21384.465388365414;1049.3074570783247;1068.647346375694;1069.8020866139789;1096.353151640536;1106.1593189176124;10475.895241047645;11147.275427097213;11240.437978101063;11314.321140731583;11383.88616113845;376904;377410;380626;526180;529579;57495.425045749005;72408.63795680988;98144;99472;103242;1027.41950797799;1045.4929359260752;1061.3313612424124;1064.180580952662;1092.8961748633867;17561.736573951657;17761.822381776532;18224;18348.81651183471;18496.72254916204;44424;44639.330410044524;44701.105977880914;45637.77181114073;46355;57736;60763;61878.690606546676;62372.37627623665;63704.08887733376;7439.51643143194;8779.956100219459;8945.507997059964;9018.00801911776;9272.304577156605;10825.891741082803;13511.527096551574;13598.184108953494;13605.523806666719;13751.656208594464;11594.71013224676;11839.70400739954;11926.701832453911;11933.52265909354;12198.268103913793;11185.217034807485;11433.428328583705;11521.193516453768;11981.28112313264;12170.878291216974;11896.345700986183;12217.328046957502;12250.693732656755;12251.69370765738;12279.44742486608;8603.225709686158;8882.289416846766;8978.775530611787;9080;9126.224270937044;11731;12008.75982480363;12353.073519486254;12410.75178496443;12529.436175371948 09:56:59 INFO - PROCESS | 9057 | |3;mootools.html;1366.3304980559528;1534.416921569803;1550.2636247782305;1718.5531761741943;1843.3548258109654;8356.373272004601;9115.088491150882;9430.33987620865;9435.575399107049;9678.080582344666;9438.244940404767;9507.239420846334;9524.714258572254;9533.618655253798;9543;2508.3352911478473;2535.3534848613576;2536.074332868499;2538.9111381101666;2540.098265115885;2477.913273035444;2555.6038813983864;2557.0538900606803;2558.2581051495044;2559.513692398448;2463.802895768334;2473.789727873133;2542.5423423783714;2550.349660836484;2551.094361501668;2432.878356082189;2433.586290330648;2438.780509754122;2489.178571071542;2492.2523243027017;2438.5244877248942;2453.4847681986803;2462.9630555541503;2477.393038705529;2479.1075212923333;9352.579133938922;9538.14156725891;9542.618295268181;9549.379290346105;9658.951705241428;5775.104858746859;5945.019071853139;5945.910811337833;5948.10778383246;5951.880962380771;3623.2391197848483;3682.558093028827;3701.722370822199;3716.5540135183683;3725.925481490382;1341.3695563085334;1341.7249463859885;1357.2380206280172;1376.6489545166019;1392.4987004678308;13521.188728676212;13614;13614.931925340408;13614.931925340408;13616.114952528153;4888.951110488921;4896.1921282987905;4964.404271487445;5054.9241761373605;5089.134847075932;10438.060574548326;10509.579616815241;10521.947390263153;10537.473126343652;10540.578376864993;5621.578381621394;5665.943340566624;5666.093425051972;5666.46168613981;5673.602847800615;4408.669349798778;4662.184117779375;4689.132510485571;4701.412323459567;5264.763085661154;4008.939865902014;4010.498687664042;4145.647619952338;4146.170765846843;4160.563140870226;1224.381687247934;1226.068188176992;1233.6669099343128;1244.3778110944527;1252.2799390350585;901.3150005995491;947.2895328503622;981.4454833019321;999.5370669958669;999.5402115027169;6005.279366475964;6218.9378106219265;6473.449756770633;6491.480681545465;6576.901346479806;1019.1591936652292;1051.6161601015574;1056.2131212246773;1057.6668349469892;1072.9624463143753;4265.232258193493;4364.738115713068;4413.58070983256;4534.274516077412;4602.539746025371;11673.241239319417;12156.756864862653;12167.209131406431;12169.513219471299;12180.330081845583;11675.182737208484;11911.821322680167;12015.699607509707;12031.578894738643;12032.578859739868;8847.646094156291;8902.376833621584;9009.324300677476;9016.278697704169;9075.047120052299;6217;6296.842578935563;6339.5562310637815;6394.104825324365;6482.805515834532;10777.137828973506;10859.945700271448;10872.130229581457;10910.890891091305;10913.072388847037;10975.341479511255;11136.665900023012;11289.20975531705;11292.604758833402;11394.25937314072;8072.798180045545;8099.230573095547;8224.671013159406;8242;8323.250907418362;9211.861822072808;9454.480003599869;9458.621655133718;9466.290028248046;9480.52597370115;8185.508869467851;8280.337572994025;8373.782793841814;8376;8596.269317108116;8125.349972002344;8137.186281372053;8252.381071419548;8393.916060839314;8594.355423343399;7685.5004424712215;7707.113681926426;7722.498037627536;7764.805879852823;7773.844523109619;5428.077226871363;5461.726913654381;5509.3664228614225;5544.944550554443;5547.201670555021;5286.947130528645;5333.200019997032;5641.830745077655;5653.321601407857;5757.309122905278 09:56:59 INFO - PROCESS | 9057 | |4;prototype.html;348.5660352860689;350.2190116041228;354.03878469954077;358.1440357545465;361.67268621897193;5563.137713654385;5601.887962240753;5610.887782244357;5616.634918730279;5633.915451283021;5255.86860328492;5291.074062039147;5359.517643412092;5395.919061214076;5477.424870388611;4315.244832154376;4326.6106050455455;4342.435483387155;4344.674149438789;4345.870073780824;4135.58644135587;4335.869923902273;4338.02845051141;4350.129974005195;4353.564643535637;4044;4306.289462238742;4311.046932851007;4319.632831209351;4328.156009578133;4297.03316753731;4312.8490502832565;4319.438472998506;4323.740575565476;4328.545502722201;3819.2170605025904;3821.3503704370323;3829.8468061277517;3835.4438606402196;3855.6722678572355;5229.921551176735;5320.600954928396;5326.547243484309;5331.733413329318;5339.5461385782255;4925.438771838624;4957.851264462072;4960.181570040939;4981.701097934136;4983.526564976321;5469.726513674301;5693.715314234313;5703.572232082611;5725.3988331225455;5730.484256416901;4168.187203495319;4178.874633760992;4183.288840897055;4203.789810509493;4221.113566151082;5893.5579831512805;5906.409359064102;5908.556858235649;5949.702514874282;5973.402659733992;4295.591918767712;4361.563843615613;4363.80362883671;4367.650587952956;4379.759113248802;4348.369486424514;4375.146846364928;4414.933775993362;4427.29163333865;4436.201483732894;4973;4975.228839529878;4977.527134922249;4984.3021976923255;4994.450610432849;5593.916091258634;5753.85615359611;5804.506616937523;5863.384344643836;5866.941330586639;1456.8178977627797;1482.4144462937295;1496.416397604935;1521.2925989414975;1524.5578782153052;1020.2603112743202;1045.1847558904067;1049.2917280835559;1052.6052979801793;1073.4632683658172;5531.363893152339;5547.750351234205;5563.833085007456;5583;5602.299712535933;5867.735951882176;5922.822315330461;5923.229980102559;5936.7031648417405;5960.344362120163;5388.515033646991;5415.53967912724;5462.808801691922;5562.661124322954;5573.749181286852;3095.92260193497;3104.518799586068;3121.204092956282;3123.406552754969;3133.984330078335;5909.29088509373;5931.288245410578;5964.403559644088;5966.522678185735;5977.342492325927;5778.3354914184565;6102.267727872595;6111.51107911366;6118.573598784695;6126.78556250538;6006.969965150146;6045.486133678686;6048.153258543719;6061.575689701766;6061.8484537886025;5656.151577263279;5922.822315330547;5971.850703732442;5998.940010599838;6046.637201767908;5880.26496687914;5885;5888;5907.3797251288;5912.615679980788;5791.0734282514595;5976.252968378953;5999.670018149044;6052.394760523913;6073.240844894388;4269.679774017023;4298.46269216348;4314.9352759708;4344.304911214191;4354;6073.726682299219;6082.239720034996;6087.817365479043;6090.878182436238;6104.969475152596;5640.294963129609;5713.685747283773;5803.3616302207565;5870.823875283749;5889.676067816311;5819.330776960534;5823.4467725566;5823.505002074871;5835.183074369506;5871.856772278174;5718.828435146952;5781.595288329777;5806.9419305806405;5845.766169353348;5848.883022339614;5812.883742325045;5844.766209351578;5845.444682755302;5849.824505264849;5902.01948016249;5548.556115510669;5759.39526349744;5788;5801.535877129904;5852.4440178183 09:56:59 INFO - PROCESS | 9057 | |5;yui.html;821.2895845093992;826.0376661189713;859.1322764008352;871.1114663043695;897.7396554999051;15894.364225430969;16213.675726485464;16333.918330408345;16340.59148521286;16340.75488867668;16228.51314460568;16369.83630163698;16374;16395;16419.343226270936;701.8876979683251;724.7789424225609;837.7151768398743;862.9870551941726;867.2411639815161;856.2293935458075;858.2661824140364;859.5873980489368;859.6604341285189;859.9742007739778;756.1153450462972;909.9909000909972;910.7085732565581;911.0206527982446;911.3210658059754;768.0246087468939;893.682742626368;910.2172131966471;913.4336711239074;921.7695576105973;673.0577191931335;675.5068799776191;675.9290274521203;676.0771546838579;676.3034074902808;15425.845741542553;15467.381304747796;15475.845241547553;15481.303341349667;15485.612859678486;14783.482578109822;15804;15932.283047262901;16260.83739162605;16409.42567010149;14004.369803358873;14781.408743650241;14786.926065369711;14841;15171.468998585107;4738.83414080509;4746.52534746526;4747.216709242971;4809.591184749265;4879.780409881564;91357;91455.54272228663;92699.53650231773;94652;95236.04763952341;11782.234154780082;11887.821682674767;12301.138920275675;12386.6284011478;12519.061070419755;23547;23758.16846410402;24527.264182074567;24534.631980520306;24622.138225162034;9270.44377337362;9310.72067837966;9371.765705857408;9619.711408657751;9656.565454554562;4810.4467986182035;5089.898202035939;5150.4334523202415;5302.310699609104;5401.432849550819;3754.4180651998986;3905.882823515299;3908.8241029153187;3910.061585219584;3911.2568611963634;666.6400143922227;675.1729131813489;683.9179298484214;687.1067612104245;690.1132045321738;612.8130920069333;685.6434653979902;715.2382712411265;718.6514540447879;729.9854002919911;9725.4650994196;10130.696079117639;10354.223433242387;10656.680299591024;10742.838857417146;703.9014537964689;765.7128576783706;796.0685997383076;809.9838003239902;823.5758584329076;5015.498450154955;5160.535551800356;5276.92084618731;5321.838110042272;5348.625596208228;37199.25601487955;121090;122701.38649306697;129610;129715;105427;106135;107905;108995;111766.44116779364;54088;78451;93303;93638.53180734189;94950;10348.091073508094;10402.063814256451;10409.271351005356;10461;10640.84038739435;44148.33777493404;48591.514084858696;57384;57645.711771440874;59456.405435946814;72563;77924;78253.60873195826;78816.08025227957;82195.17804822115;14265.64335891611;14466.782998255245;14507.202103884387;14727.042742221722;14795.112293262438;15688.372465101269;16232.269547870137;16464.506064818073;17374.565635859206;17399.913000434917;13498.93250533741;13923.72152556923;14889.627759306104;15117.54647360581;15664.451744188898;14618.488352907598;15523.611909701896;15658.765118523459;15727.44953926607;15835;13574.660633484242;13908.443662253801;13981.300934953008;14113;14161.929190353982;10723.142148628247;10864;11639.301641901513;11640.592579260025;11833.471078494951;12005.519779208735;12022.398880055787;12264.32546209967;12312.569060082855;12393.752124957631 09:56:59 INFO - PROCESS | 9057 | __end_tp_report 09:56:59 INFO - PROCESS | 9057 | __start_cc_report 09:56:59 INFO - PROCESS | 9057 | _x_x_mozilla_cycle_collect,860 09:56:59 INFO - PROCESS | 9057 | __end_cc_report 09:56:59 INFO - PROCESS | 9057 | __startTimestamp1474477019383__endTimestamp 09:56:59 INFO - PROCESS | 9057 | 09:56:59 INFO - PROCESS | 9057 | ------- Summary: start ------- 09:56:59 INFO - PROCESS | 9057 | Number of tests: 6 09:56:59 INFO - PROCESS | 9057 | 09:56:59 INFO - PROCESS | 9057 | [#0] dojo.html Cycles:165 Average:5589.81 Median:5793.77 stddev:636.74 (11.0%) stddev-sans-first:608.22 09:56:59 INFO - PROCESS | 9057 | Values: 3108.8 3147.8 3157.5 3176.5 3193.5 5629.2 5652.7 5687.6 5689.9 5703.9 5680.5 5743.7 5756.7 5759.3 5793.8 5164.7 5168.5 5188.0 5224.6 5247.8 5037.1 5266.4 5277.0 5289.1 5318.6 5038.6 5076.3 5097.2 5132.8 5179.6 5193.4 5196.0 5267.3 5269.3 5295.6 4870.5 4971.8 5145.5 5183.4 5231.6 5483.5 5492.6 5507.8 5548.4 5621.3 5863.6 5966.9 6001.4 6002.1 6003.0 5911.8 5979.1 6031.1 6037.8 6038.5 5564.0 5589.6 5608.2 5612.4 5651.3 6175.7 6180.5 6185.5 6185.9 6189.2 5673.3 5708.4 5933.7 5934.0 5936.0 5957.5 6020.4 6027.5 6088.9 6131.6 5990.0 5997.9 6007.6 6014.3 6062.6 5800.6 6017.4 6076.7 6082.5 6104.1 4537.3 4569.0 4602.4 4614.9 4616.2 4076.6 4099.7 4104.7 4114.4 4128.6 5656.5 5680.2 5685.9 5688.5 5705.1 5907.2 5928.9 5951.1 5979.0 6022.8 5654.5 5656.7 5664.5 5665.2 5692.2 5191.6 5200.3 5202.4 5204.6 5235.3 5885.9 6001.3 6011.6 6011.9 6090.8 6044.2 6051.1 6060.3 6068.5 6095.3 6103.2 6112.2 6113.5 6113.7 6117.8 5714.5 5718.7 5916.6 5924.0 5935.2 6079.3 6145.6 6152.1 6154.0 6163.6 5917.8 5970.5 5973.1 5998.0 5998.1 5658.7 5761.9 5964.1 5971.7 5976.6 5799.3 5852.9 5871.0 5894.4 5925.6 5964.9 5996.7 5998.7 6006.5 6008.5 5588.9 5632.7 5848.8 5853.4 5856.2 09:56:59 INFO - PROCESS | 9057 | 09:56:59 INFO - PROCESS | 9057 | [#1] ext.html Cycles:180 Average:71371.91 Median:4004.55 stddev:154022.13 (3846.2%) stddev-sans-first:154362.47 09:56:59 INFO - PROCESS | 9057 | Values: 572.4 621.5 638.7 645.9 656.6 43569.8 44934.6 45047.8 45254.8 49559.5 48438.3 48831.8 49018.8 49097.0 49113.5 8414.6 8630.3 9089.7 9223.9 9227.8 10715.4 11006.0 11043.0 11416.8 11439.6 11271.7 11321.1 11343.9 11345.5 11351.7 11031.8 11044.3 11089.2 11090.6 11100.6 10934.2 10974.2 11002.9 11010.9 11012.1 45208.8 45244.0 45463.5 45570.8 45583.0 3851.8 3973.9 4035.2 4049.7 4227.9 34314.0 34338.8 34400.5 34706.5 35908.5 4644.4 4672.2 4689.8 4703.8 4751.2 390286.0 390680.0 392216.0 392280.0 392661.0 63061.0 63421.7 63447.4 63462.7 63526.0 40902.0 41627.8 41878.0 44223.8 44329.8 18338.2 18412.2 18569.4 18591.3 18634.9 12468.5 12563.3 15040.3 15423.1 15549.6 20168.4 20941.7 20942.8 20996.5 21119.4 7471.9 7551.3 7610.7 7753.2 7922.3 986.7 1024.5 1074.6 1127.9 1138.5 34283.5 35644.6 35782.4 37442.8 38118.2 1125.4 1132.4 1141.8 1171.4 1202.2 17307.7 18035.1 18042.4 18065.5 18126.9 621613.0 645459.0 654510.0 655674.0 988387.0 63824.0 64697.0 64715.0 64719.7 64834.7 1339.3 1381.1 1422.6 1445.8 1478.4 32341.4 34680.3 34808.8 37247.6 37441.4 59288.4 60106.4 60227.7 60255.7 60353.0 556163.0 559863.0 560866.0 562392.0 562680.0 27677.3 31721.2 31810.5 32048.2 33125.7 49419.3 49817.8 50741.7 50909.7 51179.5 43132.6 43195.6 43578.6 44431.0 45056.0 43306.0 43556.8 44065.0 44545.3 45162.8 43241.8 43889.6 44429.6 44439.0 44568.3 35722.0 38378.2 38925.2 39055.4 39100.0 29070.6 29342.3 29418.4 29514.0 31635.7 09:56:59 INFO - PROCESS | 9057 | 09:56:59 INFO - PROCESS | 9057 | [#2] jquery.html Cycles:180 Average:27725.58 Median:11761.04 stddev:73552.26 (625.4%) stddev-sans-first:73730.00 09:56:59 INFO - PROCESS | 9057 | Values: 409.7 413.1 424.4 424.6 436.7 19040.0 19520.3 20637.1 20652.6 21032.9 18997.7 19544.7 19824.3 19862.8 19921.7 5757.6 6001.4 6015.0 6015.2 6015.7 5915.3 5929.0 5992.4 5998.4 6146.0 6152.5 6159.8 6179.2 6181.5 6196.9 6029.9 6186.9 6208.9 6245.7 6247.4 5885.8 6048.0 6059.6 6069.0 6070.0 14757.6 15107.2 15113.8 15127.8 17468.4 2328.5 2368.9 2515.5 2533.7 2760.8 10828.5 11007.1 11452.3 11522.6 13800.6 1987.1 1996.7 2069.2 2088.9 2091.4 75466.6 76654.6 77615.6 88932.1 91000.0 11134.4 11384.6 11477.8 11614.2 11986.0 16773.0 16786.5 16809.0 16823.6 16835.4 8381.2 8486.6 8551.7 8598.4 8759.1 8157.4 8173.2 8364.7 8374.7 8395.8 4796.8 5008.0 5044.6 5101.9 5169.5 2056.7 2065.6 2118.6 2132.9 2137.6 911.8 968.3 973.2 974.4 1022.5 18870.0 19183.7 19680.6 20249.5 21384.5 1049.3 1068.6 1069.8 1096.4 1106.2 10475.9 11147.3 11240.4 11314.3 11383.9 376904.0 377410.0 380626.0 526180.0 529579.0 57495.4 72408.6 98144.0 99472.0 103242.0 1027.4 1045.5 1061.3 1064.2 1092.9 17561.7 17761.8 18224.0 18348.8 18496.7 44424.0 44639.3 44701.1 45637.8 46355.0 57736.0 60763.0 61878.7 62372.4 63704.1 7439.5 8780.0 8945.5 9018.0 9272.3 10825.9 13511.5 13598.2 13605.5 13751.7 11594.7 11839.7 11926.7 11933.5 12198.3 11185.2 11433.4 11521.2 11981.3 12170.9 11896.3 12217.3 12250.7 12251.7 12279.4 8603.2 8882.3 8978.8 9080.0 9126.2 11731.0 12008.8 12353.1 12410.8 12529.4 09:56:59 INFO - PROCESS | 9057 | 09:56:59 INFO - PROCESS | 9057 | [#3] mootools.html Cycles:180 Average:6260.55 Median:4632.36 stddev:3595.37 (77.6%) stddev-sans-first:3586.64 09:56:59 INFO - PROCESS | 9057 | Values: 1366.3 1534.4 1550.3 1718.6 1843.4 8356.4 9115.1 9430.3 9435.6 9678.1 9438.2 9507.2 9524.7 9533.6 9543.0 2508.3 2535.4 2536.1 2538.9 2540.1 2477.9 2555.6 2557.1 2558.3 2559.5 2463.8 2473.8 2542.5 2550.3 2551.1 2432.9 2433.6 2438.8 2489.2 2492.3 2438.5 2453.5 2463.0 2477.4 2479.1 9352.6 9538.1 9542.6 9549.4 9659.0 5775.1 5945.0 5945.9 5948.1 5951.9 3623.2 3682.6 3701.7 3716.6 3725.9 1341.4 1341.7 1357.2 1376.6 1392.5 13521.2 13614.0 13614.9 13614.9 13616.1 4889.0 4896.2 4964.4 5054.9 5089.1 10438.1 10509.6 10521.9 10537.5 10540.6 5621.6 5665.9 5666.1 5666.5 5673.6 4408.7 4662.2 4689.1 4701.4 5264.8 4008.9 4010.5 4145.6 4146.2 4160.6 1224.4 1226.1 1233.7 1244.4 1252.3 901.3 947.3 981.4 999.5 999.5 6005.3 6218.9 6473.4 6491.5 6576.9 1019.2 1051.6 1056.2 1057.7 1073.0 4265.2 4364.7 4413.6 4534.3 4602.5 11673.2 12156.8 12167.2 12169.5 12180.3 11675.2 11911.8 12015.7 12031.6 12032.6 8847.6 8902.4 9009.3 9016.3 9075.0 6217.0 6296.8 6339.6 6394.1 6482.8 10777.1 10859.9 10872.1 10910.9 10913.1 10975.3 11136.7 11289.2 11292.6 11394.3 8072.8 8099.2 8224.7 8242.0 8323.3 9211.9 9454.5 9458.6 9466.3 9480.5 8185.5 8280.3 8373.8 8376.0 8596.3 8125.3 8137.2 8252.4 8393.9 8594.4 7685.5 7707.1 7722.5 7764.8 7773.8 5428.1 5461.7 5509.4 5544.9 5547.2 5286.9 5333.2 5641.8 5653.3 5757.3 09:56:59 INFO - PROCESS | 9057 | 09:56:59 INFO - PROCESS | 9057 | [#4] prototype.html Cycles:180 Average:4883.77 Median:5548.15 stddev:1417.01 (25.5%) stddev-sans-first:1379.49 09:56:59 INFO - PROCESS | 9057 | Values: 348.6 350.2 354.0 358.1 361.7 5563.1 5601.9 5610.9 5616.6 5633.9 5255.9 5291.1 5359.5 5395.9 5477.4 4315.2 4326.6 4342.4 4344.7 4345.9 4135.6 4335.9 4338.0 4350.1 4353.6 4044.0 4306.3 4311.0 4319.6 4328.2 4297.0 4312.8 4319.4 4323.7 4328.5 3819.2 3821.4 3829.8 3835.4 3855.7 5229.9 5320.6 5326.5 5331.7 5339.5 4925.4 4957.9 4960.2 4981.7 4983.5 5469.7 5693.7 5703.6 5725.4 5730.5 4168.2 4178.9 4183.3 4203.8 4221.1 5893.6 5906.4 5908.6 5949.7 5973.4 4295.6 4361.6 4363.8 4367.7 4379.8 4348.4 4375.1 4414.9 4427.3 4436.2 4973.0 4975.2 4977.5 4984.3 4994.5 5593.9 5753.9 5804.5 5863.4 5866.9 1456.8 1482.4 1496.4 1521.3 1524.6 1020.3 1045.2 1049.3 1052.6 1073.5 5531.4 5547.8 5563.8 5583.0 5602.3 5867.7 5922.8 5923.2 5936.7 5960.3 5388.5 5415.5 5462.8 5562.7 5573.7 3095.9 3104.5 3121.2 3123.4 3134.0 5909.3 5931.3 5964.4 5966.5 5977.3 5778.3 6102.3 6111.5 6118.6 6126.8 6007.0 6045.5 6048.2 6061.6 6061.8 5656.2 5922.8 5971.9 5998.9 6046.6 5880.3 5885.0 5888.0 5907.4 5912.6 5791.1 5976.3 5999.7 6052.4 6073.2 4269.7 4298.5 4314.9 4344.3 4354.0 6073.7 6082.2 6087.8 6090.9 6105.0 5640.3 5713.7 5803.4 5870.8 5889.7 5819.3 5823.4 5823.5 5835.2 5871.9 5718.8 5781.6 5806.9 5845.8 5848.9 5812.9 5844.8 5845.4 5849.8 5902.0 5548.6 5759.4 5788.0 5801.5 5852.4 09:56:59 INFO - PROCESS | 9057 | 09:56:59 INFO - PROCESS | 9057 | [#5] yui.html Cycles:180 Average:22043.66 Median:3907.35 stddev:31569.41 (807.9%) stddev-sans-first:31617.75 09:56:59 INFO - PROCESS | 9057 | Values: 821.3 826.0 859.1 871.1 897.7 15894.4 16213.7 16333.9 16340.6 16340.8 16228.5 16369.8 16374.0 16395.0 16419.3 701.9 724.8 837.7 863.0 867.2 856.2 858.3 859.6 859.7 860.0 756.1 910.0 910.7 911.0 911.3 768.0 893.7 910.2 913.4 921.8 673.1 675.5 675.9 676.1 676.3 15425.8 15467.4 15475.8 15481.3 15485.6 14783.5 15804.0 15932.3 16260.8 16409.4 14004.4 14781.4 14786.9 14841.0 15171.5 4738.8 4746.5 4747.2 4809.6 4879.8 91357.0 91455.5 92699.5 94652.0 95236.0 11782.2 11887.8 12301.1 12386.6 12519.1 23547.0 23758.2 24527.3 24534.6 24622.1 9270.4 9310.7 9371.8 9619.7 9656.6 4810.4 5089.9 5150.4 5302.3 5401.4 3754.4 3905.9 3908.8 3910.1 3911.3 666.6 675.2 683.9 687.1 690.1 612.8 685.6 715.2 718.7 730.0 9725.5 10130.7 10354.2 10656.7 10742.8 703.9 765.7 796.1 810.0 823.6 5015.5 5160.5 5276.9 5321.8 5348.6 37199.3 121090.0 122701.4 129610.0 129715.0 105427.0 106135.0 107905.0 108995.0 111766.4 54088.0 78451.0 93303.0 93638.5 94950.0 10348.1 10402.1 10409.3 10461.0 10640.8 44148.3 48591.5 57384.0 57645.7 59456.4 72563.0 77924.0 78253.6 78816.1 82195.2 14265.6 14466.8 14507.2 14727.0 14795.1 15688.4 16232.3 16464.5 17374.6 17399.9 13498.9 13923.7 14889.6 15117.5 15664.5 14618.5 15523.6 15658.8 15727.4 15835.0 13574.7 13908.4 13981.3 14113.0 14161.9 10723.1 10864.0 11639.3 11640.6 11833.5 12005.5 12022.4 12264.3 12312.6 12393.8 09:56:59 INFO - PROCESS | 9057 | -------- Summary: end -------- 09:56:59 INFO - PROCESS | 9057 | 09:57:00 INFO - TEST-INFO | 9057: exit 0 09:57:00 INFO - TEST-OK | dromaeo_css | took 1114166ms 09:57:00 INFO - TEST-START | kraken 09:57:00 INFO - operating with platform_type : linux_ 09:57:00 INFO - Initialising browser for kraken test... 09:57:00 INFO - TEST-INFO | started process 9377 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO_1QH_/profile http://localhost:49078/getInfo.html) 09:57:04 INFO - PROCESS | 9377 | __metrics Screen width/height:1600/1200 09:57:04 INFO - PROCESS | 9377 | colorDepth:24 09:57:04 INFO - PROCESS | 9377 | Browser inner width/height: 1024/697 09:57:04 INFO - PROCESS | 9377 | __metrics 09:57:04 INFO - PROCESS | 9377 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:57:04 INFO - PROCESS | 9377 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:57:05 INFO - PROCESS | 9377 | console.error: PushService: 09:57:05 INFO - PROCESS | 9377 | stateChangeProcessEnqueue: Error transitioning state 09:57:05 INFO - PROCESS | 9377 | UnknownError 09:57:05 INFO - TEST-INFO | 9377: exit 0 09:57:05 INFO - Browser initialized. 09:57:05 INFO - Running cycle 1/1 for kraken test... 09:57:05 INFO - TEST-INFO | started process 9466 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO_1QH_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:17 INFO - PROCESS | 9466 | RSS: Main: 144875520 09:57:17 INFO - PROCESS | 9466 | 09:57:20 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49078/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:20 INFO - PROCESS | 9466 | RSS: Main: 186105856 09:57:20 INFO - PROCESS | 9466 | 09:57:23 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49078/tests/kraken/driver.html?testName=audio-dft) 09:57:24 INFO - PROCESS | 9466 | RSS: Main: 175099904 09:57:24 INFO - PROCESS | 9466 | 09:57:26 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49078/tests/kraken/driver.html?testName=audio-fft) 09:57:27 INFO - PROCESS | 9466 | RSS: Main: 266784768 09:57:27 INFO - PROCESS | 9466 | 09:57:29 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49078/tests/kraken/driver.html?testName=audio-oscillator) 09:57:30 INFO - PROCESS | 9466 | RSS: Main: 173121536 09:57:30 INFO - PROCESS | 9466 | 09:57:32 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49078/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:57:32 INFO - PROCESS | 9466 | RSS: Main: 174792704 09:57:32 INFO - PROCESS | 9466 | 09:57:35 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49078/tests/kraken/driver.html?testName=imaging-darkroom) 09:57:36 INFO - PROCESS | 9466 | RSS: Main: 210505728 09:57:36 INFO - PROCESS | 9466 | 09:57:39 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49078/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:40 INFO - PROCESS | 9466 | RSS: Main: 210046976 09:57:40 INFO - PROCESS | 9466 | 09:57:43 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49078/tests/kraken/driver.html?testName=json-parse-financial) 09:57:43 INFO - PROCESS | 9466 | RSS: Main: 209465344 09:57:43 INFO - PROCESS | 9466 | 09:57:45 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49078/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:45 INFO - PROCESS | 9466 | RSS: Main: 171630592 09:57:45 INFO - PROCESS | 9466 | 09:57:47 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49078/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:48 INFO - PROCESS | 9466 | RSS: Main: 178769920 09:57:48 INFO - PROCESS | 9466 | 09:57:50 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49078/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:50 INFO - PROCESS | 9466 | RSS: Main: 183291904 09:57:50 INFO - PROCESS | 9466 | 09:57:53 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49078/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:53 INFO - PROCESS | 9466 | RSS: Main: 184422400 09:57:53 INFO - PROCESS | 9466 | 09:57:56 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49078/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:57 INFO - PROCESS | 9466 | RSS: Main: 182202368 09:57:57 INFO - PROCESS | 9466 | 09:57:58 INFO - PROCESS | 9466 | Cycle 1(1): loaded http://localhost:49078/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49078/tests/kraken/driver.html?testName=ai-astar) 09:57:59 INFO - PROCESS | 9466 | RSS: Main: 233398272 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | __start_tp_report 09:57:59 INFO - PROCESS | 9466 | _x_x_mozilla_page_load 09:57:59 INFO - PROCESS | 9466 | _x_x_mozilla_page_load_details 09:57:59 INFO - PROCESS | 9466 | |i|pagename|runs| 09:57:59 INFO - PROCESS | 9466 | |0;ai-astar;108;111;113;111;114;111;112;111;110;111 09:57:59 INFO - PROCESS | 9466 | |1;audio-beat-detection;149;117;116;155;117;135;124;128;151;122 09:57:59 INFO - PROCESS | 9466 | |2;audio-dft;160;161;160;160;161;161;161;161;160;160 09:57:59 INFO - PROCESS | 9466 | |3;audio-fft;75;77;76;109;78;76;75;76;96;75 09:57:59 INFO - PROCESS | 9466 | |4;audio-oscillator;82;82;121;82;82;81;103;80;81;105 09:57:59 INFO - PROCESS | 9466 | |5;imaging-gaussian-blur;116;117;117;117;117;117;116;117;117;117 09:57:59 INFO - PROCESS | 9466 | |6;imaging-darkroom;145;145;146;146;145;145;146;146;146;145 09:57:59 INFO - PROCESS | 9466 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 09:57:59 INFO - PROCESS | 9466 | |8;json-parse-financial;50;50;51;49;49;51;50;50;49;50 09:57:59 INFO - PROCESS | 9466 | |9;json-stringify-tinderbox;59;59;58;59;59;59;59;59;59;61 09:57:59 INFO - PROCESS | 9466 | |10;stanford-crypto-aes;74;74;73;74;78;76;78;79;78;72 09:57:59 INFO - PROCESS | 9466 | |11;stanford-crypto-ccm;127;119;120;119;120;117;131;126;151;119 09:57:59 INFO - PROCESS | 9466 | |12;stanford-crypto-pbkdf2;154;151;150;147;149;150;157;152;151;148 09:57:59 INFO - PROCESS | 9466 | |13;stanford-crypto-sha256-iterative;62;62;63;63;63;61;62;61;60;60 09:57:59 INFO - PROCESS | 9466 | __end_tp_report 09:57:59 INFO - PROCESS | 9466 | __start_cc_report 09:57:59 INFO - PROCESS | 9466 | _x_x_mozilla_cycle_collect,1648 09:57:59 INFO - PROCESS | 9466 | __end_cc_report 09:57:59 INFO - PROCESS | 9466 | __startTimestamp1474477079072__endTimestamp 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | ------- Summary: start ------- 09:57:59 INFO - PROCESS | 9466 | Number of tests: 14 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:1.62 (1.5%) stddev-sans-first:1.24 09:57:59 INFO - PROCESS | 9466 | Values: 108.0 111.0 113.0 111.0 114.0 111.0 112.0 111.0 110.0 111.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:131.50 stddev:15.17 (11.5%) stddev-sans-first:14.69 09:57:59 INFO - PROCESS | 9466 | Values: 149.0 117.0 116.0 155.0 117.0 135.0 124.0 128.0 151.0 122.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 09:57:59 INFO - PROCESS | 9466 | Values: 160.0 161.0 160.0 160.0 161.0 161.0 161.0 161.0 160.0 160.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:11.62 (15.3%) stddev-sans-first:12.10 09:57:59 INFO - PROCESS | 9466 | Values: 75.0 77.0 76.0 109.0 78.0 76.0 75.0 76.0 96.0 75.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:81.50 stddev:14.43 (17.7%) stddev-sans-first:15.01 09:57:59 INFO - PROCESS | 9466 | Values: 82.0 82.0 121.0 82.0 82.0 81.0 103.0 80.0 81.0 105.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 09:57:59 INFO - PROCESS | 9466 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 09:57:59 INFO - PROCESS | 9466 | Values: 145.0 145.0 146.0 146.0 145.0 145.0 146.0 146.0 146.0 145.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:57:59 INFO - PROCESS | 9466 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.00 stddev:0.74 (1.5%) stddev-sans-first:0.78 09:57:59 INFO - PROCESS | 9466 | Values: 50.0 50.0 51.0 49.0 49.0 51.0 50.0 50.0 49.0 50.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:0.74 (1.3%) stddev-sans-first:0.78 09:57:59 INFO - PROCESS | 9466 | Values: 59.0 59.0 58.0 59.0 59.0 59.0 59.0 59.0 59.0 61.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#10] stanford-crypto-aes Cycles:10 Average:75.60 Median:77.00 stddev:2.50 (3.3%) stddev-sans-first:2.59 09:57:59 INFO - PROCESS | 9466 | Values: 74.0 74.0 73.0 74.0 78.0 76.0 78.0 79.0 78.0 72.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:123.00 stddev:10.21 (8.3%) stddev-sans-first:10.81 09:57:59 INFO - PROCESS | 9466 | Values: 127.0 119.0 120.0 119.0 120.0 117.0 131.0 126.0 151.0 119.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.90 Median:151.00 stddev:2.92 (1.9%) stddev-sans-first:2.88 09:57:59 INFO - PROCESS | 9466 | Values: 154.0 151.0 150.0 147.0 149.0 150.0 157.0 152.0 151.0 148.0 09:57:59 INFO - PROCESS | 9466 | 09:57:59 INFO - PROCESS | 9466 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.00 stddev:1.16 (1.9%) stddev-sans-first:1.22 09:57:59 INFO - PROCESS | 9466 | Values: 62.0 62.0 63.0 63.0 63.0 61.0 62.0 61.0 60.0 60.0 09:57:59 INFO - PROCESS | 9466 | -------- Summary: end -------- 09:57:59 INFO - PROCESS | 9466 | 09:58:00 INFO - TEST-INFO | 9466: exit 0 09:58:00 INFO - TEST-OK | kraken | took 60047ms 09:58:00 INFO - SUITE-END | took 1174s 09:58:00 INFO - Completed test suite (00:19:34) 09:58:00 INFO - javascript benchmark 09:58:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3108.8445577721113, 3147.826869522177, 3157.494800831866, 3176.4917613181897, 3193.5209718542214, 5629.183768353586, 5652.717364131792, 5687.573431992607, 5689.8577535561635, 5703.914441283378, 5680.517156041731, 5743.712814359275, 5756.712164391784, 5759.308882934054, 5793.768249270024, 5164.715940623264, 5168.457311982234, 5188.040212560688, 5224.582033437335, 5247.816326428576, 5037.093323201822, 5266.447023062581, 5276.973615131919, 5289.127293996486, 5318.627696061278, 5038.622103342264, 5076.314697515843, 5097.158968770148, 5132.769025393847, 5179.611529135311, 5193.402758682743, 5196.0, 5267.315249017642, 5269.2886460327745, 5295.629305948585, 4870.488598697121, 4971.776270067856, 5145.51117643827, 5183.377994640668, 5231.633785635007, 5483.533899618537, 5492.5605951524185, 5507.752151153208, 5548.4174161713245, 5621.297337832771, 5863.560232982501, 5966.850828729273, 6001.369856165127, 6002.069679199687, 6003.009503431928, 5911.822645320647, 5979.1031345298115, 6031.095335699636, 6037.7584896604085, 6038.486728628071, 5564.0, 5589.552835773128, 5608.242887210175, 5612.382637909785, 5651.321841379061, 6175.6912154393, 6180.505559555225, 6185.474234690012, 6185.907211391833, 6189.164462797555, 5673.290838645169, 5708.40061793514, 5933.703314834327, 5933.970330148321, 5936.0, 5957.493613042853, 6020.397960203944, 6027.45752882234, 6088.939110608837, 6131.570790044654, 5990.0, 5997.910031349533, 6007.639541627516, 6014.338422773491, 6062.575619706578, 5800.56495762811, 6017.398260174035, 6076.726547305382, 6082.513398928075, 6104.084387341845, 4537.319402089713, 4569.040501494657, 4602.355670206107, 4614.907701845944, 4616.192166370939, 4076.5719599442223, 4099.651529619956, 4104.651104656137, 4114.444549985774, 4128.649064829463, 5656.490915817514, 5680.204771332017, 5685.943140568624, 5688.488036076773, 5705.115707065412, 5907.232059832194, 5928.881422371528, 5951.1073338999495, 5979.043353063489, 6022.849428764316, 5654.462826031603, 5656.660600363992, 5664.51851592611, 5665.1502274658305, 5692.23154874095, 5191.558717508979, 5200.323957885451, 5202.4277329493725, 5204.642031758609, 5235.31940847695, 5885.852853678607, 6001.339852616296, 6011.579189456696, 6011.909821352596, 6090.817275481654, 6044.15381846533, 6051.12258722474, 6060.303065147388, 6068.51451883857, 6095.329513753572, 6103.206583144163, 6112.17485639433, 6113.510919126548, 6113.694315284129, 6117.785877494266, 5714.485696287354, 5718.714064296852, 5916.645001299936, 5923.970380148072, 5935.169076329231, 6079.2704875413465, 6145.569810113428, 6152.138700582012, 6154.0, 6163.568550201622, 5917.763289468373, 5970.492508136857, 5973.104034394875, 5997.970010150096, 5998.070299103646, 5658.660480371191, 5761.8559536011935, 5964.105384192406, 5971.731272092679, 5976.641401515923, 5799.3040835100055, 5852.882942341214, 5871.031279838779, 5894.440028197486, 5925.585209035326, 5964.850878728066, 5996.70016499182, 5998.730057147526, 6006.489448396936, 6008.459238668541, 5588.86027849307, 5632.7183640816975, 5848.766049358148, 5853.356130825691, 5856.209411729362], "value": 5545.831743615586, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.3875453265006, 621.5245337316952, 638.6998110887881, 645.9160309159812, 656.6158797103694, 43569.78215108924, 44934.55065449345, 45047.77476112623, 45254.7737261314, 49559.50440495594, 48438.27342589855, 48831.75584122083, 49018.75490622542, 49097.0, 49113.50886491134, 8414.621342039623, 8630.309575233967, 9089.727308180765, 9223.861642075375, 9227.769305767377, 10715.410652414113, 11006.0, 11043.0, 11416.771664566704, 11439.59961401351, 11271.661850144508, 11321.094312455025, 11343.886561134366, 11345.546178152905, 11351.659450216466, 11031.779364412707, 11044.282121662067, 11089.2237543372, 11090.611828585961, 11100.555977760961, 10934.179936504714, 10974.176936729697, 11002.944985275102, 11010.944945275303, 11012.119030477543, 45208.77395613034, 45244.0, 45463.54536454659, 45570.772146139054, 45583.0, 3851.8266677999277, 3973.8807835764974, 4035.2131334389805, 4049.7367671101283, 4227.852025179135, 34314.0, 34338.82830585856, 34400.483992740126, 34706.479402808975, 35908.46137307942, 4644.396228490275, 4672.15901137795, 4689.788959496796, 4703.835365762182, 4751.216049351853, 390286.0, 390680.04659976804, 392216.0, 392280.0, 392661.0, 63061.0, 63421.68289158617, 63447.36552634415, 63462.682686586275, 63526.0, 40902.0, 41627.79186104111, 41878.0, 44223.77888110539, 44329.77835110804, 18338.174782134836, 18412.171452284678, 18569.350072747387, 18591.34930277434, 18634.906825466056, 12468.5012599495, 12563.309018004098, 15040.32318545668, 15423.074615523105, 15549.611259718598, 20168.394948151577, 20941.685874711588, 20942.79057209439, 20996.47508812292, 21119.366419007456, 7471.887921681179, 7551.320381165721, 7610.657520411595, 7753.224677532202, 7922.326602238759, 986.6546708651914, 1024.4939938290702, 1074.6023971130733, 1127.8928501792316, 1138.4876805437586, 34283.48574771381, 35644.64355356465, 35782.40589441197, 37442.812785935894, 38118.23763524769, 1125.3922881643985, 1132.428123797477, 1141.783061218366, 1171.3674615707594, 1202.1825158892038, 17307.653846922993, 18035.098245087956, 18042.368517102102, 18065.54836129081, 18126.909365453088, 621613.0, 645459.0, 654510.0, 655674.0, 988387.0, 63824.04263936045, 64697.0, 64715.0, 64719.676401618635, 64834.675826620565, 1339.2834833364298, 1381.109184575943, 1422.615893708693, 1445.7614493608437, 1478.4234148682024, 32341.35317293688, 34680.30639387148, 34808.82595587006, 37247.62752372442, 37441.4383784238, 59288.407115930015, 60106.39893601008, 60227.698861505414, 60255.69872150611, 60353.0, 556163.0, 559863.0, 560866.0, 562392.0, 562680.0, 27677.308067297672, 31721.206969825016, 31810.522842156923, 32048.198795030312, 33125.66874331226, 49419.258711118644, 49817.75091124521, 50741.746291268304, 50909.74545127251, 51179.48820511896, 43132.56867431285, 43195.56804432041, 43578.56421435872, 44431.0, 45056.0, 43306.0, 43556.782216088715, 44065.0, 44545.33182002338, 45162.77418612886, 43241.783791080845, 43889.561104388544, 44429.555704442544, 44439.0, 44568.33147502855, 35722.0, 38378.232435351696, 38925.2214955705, 39055.41416878806, 39099.9930677287, 29070.56394154047, 29342.266443339086, 29418.411631767674, 29514.0, 31635.683643163273], "value": 22080.15698684063, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.7091065343606, 413.08707755859547, 424.43126210877426, 424.5690624016623, 436.7139523612035, 19040.0, 19520.31678891239, 20637.071331790066, 20652.586948261025, 21032.89483552584, 18997.715034274497, 19544.70682939757, 19824.306149284777, 19862.801371986276, 19921.701174482394, 5757.596968212217, 6001.369856165105, 6014.969925150368, 6015.157877897098, 6015.6992150392525, 5915.260592425947, 5928.970355148218, 5992.370801065869, 5998.4001599839885, 6145.969270153643, 6152.477039451652, 6159.846003849895, 6179.165812615284, 6181.536384771138, 6196.938030619704, 6029.939700602981, 6186.907196392058, 6208.906866397007, 6245.718942647592, 6247.437730604267, 5885.823425297248, 6047.969760151171, 6059.606125601822, 6069.02895536712, 6069.998450255703, 14757.631059223499, 15107.169105699182, 15113.848861511355, 15127.848721512753, 17468.388606398843, 2328.52265285616, 2368.9052437902465, 2515.5094756522485, 2533.6579561759113, 2760.8343499390103, 10828.512716927757, 11007.06439952605, 11452.255603385753, 11522.596709115225, 13800.585982420544, 1987.1058023889163, 1996.6523194643705, 2069.2136987944637, 2088.885111318877, 2091.435312465626, 75466.62266688686, 76654.61672691656, 77615.6119219406, 88932.11067889303, 91000.0, 11134.38341989648, 11384.601538946097, 11477.770444591062, 11614.245074070159, 11986.0, 16773.0, 16786.496405107868, 16809.0, 16823.57941051459, 16835.41076062332, 8381.203785640355, 8486.618102185417, 8551.700690475804, 8598.355123365773, 8759.124087591317, 8157.428979971463, 8173.18268173178, 8364.749057528285, 8374.665013399395, 8395.832083358298, 4796.832110876103, 5007.974960125176, 5044.646874718808, 5101.923471147936, 5169.4830516948, 2056.7326247587716, 2065.5662310914577, 2118.5551034282666, 2132.893355332258, 2137.615229258717, 911.8267529169569, 968.3221744778683, 973.1630797514132, 974.3910056214866, 1022.5143057047992, 18869.962152081767, 19183.712244316346, 19680.60638787216, 20249.493762656053, 21384.465388365414, 1049.3074570783247, 1068.647346375694, 1069.8020866139789, 1096.353151640536, 1106.1593189176124, 10475.895241047645, 11147.275427097213, 11240.437978101063, 11314.321140731583, 11383.88616113845, 376904.0, 377410.0, 380626.0, 526180.0, 529579.0, 57495.425045749005, 72408.63795680988, 98144.0, 99472.0, 103242.0, 1027.41950797799, 1045.4929359260752, 1061.3313612424124, 1064.180580952662, 1092.8961748633867, 17561.736573951657, 17761.822381776532, 18224.0, 18348.81651183471, 18496.72254916204, 44424.0, 44639.330410044524, 44701.105977880914, 45637.77181114073, 46355.0, 57736.0, 60763.0, 61878.690606546676, 62372.37627623665, 63704.08887733376, 7439.51643143194, 8779.956100219459, 8945.507997059964, 9018.00801911776, 9272.304577156605, 10825.891741082803, 13511.527096551574, 13598.184108953494, 13605.523806666719, 13751.656208594464, 11594.71013224676, 11839.70400739954, 11926.701832453911, 11933.52265909354, 12198.268103913793, 11185.217034807485, 11433.428328583705, 11521.193516453768, 11981.28112313264, 12170.878291216974, 11896.345700986183, 12217.328046957502, 12250.693732656755, 12251.69370765738, 12279.44742486608, 8603.225709686158, 8882.289416846766, 8978.775530611787, 9080.0, 9126.224270937044, 11731.0, 12008.75982480363, 12353.073519486254, 12410.75178496443, 12529.436175371948], "value": 9482.270996525916, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.3304980559528, 1534.416921569803, 1550.2636247782305, 1718.5531761741943, 1843.3548258109654, 8356.373272004601, 9115.088491150882, 9430.33987620865, 9435.575399107049, 9678.080582344666, 9438.244940404767, 9507.239420846334, 9524.714258572254, 9533.618655253798, 9543.0, 2508.3352911478473, 2535.3534848613576, 2536.074332868499, 2538.9111381101666, 2540.098265115885, 2477.913273035444, 2555.6038813983864, 2557.0538900606803, 2558.2581051495044, 2559.513692398448, 2463.802895768334, 2473.789727873133, 2542.5423423783714, 2550.349660836484, 2551.094361501668, 2432.878356082189, 2433.586290330648, 2438.780509754122, 2489.178571071542, 2492.2523243027017, 2438.5244877248942, 2453.4847681986803, 2462.9630555541503, 2477.393038705529, 2479.1075212923333, 9352.579133938922, 9538.14156725891, 9542.618295268181, 9549.379290346105, 9658.951705241428, 5775.104858746859, 5945.019071853139, 5945.910811337833, 5948.10778383246, 5951.880962380771, 3623.2391197848483, 3682.558093028827, 3701.722370822199, 3716.5540135183683, 3725.925481490382, 1341.3695563085334, 1341.7249463859885, 1357.2380206280172, 1376.6489545166019, 1392.4987004678308, 13521.188728676212, 13614.0, 13614.931925340408, 13614.931925340408, 13616.114952528153, 4888.951110488921, 4896.1921282987905, 4964.404271487445, 5054.9241761373605, 5089.134847075932, 10438.060574548326, 10509.579616815241, 10521.947390263153, 10537.473126343652, 10540.578376864993, 5621.578381621394, 5665.943340566624, 5666.093425051972, 5666.46168613981, 5673.602847800615, 4408.669349798778, 4662.184117779375, 4689.132510485571, 4701.412323459567, 5264.763085661154, 4008.939865902014, 4010.498687664042, 4145.647619952338, 4146.170765846843, 4160.563140870226, 1224.381687247934, 1226.068188176992, 1233.6669099343128, 1244.3778110944527, 1252.2799390350585, 901.3150005995491, 947.2895328503622, 981.4454833019321, 999.5370669958669, 999.5402115027169, 6005.279366475964, 6218.9378106219265, 6473.449756770633, 6491.480681545465, 6576.901346479806, 1019.1591936652292, 1051.6161601015574, 1056.2131212246773, 1057.6668349469892, 1072.9624463143753, 4265.232258193493, 4364.738115713068, 4413.58070983256, 4534.274516077412, 4602.539746025371, 11673.241239319417, 12156.756864862653, 12167.209131406431, 12169.513219471299, 12180.330081845583, 11675.182737208484, 11911.821322680167, 12015.699607509707, 12031.578894738643, 12032.578859739868, 8847.646094156291, 8902.376833621584, 9009.324300677476, 9016.278697704169, 9075.047120052299, 6217.0, 6296.842578935563, 6339.5562310637815, 6394.104825324365, 6482.805515834532, 10777.137828973506, 10859.945700271448, 10872.130229581457, 10910.890891091305, 10913.072388847037, 10975.341479511255, 11136.665900023012, 11289.20975531705, 11292.604758833402, 11394.25937314072, 8072.798180045545, 8099.230573095547, 8224.671013159406, 8242.0, 8323.250907418362, 9211.861822072808, 9454.480003599869, 9458.621655133718, 9466.290028248046, 9480.52597370115, 8185.508869467851, 8280.337572994025, 8373.782793841814, 8376.0, 8596.269317108116, 8125.349972002344, 8137.186281372053, 8252.381071419548, 8393.916060839314, 8594.355423343399, 7685.5004424712215, 7707.113681926426, 7722.498037627536, 7764.805879852823, 7773.844523109619, 5428.077226871363, 5461.726913654381, 5509.3664228614225, 5544.944550554443, 5547.201670555021, 5286.947130528645, 5333.200019997032, 5641.830745077655, 5653.321601407857, 5757.309122905278], "value": 4990.492848446235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.5660352860689, 350.2190116041228, 354.03878469954077, 358.1440357545465, 361.67268621897193, 5563.137713654385, 5601.887962240753, 5610.887782244357, 5616.634918730279, 5633.915451283021, 5255.86860328492, 5291.074062039147, 5359.517643412092, 5395.919061214076, 5477.424870388611, 4315.244832154376, 4326.6106050455455, 4342.435483387155, 4344.674149438789, 4345.870073780824, 4135.58644135587, 4335.869923902273, 4338.02845051141, 4350.129974005195, 4353.564643535637, 4044.0, 4306.289462238742, 4311.046932851007, 4319.632831209351, 4328.156009578133, 4297.03316753731, 4312.8490502832565, 4319.438472998506, 4323.740575565476, 4328.545502722201, 3819.2170605025904, 3821.3503704370323, 3829.8468061277517, 3835.4438606402196, 3855.6722678572355, 5229.921551176735, 5320.600954928396, 5326.547243484309, 5331.733413329318, 5339.5461385782255, 4925.438771838624, 4957.851264462072, 4960.181570040939, 4981.701097934136, 4983.526564976321, 5469.726513674301, 5693.715314234313, 5703.572232082611, 5725.3988331225455, 5730.484256416901, 4168.187203495319, 4178.874633760992, 4183.288840897055, 4203.789810509493, 4221.113566151082, 5893.5579831512805, 5906.409359064102, 5908.556858235649, 5949.702514874282, 5973.402659733992, 4295.591918767712, 4361.563843615613, 4363.80362883671, 4367.650587952956, 4379.759113248802, 4348.369486424514, 4375.146846364928, 4414.933775993362, 4427.29163333865, 4436.201483732894, 4973.0, 4975.228839529878, 4977.527134922249, 4984.3021976923255, 4994.450610432849, 5593.916091258634, 5753.85615359611, 5804.506616937523, 5863.384344643836, 5866.941330586639, 1456.8178977627797, 1482.4144462937295, 1496.416397604935, 1521.2925989414975, 1524.5578782153052, 1020.2603112743202, 1045.1847558904067, 1049.2917280835559, 1052.6052979801793, 1073.4632683658172, 5531.363893152339, 5547.750351234205, 5563.833085007456, 5583.0, 5602.299712535933, 5867.735951882176, 5922.822315330461, 5923.229980102559, 5936.7031648417405, 5960.344362120163, 5388.515033646991, 5415.53967912724, 5462.808801691922, 5562.661124322954, 5573.749181286852, 3095.92260193497, 3104.518799586068, 3121.204092956282, 3123.406552754969, 3133.984330078335, 5909.29088509373, 5931.288245410578, 5964.403559644088, 5966.522678185735, 5977.342492325927, 5778.3354914184565, 6102.267727872595, 6111.51107911366, 6118.573598784695, 6126.78556250538, 6006.969965150146, 6045.486133678686, 6048.153258543719, 6061.575689701766, 6061.8484537886025, 5656.151577263279, 5922.822315330547, 5971.850703732442, 5998.940010599838, 6046.637201767908, 5880.26496687914, 5885.0, 5888.0, 5907.3797251288, 5912.615679980788, 5791.0734282514595, 5976.252968378953, 5999.670018149044, 6052.394760523913, 6073.240844894388, 4269.679774017023, 4298.46269216348, 4314.9352759708, 4344.304911214191, 4354.0, 6073.726682299219, 6082.239720034996, 6087.817365479043, 6090.878182436238, 6104.969475152596, 5640.294963129609, 5713.685747283773, 5803.3616302207565, 5870.823875283749, 5889.676067816311, 5819.330776960534, 5823.4467725566, 5823.505002074871, 5835.183074369506, 5871.856772278174, 5718.828435146952, 5781.595288329777, 5806.9419305806405, 5845.766169353348, 5848.883022339614, 5812.883742325045, 5844.766209351578, 5845.444682755302, 5849.824505264849, 5902.01948016249, 5548.556115510669, 5759.39526349744, 5788.0, 5801.535877129904, 5852.4440178183], "value": 4437.86344620379, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.2895845093992, 826.0376661189713, 859.1322764008352, 871.1114663043695, 897.7396554999051, 15894.364225430969, 16213.675726485464, 16333.918330408345, 16340.59148521286, 16340.75488867668, 16228.51314460568, 16369.83630163698, 16374.0, 16395.0, 16419.343226270936, 701.8876979683251, 724.7789424225609, 837.7151768398743, 862.9870551941726, 867.2411639815161, 856.2293935458075, 858.2661824140364, 859.5873980489368, 859.6604341285189, 859.9742007739778, 756.1153450462972, 909.9909000909972, 910.7085732565581, 911.0206527982446, 911.3210658059754, 768.0246087468939, 893.682742626368, 910.2172131966471, 913.4336711239074, 921.7695576105973, 673.0577191931335, 675.5068799776191, 675.9290274521203, 676.0771546838579, 676.3034074902808, 15425.845741542553, 15467.381304747796, 15475.845241547553, 15481.303341349667, 15485.612859678486, 14783.482578109822, 15804.0, 15932.283047262901, 16260.83739162605, 16409.42567010149, 14004.369803358873, 14781.408743650241, 14786.926065369711, 14841.0, 15171.468998585107, 4738.83414080509, 4746.52534746526, 4747.216709242971, 4809.591184749265, 4879.780409881564, 91357.0, 91455.54272228663, 92699.53650231773, 94652.0, 95236.04763952341, 11782.234154780082, 11887.821682674767, 12301.138920275675, 12386.6284011478, 12519.061070419755, 23547.0, 23758.16846410402, 24527.264182074567, 24534.631980520306, 24622.138225162034, 9270.44377337362, 9310.72067837966, 9371.765705857408, 9619.711408657751, 9656.565454554562, 4810.4467986182035, 5089.898202035939, 5150.4334523202415, 5302.310699609104, 5401.432849550819, 3754.4180651998986, 3905.882823515299, 3908.8241029153187, 3910.061585219584, 3911.2568611963634, 666.6400143922227, 675.1729131813489, 683.9179298484214, 687.1067612104245, 690.1132045321738, 612.8130920069333, 685.6434653979902, 715.2382712411265, 718.6514540447879, 729.9854002919911, 9725.4650994196, 10130.696079117639, 10354.223433242387, 10656.680299591024, 10742.838857417146, 703.9014537964689, 765.7128576783706, 796.0685997383076, 809.9838003239902, 823.5758584329076, 5015.498450154955, 5160.535551800356, 5276.92084618731, 5321.838110042272, 5348.625596208228, 37199.25601487955, 121090.0, 122701.38649306697, 129610.0, 129715.0, 105427.0, 106135.0, 107905.0, 108995.0, 111766.44116779364, 54088.0, 78451.0, 93303.0, 93638.53180734189, 94950.0, 10348.091073508094, 10402.063814256451, 10409.271351005356, 10461.0, 10640.84038739435, 44148.33777493404, 48591.514084858696, 57384.0, 57645.711771440874, 59456.405435946814, 72563.0, 77924.0, 78253.60873195826, 78816.08025227957, 82195.17804822115, 14265.64335891611, 14466.782998255245, 14507.202103884387, 14727.042742221722, 14795.112293262438, 15688.372465101269, 16232.269547870137, 16464.506064818073, 17374.565635859206, 17399.913000434917, 13498.93250533741, 13923.72152556923, 14889.627759306104, 15117.54647360581, 15664.451744188898, 14618.488352907598, 15523.611909701896, 15658.765118523459, 15727.44953926607, 15835.0, 13574.660633484242, 13908.443662253801, 13981.300934953008, 14113.0, 14161.929190353982, 10723.142148628247, 10864.0, 11639.301641901513, 11640.592579260025, 11833.471078494951, 12005.519779208735, 12022.398880055787, 12264.32546209967, 12312.569060082855, 12393.752124957631], "value": 8155.217914149206, "unit": "score"}], "name": "dromaeo_css", "value": 7708.082137298499, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 113.0, 111.0, 114.0, 111.0, 112.0, 111.0, 110.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 116.0, 155.0, 117.0, 135.0, 124.0, 128.0, 151.0, 122.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 161.0, 161.0, 161.0, 161.0, 160.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 109.0, 78.0, 76.0, 75.0, 76.0, 96.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 121.0, 82.0, 82.0, 81.0, 103.0, 80.0, 81.0, 105.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 49.0, 49.0, 51.0, 50.0, 50.0, 49.0, 50.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 61.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 74.0, 78.0, 76.0, 78.0, 79.0, 78.0, 72.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 119.0, 120.0, 119.0, 120.0, 117.0, 131.0, 126.0, 151.0, 119.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 151.0, 150.0, 147.0, 149.0, 150.0, 157.0, 152.0, 151.0, 148.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 63.0, 63.0, 61.0, 62.0, 61.0, 60.0, 60.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1453.8000000000002, "alertThreshold": 2.0}]} 09:58:00 INFO - Return code: 0 09:58:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:58:00 INFO - # TBPL SUCCESS # 09:58:00 INFO - Running post-action listener: _resource_record_post_action 09:58:00 INFO - [mozharness: 2016-09-21 16:58:00.380564Z] Finished run-tests step (success) 09:58:00 INFO - Running post-run listener: _resource_record_post_run 09:58:00 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 360095744; Read time: 0; Write time: 212748 09:58:00 INFO - TinderboxPrint: CPU usage
12.9% 09:58:00 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:58:00 INFO - TinderboxPrint: I/O write bytes / time
360,095,744 / 212,748 09:58:00 INFO - TinderboxPrint: CPU idle
8,275.8 (87.1%) 09:58:00 INFO - TinderboxPrint: CPU user
1,183.8 (12.5%) 09:58:00 INFO - TinderboxPrint: Swap in / out
0 / 0 09:58:00 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107315200; Read time: 0; Write time: 68920 09:58:00 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 252211200; Read time: 0; Write time: 143828 09:58:01 INFO - Running post-run listener: _upload_blobber_files 09:58:01 INFO - Blob upload gear active. 09:58:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:01 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:58:01 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:58:01 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:58:01 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:58:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:02 INFO - (blobuploader) - INFO - Done attempting. 09:58:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:06 INFO - (blobuploader) - INFO - Done attempting. 09:58:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:58:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:58:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:07 INFO - (blobuploader) - INFO - Done attempting. 09:58:07 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:07 INFO - Return code: 0 09:58:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8154c5555027e19c3ef05d8e1380abf449045717efc3f187a23beb9c9c17515f07335cc7ee9933c54c12cbf7ba0e0dc261525c45b0d4bc315d6e1404ee9e0f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6364f1ead315f49b431933fd1d7414738a2e1c77d65294c114699fc515a92c7a1182bf9342c423706664c6bc50135debed9648528d1af1724df778c3a923484", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b21eb71589e1f6e40efe69ec185ad0b0a02ad1b16b0e21301d0ad01a63590e715507e6f83b72cefc61569772d388cbf98612aee87f5bd3002dc987bcd77941ac"} 09:58:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:07 INFO - Contents: 09:58:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8154c5555027e19c3ef05d8e1380abf449045717efc3f187a23beb9c9c17515f07335cc7ee9933c54c12cbf7ba0e0dc261525c45b0d4bc315d6e1404ee9e0f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6364f1ead315f49b431933fd1d7414738a2e1c77d65294c114699fc515a92c7a1182bf9342c423706664c6bc50135debed9648528d1af1724df778c3a923484", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b21eb71589e1f6e40efe69ec185ad0b0a02ad1b16b0e21301d0ad01a63590e715507e6f83b72cefc61569772d388cbf98612aee87f5bd3002dc987bcd77941ac"} 09:58:07 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:07 INFO - Copying logs to upload dir... 09:58:07 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.908668 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-21 09:58:07.574431) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 09:58:07.578252) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8154c5555027e19c3ef05d8e1380abf449045717efc3f187a23beb9c9c17515f07335cc7ee9933c54c12cbf7ba0e0dc261525c45b0d4bc315d6e1404ee9e0f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6364f1ead315f49b431933fd1d7414738a2e1c77d65294c114699fc515a92c7a1182bf9342c423706664c6bc50135debed9648528d1af1724df778c3a923484", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b21eb71589e1f6e40efe69ec185ad0b0a02ad1b16b0e21301d0ad01a63590e715507e6f83b72cefc61569772d388cbf98612aee87f5bd3002dc987bcd77941ac"} build_url:https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011126 build_url: 'https://queue.taskcluster.net/v1/task/Ln9JXw-VRriTvhiI5AGCOw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8154c5555027e19c3ef05d8e1380abf449045717efc3f187a23beb9c9c17515f07335cc7ee9933c54c12cbf7ba0e0dc261525c45b0d4bc315d6e1404ee9e0f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6364f1ead315f49b431933fd1d7414738a2e1c77d65294c114699fc515a92c7a1182bf9342c423706664c6bc50135debed9648528d1af1724df778c3a923484", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b21eb71589e1f6e40efe69ec185ad0b0a02ad1b16b0e21301d0ad01a63590e715507e6f83b72cefc61569772d388cbf98612aee87f5bd3002dc987bcd77941ac"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 09:58:07.604303) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 09:58:07.604712) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1474475850.278305-65949462 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004417 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 09:58:07.690967) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 09:58:07.691324) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 09:58:07.691684) ========= ========= Total master_lag: 0.17 =========