builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1474381310.75 results: success (0) buildid: 20160920043002 builduid: 99954b29e98b41d4aeb527ec8ba4d20f revision: 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:50.745415) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:50.745889) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:50.746164) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:50.758057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:50.758479) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 07:21:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2016-09-20 07:21:51 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129955 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:50.900120) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:50.900430) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013844 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:50.949562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:50.949935) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 --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=9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 07:21:51,219 truncating revision to first 12 chars 2016-09-20 07:21:51,219 Setting DEBUG logging. 2016-09-20 07:21:51,219 attempt 1/10 2016-09-20 07:21:51,219 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/62f79d676e0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 07:21:51,524 unpacking tar archive at: mozilla-central-62f79d676e0e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.651902 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:51.615301) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:51.615621) ========= script_repo_revision: 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:51.616013) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:51.616278) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 07:21:51.626656) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-09-20 07:21:51.626935) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:21:51 INFO - MultiFileLogger online at 20160920 07:21:51 in /builds/slave/test 07:21:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:21:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:21:51 INFO - {'append_to_log': False, 07:21:51 INFO - 'base_work_dir': '/builds/slave/test', 07:21:51 INFO - 'blob_upload_branch': 'Firefox', 07:21:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:21:51 INFO - 'branch': 'Firefox', 07:21:51 INFO - 'buildbot_json_path': 'buildprops.json', 07:21:51 INFO - 'config_files': ('talos/linux_config.py',), 07:21:51 INFO - 'default_actions': ('clobber', 07:21:51 INFO - 'read-buildbot-config', 07:21:51 INFO - 'download-and-extract', 07:21:51 INFO - 'populate-webroot', 07:21:51 INFO - 'create-virtualenv', 07:21:51 INFO - 'install', 07:21:51 INFO - 'run-tests'), 07:21:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:21:51 INFO - 'download_minidump_stackwalk': True, 07:21:51 INFO - 'download_symbols': 'ondemand', 07:21:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:21:51 INFO - 'tooltool.py': '/tools/tooltool.py', 07:21:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:21:51 INFO - '/tools/misc-python/virtualenv.py')}, 07:21:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:21:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:21:51 INFO - 'installer_path': 'installer.exe', 07:21:51 INFO - 'log_level': 'info', 07:21:51 INFO - 'log_name': 'talos', 07:21:51 INFO - 'log_to_console': True, 07:21:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:21:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:21:51 INFO - 'opt_config_files': (), 07:21:51 INFO - 'pip_index': False, 07:21:51 INFO - 'sps_profile': False, 07:21:51 INFO - 'sps_profile_interval': 0, 07:21:51 INFO - 'suite': 'dromaeojs', 07:21:51 INFO - 'system_bits': '32', 07:21:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:21:51 INFO - 'title': 'talos-linux64-ix-013', 07:21:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:21:51 INFO - 'use_talos_json': True, 07:21:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:21:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:21:51 INFO - 'work_dir': 'build'} 07:21:51 INFO - [mozharness: 2016-09-20 14:21:51.991003Z] Running clobber step. 07:21:51 INFO - Running pre-action listener: _resource_record_pre_action 07:21:51 INFO - Running main action method: clobber 07:21:51 INFO - rmtree: /builds/slave/test/build 07:21:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:21:52 INFO - Running post-action listener: _resource_record_post_action 07:21:52 INFO - [mozharness: 2016-09-20 14:21:52.495695Z] Finished clobber step (success) 07:21:52 INFO - [mozharness: 2016-09-20 14:21:52.495797Z] Running read-buildbot-config step. 07:21:52 INFO - Running pre-action listener: _resource_record_pre_action 07:21:52 INFO - Running main action method: read_buildbot_config 07:21:52 INFO - Using buildbot properties: 07:21:52 INFO - { 07:21:52 INFO - "project": "", 07:21:52 INFO - "product": "firefox", 07:21:52 INFO - "script_repo_revision": "production", 07:21:52 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:21:52 INFO - "repository": "", 07:21:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:21:52 INFO - "buildid": "20160920043002", 07:21:52 INFO - "pgo_build": "True", 07:21:52 INFO - "basedir": "/builds/slave/test", 07:21:52 INFO - "buildnumber": 583, 07:21:52 INFO - "slavename": "talos-linux64-ix-013", 07:21:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:21:52 INFO - "platform": "ubuntu64_hw", 07:21:52 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 07:21:52 INFO - "branch": "mozilla-central", 07:21:52 INFO - "repo_path": "mozilla-central", 07:21:52 INFO - "revision": "62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5", 07:21:52 INFO - "stage_platform": "linux64-pgo", 07:21:52 INFO - "builduid": "99954b29e98b41d4aeb527ec8ba4d20f", 07:21:52 INFO - "slavebuilddir": "test" 07:21:52 INFO - } 07:21:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 07:21:52 INFO - Running post-action listener: _resource_record_post_action 07:21:52 INFO - [mozharness: 2016-09-20 14:21:52.498603Z] Finished read-buildbot-config step (success) 07:21:52 INFO - [mozharness: 2016-09-20 14:21:52.498694Z] Running download-and-extract step. 07:21:52 INFO - Running pre-action listener: _resource_record_pre_action 07:21:52 INFO - Running main action method: download_and_extract 07:21:52 INFO - mkdir: /builds/slave/test/build/tests 07:21:52 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')]} 07:21:52 INFO - trying https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 07:21:52 INFO - Downloading https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 07:21:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:21:53 INFO - Downloaded 1361 bytes. 07:21:53 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 07:21:53 INFO - Using the following test package requirements: 07:21:53 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 07:21:53 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:21:53 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:21:53 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:21:53 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:21:53 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:21:53 INFO - u'jsshell-linux-x86_64.zip'], 07:21:53 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:21:53 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:21:53 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 07:21:53 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:21:53 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:21:53 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:21:53 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:21:53 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:21:53 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:21:53 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 07:21:53 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:21:53 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:21:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 07:21:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:21:53 INFO - Fetch https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:21:53 INFO - Expected file size: 21941142 07:21:57 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:21:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 07:21:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:21:57 INFO - Fetch https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:21:58 INFO - Expected file size: 21941142 07:22:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 07:22:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:22:03 INFO - Fetch https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:22:03 INFO - Expected file size: 14414059 07:22:04 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')]} 07:22:04 INFO - trying https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 07:22:04 INFO - Downloading https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 07:22:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:22:05 INFO - Downloaded 59575811 bytes. 07:22:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 07:22:05 INFO - mkdir: /builds/slave/test/properties 07:22:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:22:05 INFO - Writing to file /builds/slave/test/properties/build_url 07:22:05 INFO - Contents: 07:22:05 INFO - build_url:https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 07:22:06 INFO - Running post-action listener: _resource_record_post_action 07:22:06 INFO - Running post-action listener: set_extra_try_arguments 07:22:06 INFO - [mozharness: 2016-09-20 14:22:06.306857Z] Finished download-and-extract step (success) 07:22:06 INFO - [mozharness: 2016-09-20 14:22:06.307083Z] Running populate-webroot step. 07:22:06 INFO - Running pre-action listener: _resource_record_pre_action 07:22:06 INFO - Running main action method: populate_webroot 07:22:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:22:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:22:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:22:06 INFO - '--tppagecycles', 07:22:06 INFO - '7'], 07:22:06 INFO - 'tests': ['tsvgm']}}, 07:22:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:22:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:22:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:22:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:22:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:22:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:22:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:06 INFO - 'talos_options': ['--disable-e10s'], 07:22:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:22:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:22:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:06 INFO - 'talos_options': ['--disable-e10s'], 07:22:06 INFO - 'tests': ['damp', 'tps']}, 07:22:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:06 INFO - 'tests': ['damp', 'tps']}, 07:22:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:22:06 INFO - 'tests': ['dromaeo_dom']}, 07:22:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:22:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:22:06 INFO - 'tests': ['basic_compositor_video']}, 07:22:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:22:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:22:06 INFO - 'tests': ['a11yr', 07:22:06 INFO - 'ts_paint', 07:22:06 INFO - 'tpaint', 07:22:06 INFO - 'sessionrestore', 07:22:06 INFO - 'sessionrestore_no_auto_restore', 07:22:06 INFO - 'tabpaint']}, 07:22:06 INFO - 'other-e10s': {'tests': ['a11yr', 07:22:06 INFO - 'ts_paint', 07:22:06 INFO - 'tpaint', 07:22:06 INFO - 'sessionrestore', 07:22:06 INFO - 'sessionrestore_no_auto_restore', 07:22:06 INFO - 'tabpaint']}, 07:22:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:22:06 INFO - 'ts_paint', 07:22:06 INFO - 'tpaint', 07:22:06 INFO - 'sessionrestore', 07:22:06 INFO - 'sessionrestore_no_auto_restore', 07:22:06 INFO - 'tabpaint']}, 07:22:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:22:06 INFO - 'ts_paint', 07:22:06 INFO - 'tpaint', 07:22:06 INFO - 'sessionrestore', 07:22:06 INFO - 'sessionrestore_no_auto_restore', 07:22:06 INFO - 'tabpaint']}, 07:22:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:22:06 INFO - 'tests': ['a11yr', 07:22:06 INFO - 'ts_paint', 07:22:06 INFO - 'tpaint', 07:22:06 INFO - 'sessionrestore', 07:22:06 INFO - 'sessionrestore_no_auto_restore', 07:22:06 INFO - 'tabpaint']}, 07:22:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:22:06 INFO - 'tests': ['a11yr', 07:22:06 INFO - 'ts_paint', 07:22:06 INFO - 'tpaint', 07:22:06 INFO - 'sessionrestore', 07:22:06 INFO - 'sessionrestore_no_auto_restore', 07:22:06 INFO - 'tabpaint']}, 07:22:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:22:06 INFO - 'tests': ['tsvgx', 07:22:06 INFO - 'tsvgr_opacity', 07:22:06 INFO - 'tart', 07:22:06 INFO - 'tscrollx', 07:22:06 INFO - 'cart']}, 07:22:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:22:06 INFO - 'tsvgr_opacity', 07:22:06 INFO - 'tart', 07:22:06 INFO - 'tscrollx', 07:22:06 INFO - 'cart']}, 07:22:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:06 INFO - 'talos_options': ['--disable-e10s'], 07:22:06 INFO - 'tests': ['tp5o']}, 07:22:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:06 INFO - 'tests': ['tp5o']}, 07:22:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:06 INFO - 'talos_options': ['--disable-e10s', 07:22:06 INFO - '--xperf_path', 07:22:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:22:06 INFO - 'tests': ['tp5n']}, 07:22:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:22:06 INFO - 'talos_options': ['--xperf_path', 07:22:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:22:06 INFO - 'tests': ['tp5n']}}, 07:22:06 INFO - 'talos.zip': {'path': '', 07:22:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:22:06 INFO - Running post-action listener: _resource_record_post_action 07:22:06 INFO - [mozharness: 2016-09-20 14:22:06.327027Z] Finished populate-webroot step (success) 07:22:06 INFO - [mozharness: 2016-09-20 14:22:06.327117Z] Running create-virtualenv step. 07:22:06 INFO - Running pre-action listener: _resource_record_pre_action 07:22:06 INFO - Running main action method: create_virtualenv 07:22:06 INFO - Creating virtualenv /builds/slave/test/build/venv 07:22:06 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 07:22:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:22:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:22:06 INFO - Using real prefix '/usr' 07:22:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:22:06 INFO - Installing distribute.............................................................................................................................................................................................done. 07:22:08 INFO - Installing pip.................done. 07:22:08 INFO - Return code: 0 07:22:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:22: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')]} 07:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22: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')]} 07:22:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:08 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 0x7f0406596f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1976790>, '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 0x1977690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1977b20>, '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': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', '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 07:22:08 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 07:22:08 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 07:22:08 INFO - Using env: {'DISPLAY': ':0', 07:22:08 INFO - 'HOME': '/home/cltbld', 07:22:08 INFO - 'LANG': 'en_US.UTF-8', 07:22:08 INFO - 'LANGUAGE': 'en_US:en', 07:22:08 INFO - 'LOGNAME': 'cltbld', 07:22:08 INFO - 'MAIL': '/var/mail/cltbld', 07:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:08 INFO - 'MOZ_NO_REMOTE': '1', 07:22:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:22:08 INFO - 'NO_EM_RESTART': '1', 07:22:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:22:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:08 INFO - 'PWD': '/builds/slave/test', 07:22:08 INFO - 'SHELL': '/bin/bash', 07:22:08 INFO - 'SHLVL': '1', 07:22:08 INFO - 'TERM': 'linux', 07:22:08 INFO - 'TMOUT': '86400', 07:22:08 INFO - 'USER': 'cltbld', 07:22:08 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', 07:22:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:08 INFO - '_': '/tools/buildbot/bin/python'} 07:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:09 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 07:22:09 INFO - Cleaning up... 07:22:09 INFO - Return code: 0 07:22:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:22: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')]} 07:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22: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')]} 07:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22: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', '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 0x7f0406596f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1976790>, '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 0x1977690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1977b20>, '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': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', '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 07:22: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', 'psutil>=3.1.1'] in /builds/slave/test/build 07:22: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 psutil>=3.1.1 07:22:09 INFO - Using env: {'DISPLAY': ':0', 07:22:09 INFO - 'HOME': '/home/cltbld', 07:22:09 INFO - 'LANG': 'en_US.UTF-8', 07:22:09 INFO - 'LANGUAGE': 'en_US:en', 07:22:09 INFO - 'LOGNAME': 'cltbld', 07:22:09 INFO - 'MAIL': '/var/mail/cltbld', 07:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:09 INFO - 'MOZ_NO_REMOTE': '1', 07:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:22:09 INFO - 'NO_EM_RESTART': '1', 07:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:09 INFO - 'PWD': '/builds/slave/test', 07:22:09 INFO - 'SHELL': '/bin/bash', 07:22:09 INFO - 'SHLVL': '1', 07:22:09 INFO - 'TERM': 'linux', 07:22:09 INFO - 'TMOUT': '86400', 07:22:09 INFO - 'USER': 'cltbld', 07:22:09 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', 07:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:09 INFO - '_': '/tools/buildbot/bin/python'} 07:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:09 INFO - Downloading/unpacking psutil>=3.1.1 07:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:09 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 07:22:09 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 07:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:22:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:22:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:22:11 INFO - Installing collected packages: psutil 07:22:11 INFO - Running setup.py install for psutil 07:22:11 INFO - building 'psutil._psutil_linux' extension 07:22:11 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 07:22:11 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 07:22:11 INFO - building 'psutil._psutil_posix' extension 07:22:11 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 07:22:11 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 07:22:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:22:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:22:11 INFO - Successfully installed psutil 07:22:11 INFO - Cleaning up... 07:22:11 INFO - Return code: 0 07:22:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:22:11 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')]} 07:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:11 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')]} 07:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7f0406596f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1976790>, '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 0x1977690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1977b20>, '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': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', '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 07:22:11 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 07:22:11 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 07:22:11 INFO - Using env: {'DISPLAY': ':0', 07:22:11 INFO - 'HOME': '/home/cltbld', 07:22:11 INFO - 'LANG': 'en_US.UTF-8', 07:22:11 INFO - 'LANGUAGE': 'en_US:en', 07:22:11 INFO - 'LOGNAME': 'cltbld', 07:22:11 INFO - 'MAIL': '/var/mail/cltbld', 07:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:11 INFO - 'MOZ_NO_REMOTE': '1', 07:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:22:11 INFO - 'NO_EM_RESTART': '1', 07:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:11 INFO - 'PWD': '/builds/slave/test', 07:22:11 INFO - 'SHELL': '/bin/bash', 07:22:11 INFO - 'SHLVL': '1', 07:22:11 INFO - 'TERM': 'linux', 07:22:11 INFO - 'TMOUT': '86400', 07:22:11 INFO - 'USER': 'cltbld', 07:22:11 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', 07:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:11 INFO - '_': '/tools/buildbot/bin/python'} 07:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:22:12 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 07:22:12 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 07:22:12 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 07:22:12 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 07:22:12 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 07:22:12 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 07:22:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:22:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:22:14 INFO - Installing collected packages: mozsystemmonitor 07:22:14 INFO - Running setup.py install for mozsystemmonitor 07:22:14 INFO - Successfully installed mozsystemmonitor 07:22:14 INFO - Cleaning up... 07:22:14 INFO - Return code: 0 07:22:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:22:14 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')]} 07:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:14 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')]} 07:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:14 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 0x7f0406596f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1976790>, '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 0x1977690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1977b20>, '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': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', '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 07:22:14 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 07:22:14 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 07:22:14 INFO - Using env: {'DISPLAY': ':0', 07:22:14 INFO - 'HOME': '/home/cltbld', 07:22:14 INFO - 'LANG': 'en_US.UTF-8', 07:22:14 INFO - 'LANGUAGE': 'en_US:en', 07:22:14 INFO - 'LOGNAME': 'cltbld', 07:22:14 INFO - 'MAIL': '/var/mail/cltbld', 07:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:14 INFO - 'MOZ_NO_REMOTE': '1', 07:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:22:14 INFO - 'NO_EM_RESTART': '1', 07:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:14 INFO - 'PWD': '/builds/slave/test', 07:22:14 INFO - 'SHELL': '/bin/bash', 07:22:14 INFO - 'SHLVL': '1', 07:22:14 INFO - 'TERM': 'linux', 07:22:14 INFO - 'TMOUT': '86400', 07:22:14 INFO - 'USER': 'cltbld', 07:22:14 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', 07:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:14 INFO - '_': '/tools/buildbot/bin/python'} 07:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:14 INFO - Downloading/unpacking jsonschema==2.5.1 07:22:14 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 07:22:14 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 07:22:14 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 07:22:14 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 07:22:14 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 07:22:14 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 07:22:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:22:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:22:16 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 07:22:16 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 07:22:16 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 07:22:16 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 07:22:16 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 07:22:16 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 07:22:16 INFO - Downloading functools32-3.2.3-2.tar.gz 07:22:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:22:16 INFO - warning: no files found matching '*.txt' 07:22:16 INFO - no previously-included directories found matching 'build' 07:22:16 INFO - no previously-included directories found matching 'dist' 07:22:16 INFO - no previously-included directories found matching '.git*' 07:22:16 INFO - Installing collected packages: jsonschema, functools32 07:22:16 INFO - Running setup.py install for functools32 07:22:16 INFO - warning: no files found matching '*.txt' 07:22:16 INFO - no previously-included directories found matching 'build' 07:22:16 INFO - no previously-included directories found matching 'dist' 07:22:16 INFO - no previously-included directories found matching '.git*' 07:22:16 INFO - Successfully installed jsonschema functools32 07:22:16 INFO - Cleaning up... 07:22:16 INFO - Return code: 0 07:22:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:22:16 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')]} 07:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:16 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')]} 07:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:16 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 0x7f0406596f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1976790>, '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 0x1977690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1977b20>, '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': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', '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 07:22:16 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 07:22:16 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 07:22:16 INFO - Using env: {'DISPLAY': ':0', 07:22:16 INFO - 'HOME': '/home/cltbld', 07:22:16 INFO - 'LANG': 'en_US.UTF-8', 07:22:16 INFO - 'LANGUAGE': 'en_US:en', 07:22:16 INFO - 'LOGNAME': 'cltbld', 07:22:16 INFO - 'MAIL': '/var/mail/cltbld', 07:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:16 INFO - 'MOZ_NO_REMOTE': '1', 07:22:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:22:16 INFO - 'NO_EM_RESTART': '1', 07:22:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:16 INFO - 'PWD': '/builds/slave/test', 07:22:16 INFO - 'SHELL': '/bin/bash', 07:22:16 INFO - 'SHLVL': '1', 07:22:16 INFO - 'TERM': 'linux', 07:22:16 INFO - 'TMOUT': '86400', 07:22:16 INFO - 'USER': 'cltbld', 07:22:16 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', 07:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:16 INFO - '_': '/tools/buildbot/bin/python'} 07:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:22:17 INFO - Cleaning up... 07:22:17 INFO - Return code: 0 07:22:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:22:17 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')]} 07:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:17 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')]} 07:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:17 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 0x7f0406596f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1976790>, '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 0x1977690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1977b20>, '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': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', '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 07:22:17 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 07:22:17 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 07:22:17 INFO - Using env: {'DISPLAY': ':0', 07:22:17 INFO - 'HOME': '/home/cltbld', 07:22:17 INFO - 'LANG': 'en_US.UTF-8', 07:22:17 INFO - 'LANGUAGE': 'en_US:en', 07:22:17 INFO - 'LOGNAME': 'cltbld', 07:22:17 INFO - 'MAIL': '/var/mail/cltbld', 07:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:17 INFO - 'MOZ_NO_REMOTE': '1', 07:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:22:17 INFO - 'NO_EM_RESTART': '1', 07:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:17 INFO - 'PWD': '/builds/slave/test', 07:22:17 INFO - 'SHELL': '/bin/bash', 07:22:17 INFO - 'SHLVL': '1', 07:22:17 INFO - 'TERM': 'linux', 07:22:17 INFO - 'TMOUT': '86400', 07:22:17 INFO - 'USER': 'cltbld', 07:22:17 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', 07:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:17 INFO - '_': '/tools/buildbot/bin/python'} 07:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:17 INFO - Downloading/unpacking blobuploader==1.2.4 07:22:17 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 07:22:17 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 07:22:17 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 07:22:17 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 07:22:17 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 07:22:17 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 07:22:19 INFO - Downloading blobuploader-1.2.4.tar.gz 07:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:22:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:22:19 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 07:22:19 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 07:22:19 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 07:22:19 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 07:22:19 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 07:22:19 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 07:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:22:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:22:19 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 07:22:19 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 07:22:19 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 07:22:19 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 07:22:19 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 07:22:19 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 07:22:19 INFO - Downloading docopt-0.6.1.tar.gz 07:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:22:19 INFO - Installing collected packages: blobuploader, requests, docopt 07:22:19 INFO - Running setup.py install for blobuploader 07:22:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:22:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:22:19 INFO - Running setup.py install for requests 07:22:20 INFO - Running setup.py install for docopt 07:22:20 INFO - Successfully installed blobuploader requests docopt 07:22:20 INFO - Cleaning up... 07:22:20 INFO - Return code: 0 07:22:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:22:20 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')]} 07:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:20 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')]} 07:22:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:20 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 0x7f0406596f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1976790>, '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 0x1977690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1977b20>, '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': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', '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 07:22:20 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 07:22:20 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 07:22:20 INFO - Using env: {'DISPLAY': ':0', 07:22:20 INFO - 'HOME': '/home/cltbld', 07:22:20 INFO - 'LANG': 'en_US.UTF-8', 07:22:20 INFO - 'LANGUAGE': 'en_US:en', 07:22:20 INFO - 'LOGNAME': 'cltbld', 07:22:20 INFO - 'MAIL': '/var/mail/cltbld', 07:22:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:20 INFO - 'MOZ_NO_REMOTE': '1', 07:22:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:22:20 INFO - 'NO_EM_RESTART': '1', 07:22:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:22:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:20 INFO - 'PWD': '/builds/slave/test', 07:22:20 INFO - 'SHELL': '/bin/bash', 07:22:20 INFO - 'SHLVL': '1', 07:22:20 INFO - 'TERM': 'linux', 07:22:20 INFO - 'TMOUT': '86400', 07:22:20 INFO - 'USER': 'cltbld', 07:22:20 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', 07:22:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:20 INFO - '_': '/tools/buildbot/bin/python'} 07:22:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:22:20 INFO - Running setup.py (path:/tmp/pip-i7V1EI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:22:20 INFO - Running setup.py (path:/tmp/pip-jTnnvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:22:20 INFO - Running setup.py (path:/tmp/pip-Nhp7zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:22:20 INFO - Running setup.py (path:/tmp/pip-PqV9uD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:22:21 INFO - Running setup.py (path:/tmp/pip-mM_q6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:22:21 INFO - Running setup.py (path:/tmp/pip-ybkmI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:22:21 INFO - Running setup.py (path:/tmp/pip-F46u1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:22:21 INFO - Running setup.py (path:/tmp/pip-si0m90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:22:21 INFO - Running setup.py (path:/tmp/pip-x6l1Wh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:22:21 INFO - Running setup.py (path:/tmp/pip-o1p4xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:22:21 INFO - Running setup.py (path:/tmp/pip-FJ2ydg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:22:21 INFO - Running setup.py (path:/tmp/pip-jUnMCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:22:21 INFO - Running setup.py (path:/tmp/pip-u6m5V4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:22:21 INFO - Running setup.py (path:/tmp/pip-F4OvMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:21 INFO - Running setup.py (path:/tmp/pip-3DgvZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:22:21 INFO - Running setup.py (path:/tmp/pip-ZJmIST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:22:21 INFO - Running setup.py (path:/tmp/pip-KKlfcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:22:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:22:21 INFO - Running setup.py install for manifestparser 07:22:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:22:22 INFO - Running setup.py install for mozcrash 07:22:22 INFO - Running setup.py install for mozdebug 07:22:22 INFO - Running setup.py install for mozdevice 07:22:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:22:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:22:22 INFO - Running setup.py install for mozfile 07:22:22 INFO - Running setup.py install for mozhttpd 07:22:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:22:22 INFO - Running setup.py install for mozinfo 07:22:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:22:22 INFO - Running setup.py install for mozInstall 07:22:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:22:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:22:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:22:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:22:22 INFO - Running setup.py install for mozleak 07:22:22 INFO - Running setup.py install for mozlog 07:22:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:22:22 INFO - Running setup.py install for moznetwork 07:22:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:22:23 INFO - Running setup.py install for mozprocess 07:22:23 INFO - Running setup.py install for mozprofile 07:22:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:22:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:22:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:22:23 INFO - Running setup.py install for mozrunner 07:22:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:22:23 INFO - Running setup.py install for mozscreenshot 07:22:23 INFO - Running setup.py install for moztest 07:22:23 INFO - Running setup.py install for mozversion 07:22:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:22:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:22:23 INFO - Cleaning up... 07:22:23 INFO - Return code: 0 07:22:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:22:23 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')]} 07:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:23 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')]} 07:22:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:23 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 0x7f0406596f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1976790>, '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 0x1977690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1977b20>, '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': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', '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 07:22:23 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 07:22:23 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 07:22:23 INFO - Using env: {'DISPLAY': ':0', 07:22:23 INFO - 'HOME': '/home/cltbld', 07:22:23 INFO - 'LANG': 'en_US.UTF-8', 07:22:23 INFO - 'LANGUAGE': 'en_US:en', 07:22:23 INFO - 'LOGNAME': 'cltbld', 07:22:23 INFO - 'MAIL': '/var/mail/cltbld', 07:22:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:23 INFO - 'MOZ_NO_REMOTE': '1', 07:22:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:22:23 INFO - 'NO_EM_RESTART': '1', 07:22:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:22:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:23 INFO - 'PWD': '/builds/slave/test', 07:22:23 INFO - 'SHELL': '/bin/bash', 07:22:23 INFO - 'SHLVL': '1', 07:22:23 INFO - 'TERM': 'linux', 07:22:23 INFO - 'TMOUT': '86400', 07:22:23 INFO - 'USER': 'cltbld', 07:22:23 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', 07:22:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:23 INFO - '_': '/tools/buildbot/bin/python'} 07:22:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:22:23 INFO - Running setup.py (path:/tmp/pip-02D9EB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:22:24 INFO - Running setup.py (path:/tmp/pip-cU9f1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:22:24 INFO - Running setup.py (path:/tmp/pip-PAXTiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:22:24 INFO - Running setup.py (path:/tmp/pip-it3Kll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:22:24 INFO - Running setup.py (path:/tmp/pip-k2Go5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:22:24 INFO - Running setup.py (path:/tmp/pip-K9qk_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:22:24 INFO - Running setup.py (path:/tmp/pip-CLZFmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:22:24 INFO - Running setup.py (path:/tmp/pip-yrZNkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:22:24 INFO - Running setup.py (path:/tmp/pip-O7Q5Tx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:22:24 INFO - Running setup.py (path:/tmp/pip-eq6sXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:22:24 INFO - Running setup.py (path:/tmp/pip-zCwxBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:22:24 INFO - Running setup.py (path:/tmp/pip-Mg31zQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:22:24 INFO - Running setup.py (path:/tmp/pip-wZUf70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:22:24 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)) 07:22:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:22:24 INFO - Running setup.py (path:/tmp/pip-Q1iouE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:22:25 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)) 07:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:25 INFO - Running setup.py (path:/tmp/pip-0QqF8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:25 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)) 07:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:22:25 INFO - Running setup.py (path:/tmp/pip-qqdzuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:22:25 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)) 07:22:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:22:25 INFO - Running setup.py (path:/tmp/pip-TN83qS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:22:25 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)) 07:22:25 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)) 07:22:25 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)) 07:22:25 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)) 07:22:25 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)) 07:22:25 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)) 07:22:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:22:25 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 07:22:25 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 07:22:25 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 07:22:25 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 07:22:25 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 07:22:25 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 07:22:27 INFO - Downloading blessings-1.6.tar.gz 07:22:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:22:27 INFO - Installing collected packages: blessings 07:22:27 INFO - Running setup.py install for blessings 07:22:27 INFO - Successfully installed blessings 07:22:27 INFO - Cleaning up... 07:22:27 INFO - Return code: 0 07:22:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:22:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:22:27 INFO - Reading from file tmpfile_stdout 07:22:27 INFO - Current package versions: 07:22:27 INFO - argparse == 1.2.1 07:22:27 INFO - blessings == 1.6 07:22:27 INFO - blobuploader == 1.2.4 07:22:27 INFO - docopt == 0.6.1 07:22:27 INFO - functools32 == 3.2.3-2 07:22:27 INFO - jsonschema == 2.5.1 07:22:27 INFO - manifestparser == 1.1 07:22:27 INFO - mozInstall == 1.12 07:22:27 INFO - mozcrash == 0.17 07:22:27 INFO - mozdebug == 0.1 07:22:27 INFO - mozdevice == 0.48 07:22:27 INFO - mozfile == 1.2 07:22:27 INFO - mozhttpd == 0.7 07:22:27 INFO - mozinfo == 0.9 07:22:27 INFO - mozleak == 0.1 07:22:27 INFO - mozlog == 3.4 07:22:27 INFO - moznetwork == 0.27 07:22:27 INFO - mozprocess == 0.23 07:22:27 INFO - mozprofile == 0.28 07:22:27 INFO - mozrunner == 6.12 07:22:27 INFO - mozscreenshot == 0.1 07:22:27 INFO - mozsystemmonitor == 0.3 07:22:27 INFO - moztest == 0.7 07:22:27 INFO - mozversion == 1.4 07:22:27 INFO - psutil == 3.1.1 07:22:27 INFO - requests == 1.2.3 07:22:27 INFO - wsgiref == 0.1.2 07:22:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:22:27 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')]} 07:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:27 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')]} 07:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:27 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 0x7f0406596f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1976790>, '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 0x1977690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1977b20>, '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': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', '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 07:22:27 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 07:22:27 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 07:22:27 INFO - Using env: {'DISPLAY': ':0', 07:22:27 INFO - 'HOME': '/home/cltbld', 07:22:27 INFO - 'LANG': 'en_US.UTF-8', 07:22:27 INFO - 'LANGUAGE': 'en_US:en', 07:22:27 INFO - 'LOGNAME': 'cltbld', 07:22:27 INFO - 'MAIL': '/var/mail/cltbld', 07:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:27 INFO - 'MOZ_NO_REMOTE': '1', 07:22:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:22:27 INFO - 'NO_EM_RESTART': '1', 07:22:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:27 INFO - 'PWD': '/builds/slave/test', 07:22:27 INFO - 'SHELL': '/bin/bash', 07:22:27 INFO - 'SHLVL': '1', 07:22:27 INFO - 'TERM': 'linux', 07:22:27 INFO - 'TMOUT': '86400', 07:22:27 INFO - 'USER': 'cltbld', 07:22:27 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', 07:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:27 INFO - '_': '/tools/buildbot/bin/python'} 07:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:27 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)) 07:22:27 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)) 07:22:27 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)) 07:22:27 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)) 07:22:27 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)) 07:22:27 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)) 07:22:27 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)) 07:22:27 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)) 07:22:27 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)) 07:22:27 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)) 07:22:27 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)) 07:22:27 INFO - Cleaning up... 07:22:27 INFO - Return code: 0 07:22:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:22:27 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')]} 07:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:27 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')]} 07:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:27 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 0x7f0406596f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1976790>, '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 0x1977690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1977b20>, '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': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', '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 07:22:27 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 07:22:27 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 07:22:27 INFO - Using env: {'DISPLAY': ':0', 07:22:27 INFO - 'HOME': '/home/cltbld', 07:22:27 INFO - 'LANG': 'en_US.UTF-8', 07:22:27 INFO - 'LANGUAGE': 'en_US:en', 07:22:27 INFO - 'LOGNAME': 'cltbld', 07:22:27 INFO - 'MAIL': '/var/mail/cltbld', 07:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:27 INFO - 'MOZ_NO_REMOTE': '1', 07:22:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:22:27 INFO - 'NO_EM_RESTART': '1', 07:22:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:27 INFO - 'PWD': '/builds/slave/test', 07:22:27 INFO - 'SHELL': '/bin/bash', 07:22:27 INFO - 'SHLVL': '1', 07:22:27 INFO - 'TERM': 'linux', 07:22:27 INFO - 'TMOUT': '86400', 07:22:27 INFO - 'USER': 'cltbld', 07:22:27 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', 07:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:27 INFO - '_': '/tools/buildbot/bin/python'} 07:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:22:28 INFO - Cleaning up... 07:22:28 INFO - Return code: 0 07:22:28 INFO - Running post-action listener: _resource_record_post_action 07:22:28 INFO - Running post-action listener: _start_resource_monitoring 07:22:28 INFO - Starting resource monitoring. 07:22:28 INFO - [mozharness: 2016-09-20 14:22:28.043333Z] Finished create-virtualenv step (success) 07:22:28 INFO - [mozharness: 2016-09-20 14:22:28.043844Z] Running install step. 07:22:28 INFO - Running pre-action listener: _resource_record_pre_action 07:22:28 INFO - Running main action method: install 07:22:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:22:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:22:28 INFO - Reading from file tmpfile_stdout 07:22:28 INFO - Detecting whether we're running mozinstall >=1.0... 07:22:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:22:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:22:28 INFO - Reading from file tmpfile_stdout 07:22:28 INFO - Output received: 07:22:28 INFO - Usage: mozinstall [options] installer 07:22:28 INFO - Options: 07:22:28 INFO - -h, --help show this help message and exit 07:22:28 INFO - -d DEST, --destination=DEST 07:22:28 INFO - Directory to install application into. [default: 07:22:28 INFO - "/builds/slave/test"] 07:22:28 INFO - --app=APP Application being installed. [default: firefox] 07:22:28 INFO - mkdir: /builds/slave/test/build/application 07:22:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:22:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:22:45 INFO - Reading from file tmpfile_stdout 07:22:45 INFO - Output received: 07:22:45 INFO - /builds/slave/test/build/application/firefox/firefox 07:22:45 INFO - Running post-action listener: _resource_record_post_action 07:22:45 INFO - [mozharness: 2016-09-20 14:22:45.277684Z] Finished install step (success) 07:22:45 INFO - [mozharness: 2016-09-20 14:22:45.277929Z] Running run-tests step. 07:22:45 INFO - Running pre-action listener: _resource_record_pre_action 07:22:45 INFO - Running main action method: run_tests 07:22:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:22:45 INFO - Python 2.7.3 07:22:45 INFO - Return code: 0 07:22:45 INFO - grabbing minidump binary from tooltool 07:22: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')]} 07:22:45 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 0x1976790>, '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 0x1977690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1977b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:22:45 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 07:22:45 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 07:22:45 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 07:22:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:22:45 INFO - Return code: 0 07:22:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:22:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:22:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:22:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:22:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:22:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 07:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --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 07:22:45 INFO - Using env: {'DISPLAY': ':0', 07:22:45 INFO - 'HOME': '/home/cltbld', 07:22:45 INFO - 'LANG': 'en_US.UTF-8', 07:22:45 INFO - 'LANGUAGE': 'en_US:en', 07:22:45 INFO - 'LOGNAME': 'cltbld', 07:22:45 INFO - 'MAIL': '/var/mail/cltbld', 07:22:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:22:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:45 INFO - 'MOZ_NO_REMOTE': '1', 07:22:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:22:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:22:45 INFO - 'NO_EM_RESTART': '1', 07:22:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:45 INFO - 'PWD': '/builds/slave/test', 07:22:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:22:45 INFO - 'SHELL': '/bin/bash', 07:22:45 INFO - 'SHLVL': '1', 07:22:45 INFO - 'TERM': 'linux', 07:22:45 INFO - 'TMOUT': '86400', 07:22:45 INFO - 'USER': 'cltbld', 07:22:45 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099', 07:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:45 INFO - '_': '/tools/buildbot/bin/python'} 07:22:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 07:22:45 INFO - mozversion application_buildid: 20160920043002 07:22:45 INFO - mozversion application_changeset: 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 07:22:45 INFO - mozversion application_display_name: Nightly 07:22:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:22:45 INFO - mozversion application_name: Firefox 07:22:45 INFO - mozversion application_remotingname: firefox 07:22:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:22:45 INFO - mozversion application_vendor: Mozilla 07:22:45 INFO - mozversion application_version: 52.0a1 07:22:45 INFO - mozversion platform_buildid: 20160920043002 07:22:45 INFO - mozversion platform_changeset: 62f79d676e0e11b3ad59a5425b3ebb3ec5bbefb5 07:22:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:22:45 INFO - mozversion platform_version: 52.0a1 07:22:45 INFO - using testdate: 1474381365 07:22:45 INFO - actual date: 1474381365 07:22:45 INFO - starting webserver on 'localhost:39988' 07:22:45 INFO - SUITE-START | Running 2 tests 07:22:45 INFO - TEST-START | dromaeo_css 07:22:45 INFO - operating with platform_type : linux_ 07:22:45 INFO - Initialising browser for dromaeo_css test... 07:22:45 INFO - TEST-INFO | started process 32376 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgsJnS6/profile http://localhost:39988/getInfo.html) 07:22:49 INFO - PROCESS | 32376 | __metrics Screen width/height:1600/1200 07:22:49 INFO - PROCESS | 32376 | colorDepth:24 07:22:49 INFO - PROCESS | 32376 | Browser inner width/height: 1024/697 07:22:49 INFO - PROCESS | 32376 | __metrics 07:22:50 INFO - PROCESS | 32376 | 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" 07:22:50 INFO - PROCESS | 32376 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:22:50 INFO - PROCESS | 32376 | console.error: PushService: 07:22:50 INFO - PROCESS | 32376 | stateChangeProcessEnqueue: Error transitioning state 07:22:50 INFO - PROCESS | 32376 | UnknownError 07:22:51 INFO - TEST-INFO | 32376: exit 0 07:22:51 INFO - Browser initialized. 07:22:51 INFO - Running cycle 1/1 for dromaeo_css test... 07:22:51 INFO - TEST-INFO | started process 32457 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgsJnS6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:23:03 INFO - PROCESS | 32457 | RSS: Main: 173621248 07:23:03 INFO - PROCESS | 32457 | 07:23:07 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.15003 seconds. 07:23:19 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 07:23:32 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 07:23:39 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 5.80014 seconds. 07:23:42 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 07:23:48 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 07:23:52 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.10002 seconds. 07:23:55 INFO - PROCESS | 32457 | 1474381435229 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] 07:24:05 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:24:21 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 07:24:23 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 07:24:25 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 07:24:26 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 07:24:27 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 2.18337 seconds. 07:24:38 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:24:44 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.1667 seconds. 07:24:49 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 07:25:13 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:25:25 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.10003 seconds. 07:25:31 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.03331 seconds. 07:25:58 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.33332 seconds. 07:26:03 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 07:26:04 INFO - PROCESS | 32457 | Cycle 1(1): loaded http://localhost:39988/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39988/tests/dromaeo/cssquery-ext.html) 07:26:05 INFO - PROCESS | 32457 | RSS: Main: 311726080 07:26:05 INFO - PROCESS | 32457 | 07:26:28 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 07:26:44 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:27:12 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:27:19 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:27:37 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 07:27:50 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:27:51 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:28:08 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 07:28:37 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 07:28:41 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:28:49 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:29:08 INFO - PROCESS | 32457 | Cycle 1(1): loaded http://localhost:39988/tests/dromaeo/cssquery-ext.html (next: http://localhost:39988/tests/dromaeo/cssquery-jquery.html) 07:29:08 INFO - PROCESS | 32457 | RSS: Main: 269758464 07:29:08 INFO - PROCESS | 32457 | 07:29:12 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 07:29:15 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 07:29:19 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:29:23 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:29:25 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:29:27 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 07:29:31 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 07:29:51 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:30:40 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:31:04 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:31:10 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:31:16 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:31:22 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 07:31:32 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:31:46 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:31:49 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 2.00001 seconds. 07:31:49 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 2.00001 seconds. 07:32:08 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:32:10 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:32:10 INFO - PROCESS | 32457 | Cycle 1(1): loaded http://localhost:39988/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39988/tests/dromaeo/cssquery-mootools.html) 07:32:11 INFO - PROCESS | 32457 | RSS: Main: 239976448 07:32:11 INFO - PROCESS | 32457 | 07:32:16 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 07:32:40 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:33:02 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:33:06 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 07:33:12 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:33:16 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:33:28 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:33:42 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:33:50 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:33:52 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:34:04 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 07:34:08 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:34:20 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 07:34:28 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:34:37 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:34:39 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:34:45 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:34:53 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:35:02 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:35:13 INFO - PROCESS | 32457 | Cycle 1(1): loaded http://localhost:39988/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39988/tests/dromaeo/cssquery-prototype.html) 07:35:14 INFO - PROCESS | 32457 | RSS: Main: 272048128 07:35:14 INFO - PROCESS | 32457 | 07:35:17 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 07:35:24 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 07:35:39 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:35:48 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:35:50 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:36:01 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:36:12 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 07:36:17 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:36:24 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 07:36:29 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:36:31 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:36:37 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 07:36:39 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:36:59 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 07:37:10 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 07:37:28 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:37:30 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 07:37:32 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:37:35 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:37:46 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:37:56 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 07:38:02 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:38:09 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:38:10 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:38:16 INFO - PROCESS | 32457 | Cycle 1(1): loaded http://localhost:39988/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39988/tests/dromaeo/cssquery-yui.html) 07:38:17 INFO - PROCESS | 32457 | RSS: Main: 217534464 07:38:17 INFO - PROCESS | 32457 | 07:38:22 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 07:38:25 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:38:39 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:38:56 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:39:04 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:39:13 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:39:22 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:39:26 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:39:46 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:39:54 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:39:55 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:40:09 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 07:40:11 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:40:19 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:40:21 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:40:27 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:40:31 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:40:45 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:40:51 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:40:59 INFO - PROCESS | 32457 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:41:20 INFO - PROCESS | 32457 | Cycle 1(1): loaded http://localhost:39988/tests/dromaeo/cssquery-yui.html (next: http://localhost:39988/tests/dromaeo/cssquery-dojo.html) 07:41:20 INFO - PROCESS | 32457 | RSS: Main: 309944320 07:41:20 INFO - PROCESS | 32457 | 07:41:20 INFO - PROCESS | 32457 | __start_tp_report 07:41:20 INFO - PROCESS | 32457 | _x_x_mozilla_page_load 07:41:20 INFO - PROCESS | 32457 | _x_x_mozilla_page_load_details 07:41:20 INFO - PROCESS | 32457 | |i|pagename|runs| 07:41:20 INFO - PROCESS | 32457 | |0;dojo.html;3014.6683864774873;3046.238440389902;3047.085874237728;3076.292452735869;3092.6907309269063;5144.6655967362185;5215.4523775003645;5322.387925388577;5366.543843773279;5396.622236443451;5231.5814734821215;5318.361796584415;5370.113931201347;5401.216823560581;5434.809781657643;4788.138135135674;4803.951960480385;4838.274258861164;4890.168671325883;4892.290617860408;4785.688930219542;4876.634252431082;4880.023995200956;4884.145274576953;4910.901781964359;4831.275308703687;4838.104950584118;4873.707577545341;4894.755262236892;4927.310176575283;4751.786169622375;4809.95190048102;4826.927596086061;4868.926966095476;4869.853904382874;4794.161021821203;4797;4803.279508073782;4833.081714474274;4895.167821470359;5341.1988201769655;5366.382865970424;5387.78448862045;5389.272448219519;5401.162819762943;5508.44915508446;5535.861603459905;5546.168074788774;5582.86042848928;5599.63602365849;5593.105103183511;5624.690642014688;5656.208130861683;5666.320041594994;5715.885682286373;5242.868928276824;5250.212468129735;5251.081060814342;5252.737363131828;5315.760790764348;5631.18347839561;5679.204911312419;5706.3152421709665;5715.914261286084;5749.712514374264;5523.28197334344;5531.197976293495;5531.502164805187;5582.8325150245555;5600.635958662674;5591.27313449249;5596.972015139898;5601.551875849922;5601.887962240814;5602.775888964477;5472.069748142825;5477.972610136924;5587.664740115606;5591.191199124313;5618.943810561924;5675.262215911905;5709.51469125129;5713.542916566665;5767.567432442584;5773.451522105393;4391.385206071152;4417.911641767211;4420.049689316812;4420.58004489573;4472.709273897186;3826.559945606291;3835.5589107252463;3937.7243592948794;3955.4462375267485;3988.581198974124;5319.255304257407;5377.973110134424;5386.811461598825;5405.729713514309;5474.069408200616;5651.265335506358;5652.434756524314;5661.065924122556;5666.15007748841;5675.233843431084;5257.894842103136;5412.891742165135;5416.214648875891;5421;5486.067368547357;4874.025194961022;4921.237208232729;4943.060818444484;4950.950490495121;5023.09584274834;5885.176075349369;5887.293524776968;5889.970550147222;5895.4399332063385;5897.557683173779;5615.943840561706;5624.887502250014;5684.147377893184;5686;5720.513756330592;5574.832755017356;5640.915386269127;5892.793752218823;5907.675077870758;5920.911186332122;5666.886662266649;5712.971435142797;5715.2855893013375;5722.742476588647;5723.112917497794;5749.223854779719;5757.740901659519;5757.827265181883;5759.222504961944;5774.335951365647;5310.309659744208;5563.860903477445;5566.192902029264;5569.916451253153;5573.860653483695;5408.188771684278;5408.242846001642;5413.10683737179;5460.945390546202;5546.362168350692;5383.865403364947;5390.568754499709;5393.6763794172475;5394.676319420847;5397.676139431646;5466.988607107698;5504.119340905436;5523.337199536081;5526.049820653568;5550.056490396723;5255.63210575256;5361.088614935392;5387.622866399314;5420.105682562491;5466.426025267289 07:41:20 INFO - PROCESS | 32457 | |1;ext.html;578.1991941161492;608.5557542993614;614.8278482025032;641.7593402474071;655.6262930129822;32371.67628323719;35759.999999999935;35827.02951337679;35844;35879;35915.10212244695;44739;45733.771331143296;46054.539454605445;50227;8993.59528821203;9128.178463938244;9136.314776391764;9139.771505712377;9144.908550914506;9017.819643607125;9079.2282655974;9117.407368521024;9122;9124;8280.461769984962;8485.490870547756;8512.616932238065;8739.563021848913;10723.624673136443;10993.340399576051;11065.778684426266;11075.833862492069;11076;11080.445977701083;10837.620683276047;10911.399873006982;10913.072388846958;10918.235723499358;10922.235443518957;44086.55913440857;44212.77893610511;44380.556194438286;44411;44411;3312.4865645824934;3417.6069751978594;3428.6228514863346;3472.2881809229048;3480.669336413062;33101.503477447855;33252.50121248183;33287.50068748971;33408.16479588005;33953;4324.848630297956;4562.087582483517;4701.48283688794;4746.786394612251;4752.239641657318;358379;358554;358784;358841;360379.19810401043;58939.705301473216;59141;59228.40771592315;59234.40765592289;59277.703611481666;63692;63705;63779.362206377344;63782.36217637857;63847.36152638507;17758;17886.910565447088;17960.281588736565;17965.101744912965;17971.910140449214;13840.238786866617;13903;13971.720565588632;13979.650508737363;13990.86009139916;13711.862881371058;17855.107244637715;18232.544186395182;18264.726029109574;18286.908565457088;5546.195801608825;6590.429732404366;6766.8646627068165;6802.217744959294;6857.348551887562;887.8712586674895;1007.4660429972126;1059.7986382587283;1070.5931745936493;1115.966521004371;31291.21771955674;31833;34030.65969340324;34950;35505.82247088748;1084.8589683341115;1086.625114335553;1105.303658695017;1139.2594813371209;1205.1443475132578;15751.606209844846;16981.745273820903;16984.23570939286;17006.319747209975;17010.574735631708;527794;531138;534411;535786;536231;57901.71049144727;58137.4186258132;58140.70929645325;58142;58160.70919645375;1226.1171956191529;1234.481517762542;1298.4481595322138;1380.3926272440094;1389.4442223110836;29284;32841.67158328386;32949.341013180085;33281;33899.4915076279;50214.246786298965;50448.24327635015;50997.7450112747;51028;51054.48945510497;392195;392894;393599;393862;394085;25882.48235035251;26081.608775867997;26539.469210616066;28393.574096388158;28748.281292967844;48276;48611.5138848607;48897.75551122222;49029.2645610309;49346;40682;41378;41412.378814317206;41630.37554436625;43247;41384;41715.58284417117;42697.43815371088;42801.571984279755;43100;40252.3962140574;41224.175516488904;41547.7922610385;41713.58286417097;41842.163156737304;34246.315073697886;36973.17051252681;37110.81444592868;37452.812735936146;38166;27038.729612704406;27509;27518.862405687843;27617.447651047267;29628.851855740584 07:41:20 INFO - PROCESS | 32457 | |2;jquery.html;368.37376460017975;369.0660936691734;376.6891218559412;389.188541890159;399.1796857457925;14185;18295.451136465912;19378.612427751435;19886.204551817948;20040.799592004074;15538.476893901592;19488.41534753956;19787.802121978777;19960.20159193634;19989.300374486895;5692.345380281279;5952.404759524056;5953.987822070237;5955.464008239258;5957.553183511232;4268.487781466213;4383.890402739925;4444.444444444444;5795.971020144893;6552.803415897531;6580.309067547911;6584.835379115512;6585.934140658604;6600.768973085943;6610.537262391635;6390;6486.286508484063;6506.186726659168;6508.8047358579315;6522.249941256769;5540;5714.087274026151;5764.135379693038;5774.422557744234;5775.451332123442;23355.649665255034;23602.527949440915;23721.7627823723;23850.522989540113;23896.283111506684;2530.392705750625;2750.559910414344;2876.3240638449947;3046.375493023947;3127.874885004597;13728.45086196561;14480.493182738659;14749.705005899821;14768.483103091448;15177.24113794306;1817.8363947244811;1830.469163942455;1860.7581014468167;1876.4317979655543;1884.4723477426342;77814.61092694501;78319.60840195819;79065;80728.59635701784;80993.59503202506;9632.325737198329;9635.614575416905;9796.28565001122;10009.499525023719;10416.270861039655;13102.279374634296;13111.60665180046;13114.934425327812;13125.934370328278;13129.540466083641;8075.919240807634;8254;8324.042735085422;8374.958125209336;8418.536980466133;8284.378671599654;8404.07555168931;8424.578771061422;8615.741527754177;8630.352723545759;6055.485283750931;6196.504279657616;6208.627482351073;6212.0371342441995;6362.4688297994135;1674.4055860169465;1737.8522825559717;1741.9216135273944;1746.7729195204543;1777.4489908128521;930.1582068228264;990;1007.8840933292619;1018.1600179851652;1052.9157667386592;19375.515612109528;19758;20990.685139722915;21144;21150.259740908994;955.5126885288553;981.7005813227034;988.1008282462924;1005.959761609542;1007.7027276953316;10529.10502607287;11047.944760276147;11099.722506937229;11119.722006949729;11164.665060048212;343893;348606;506069;510470;514320;95135.52432237794;97172.08483745498;100095.49952250338;100666;101392;1015.5886865819355;1039.870016247969;1059.8569193158826;1061.2571200159919;1082.9187810914054;17575;17773.022483763518;18312.542186445444;18458.538536536693;18724.531886702942;40964.79517602393;41886;42377.57622423736;43057.354139688556;43881;57003.42996570147;58000.4199957995;58988.70505647444;59943.70028150006;60324.39675603188;7441.888371674321;7561.865720141803;7797.493162944391;8180.672773089248;8302.792430189294;9507.952460237655;11401.372924489233;11532.250403724067;12408.62774116778;12566.308853013172;11227.887721122685;11235.15736319763;11478.58949620589;11485.196036277715;11580.594679186188;11029.283096598696;11059.778804424028;11061.22571420025;11081.224314297922;11316.15128865322;5986.9401305986385;6965.965170174117;11288.379139147426;11390.772184556097;11697.2981621103;8390.454620449651;8469.491830490191;8599.097094804956;8651.226498567905;8666.480011199348;8972.19250267479;10558.155347572023;10961.342319460859;11088.5564577416;11517.366544840113 07:41:20 INFO - PROCESS | 32457 | |3;mootools.html;1342.261756034181;1476.7194233095715;1484.8292446368666;1635.6892190483807;1734.2369357482708;7432.959385686008;8379.162083791618;8680.869786953193;8725.86911196331;8962.327825413104;8783.824323513525;8881.245094166987;8884.156005179513;8886.20024197822;8888.911110888888;2415.2995631266954;2455.2020593307157;2458.21337172105;2462.704475462947;2463.3718401807514;2340.20433052762;2381.595128828095;2386.6658667786523;2400.95198096038;2401.2796161151587;2381.1189859751917;2394.066314137479;2400.699912510936;2425.333033415807;2436.671049408325;2299.5171014086895;2316.096722278313;2319.7100362454694;2326.0230703104735;2339.1929784224417;2321.419645088728;2350.8354415190943;2357.7406485286606;2361.9527609447887;2366.0890557135453;8935.910640893573;9102.68140615082;9105.863412048824;9115.680951166678;9119.863202051974;5281.498257665516;5449.945500544984;5450.700211488366;5452.754626041838;5531.3915469298345;3237.7733558650903;3346.0463767826054;3375.9662403375896;3378.5438965739554;3390.762646614738;1100.4222783038888;1150.5052827284264;2057.3827851644446;2120.448683342342;2120.5228823514585;12386.75226495474;12556.623301300975;12614.81077783834;12617.936910315482;12628.494860205581;4762.690425122356;4777.791016323179;4804.73573953429;4848.781804818792;4905.411350637946;10678.412687302272;10692.732681682864;10698.197635177392;10705;10711.357318560911;5538.861528461821;5544.390117087117;5545.390007099216;5550.056490396642;5550.777968881281;4208.473940757405;4331.306990881444;4831.51684831514;4955.653104282737;5076.6446348755235;3917.5103112110987;3962.920741585152;4091.8772436826944;4116.341385378325;4123.463949686581;1232.1990706040965;1239.7334573066835;1241.76406482768;1266.259238345578;1286.414681319997;857.9957100214459;880.7269746378643;893.1738142218422;915.2586405011829;942.5767091691458;5754;5777.711114444261;5922.082077278029;6019.127226552126;6274;498.2924247568462;509.19547115557333;510.6578592343139;516.4293455731438;520.0638850069859;4193.22425351311;4206.116715489781;4351.5430879757805;4409.559044095565;4419.1382680377665;10895.891041089488;11052.557897684163;11100.389478578594;11124.888751112547;11251.156163287786;11006.779864402828;11025.283356581796;11025.393603351733;11027.724306892393;11046.558137674403;7833.608319583998;8161.5919204039565;8164.142765009708;8172.305354044973;8406.327493800603;5887.67617781009;6056.727447264796;6114.847128821815;6118.785842495491;6175.320714721467;10432.113270372103;10570.682879513626;10589.735256618646;10634.30876992993;10740.087092597218;10759.838602420814;10995.615153469591;10997.945010274898;11002.559897604006;11007.394593297446;6893.551919125241;7123;7517.774466766005;8112.472689275178;8251.628676709683;8070.192980701883;8455;8522.531260780717;8523.445976011539;8531.36014798905;7477.813054673459;7500.36246919018;7527.892190186756;7542.547447153188;7732.8840067397905;7198.280171982759;7341.963290183515;7420.962895185489;7436.814079648052;7687.923120768721;6840.3501667341525;6913.827154321182;6932.61870597122;6963.2340442552295;6965.094537710065;4995.700257984533;5000.049990501793;5006.397952655261;5256.553192978639;5420.945790542202;5335.786568537215;5364.168553874156;5381.354237491526;5540.141278101901;5540.31294822019 07:41:20 INFO - PROCESS | 32457 | |4;prototype.html;302.7714075872716;321.8471226167569;331.39189587107654;337.6994474917323;338.7408632396216;4950.2327139293375;5206.323177986861;5270.814066834965;5271.710055946922;5302.337207849019;5247.685138891669;5265.078611243036;5321.909008653217;5324.509137441513;5332.413434522199;4155.501339839224;4166.874943765174;4166.916541729135;4172.499300083994;4178.289690752564;3923.39187425948;4125.9174816503655;4181.895452613679;4193.601607847249;4195.916081678364;4116.897077573055;4122.835086596532;4129.050318426764;4142.482189726285;4145.39891715701;4114.629683328515;4118.91762164758;4120.505539335284;4136.462259906193;4142.337226043834;3642.581103173116;3646.890593282206;3647.5075864758182;3654.177809992757;3659.1035196376793;5068.493150684939;5079.187330027178;5125.1287281162295;5129.974005198975;5134.614903882224;4648.628109751212;4691.225947718623;4703.247480403119;4704.482506924235;4710.387649605529;5442.809501667462;5453.700046497441;5463.890722185534;5491.862703432406;5520.309961254843;3650.408210170782;3958.2083583283456;3961.4057891316247;3963.207358528306;3968.3055465293746;5333.173358129496;5558.555315574744;5625.943740562583;5665.263515742927;5672.376038635747;3882.1265215326325;3975.7018223633345;3978.622030907059;3990.521137463465;4005.776793879367;4049.635532802062;4056.3712624542654;4059.6143366380147;4060.451839001697;4064.227796718614;4357.803898824561;4494.595486406239;4538.773061346919;4661.417322834645;4670.276107203321;5418.431064738145;5458.863528411742;5462.481064298965;5477.178423236546;5482.972585137049;1390.8609139086213;1411.2661416063795;1411.9011669183265;1425.7647488164337;1429.4568064135563;858.4677499949946;908.2643059122132;915.6200176926634;923.8891333040078;926.3561824531886;4740.620750339965;4747.287906813936;4762;4764.785584648699;4792.880177995578;4887.95112048882;4939.678920870132;4956.553910148032;4958.504149585085;4971.6271279654175;4738.218193997952;4745.359376484131;4745.857624271277;4748.361815173828;4974.403071631427;2960.585518027478;2989.2228020714338;2993.4555627716704;2999.880004799827;3014.728674419313;5838.9124163137585;5854.765809367663;5870.148828419855;5889.734961926638;5977.372375900555;5618.820047789928;5771.220885180531;5824;5831.7667293307795;5872.11918212263;5878.559108066912;5890.528757699374;5894.941050589525;5911.201987731773;5912.438318359749;5396.92218495874;5651.943480565306;5657.660540367591;5776.451237132634;5783.450572195635;5633.732435187663;5636.323641163087;5637.379888212376;5643.7742490299925;5669.971650141723;5610.204734484925;5685.630434021776;5802.129680547782;5891.823245302648;5920.911186332295;4121.196366708463;4132.8140233688955;4144.3990621359135;4154.584541545822;4158.293090174738;5681.659100453986;5813.970930145491;5817.621854579439;5823.388544202967;5825.038868586806;5534.363548192009;5540.418256083214;5546.7781288748;5573.219749235192;5574.91637625444;5410.48600382963;5490.940897163564;5530.198121272473;5531.944680553143;5600.439956004367;5244.868878278074;5424.755885985219;5435.184722291688;5459.590530710292;5509.173623956439;5555.194496798023;5559.555235581225;5560.416156303691;5561.388247292875;5573.108302671553;5370.140777475585;5419.2413062170535;5527.309086364205;5546.861328466821;5549.38956714753 07:41:20 INFO - PROCESS | 32457 | |5;yui.html;718.364247640838;769.9720872635033;772.5094564951256;802.7110240313483;830.6137645994611;12797;12881.484740610387;15332.61668458289;17340.82659173408;17363.913180434098;11368.204225704183;12041.75916481672;16855;16893.1553422329;16918.323267069303;791.0507391130652;813.0812182234067;815.8653822119372;816.302061737215;816.4203415574949;663.4592806862415;678.2640834694363;771.8456308738247;813.3005615170948;814.9918500815005;838.8573942429771;854.3933806997009;854.700854700856;854.9569525179271;855.3627547477138;849.4521033933079;850.3579797253066;850.8383407152683;851.4848516647432;854.4744981836158;624.8437890527368;624.9031400132986;625.0187206086446;625.2091104752492;625.262190615074;16342.10118443485;16412.589685257844;16520.256588453427;16580.253888574924;16588.83411165885;15279.388824447009;15286.464973725866;15297.158656273901;15297.541073767694;15305.693886122215;11392.292049005473;11706;11794.941025294904;11988.580399686056;12000.339981301026;3441.6214216436174;3537.7523573349877;3602.0994751312173;3646.4894914711963;3648.7628304160144;83476.58261708653;85738.99999999937;85825;86731.56634216852;86781.13218867793;11969.760604787856;11976.820347694791;11979.640610781691;12124.090693198046;12214.26714397139;22779.202727904445;22803.543929121326;22834;22949;22998.8850055752;8166.938298021338;8308.210719981593;8385.95807020961;8644.351673624504;8849.336299777413;4810.7835147418455;4901.3383193269165;4915.778789954461;4937.1113199624415;5001.149804533238;3518.876839310663;3616.1321282891918;3620.692241159531;3622.7464077514846;3632.164602141522;549.7306319903219;555.7443575955025;571.8598943258928;573.5125143627882;574.3481148896033;712.8788106021989;722.6747963416378;738.7192866710616;755.738986274225;769.6767357709775;9450.669226577174;9786.266030047775;9929.2056635469;10100.242481813893;10102.646407375707;652.8086242607261;653.4380432827764;675.4731309578439;685.5201359048685;761.3072104384983;4587.17430862448;4678.204705200124;4774.665773395898;4782.263817729151;4878.902421951541;106745.93254067516;125003;125924;130277;134695;107632;108466;113654;114514;118344.40827795806;61034.69482652647;80489.59755201187;96158.55762163573;100228.49885750671;103543.4822825881;9077.319201059814;9304.767380815263;9334.626614935327;9443.905560944302;9464.384814987005;46775.766121169174;47286.76356618195;50704;56005.719971399885;57249.69505489052;66312.6684366575;68995;73616.26383736094;75407.24592754002;76792.61603691945;12872;12904.741905161656;12969.740605188032;13317.134386264861;13863.445462181804;15142.848571514143;15193;15246.923765381545;15359.232038398259;15416.229188540752;13516;14056.929715351358;14460.493882714054;14609.707805843611;14921.85078149248;14019.579412617637;14110.223937683115;14181.716365672422;14387;14567.854321456649;13491.190528568317;13688.589342319747;13704.52034178799;13734.862651373758;13888.583342499742;11119.591732890307;11459.48432320531;11569.190156689288;12118;12230;11484.253523520945;11708.414579271173;11975.401229938294;12309.261444313011;12357.821089455272 07:41:20 INFO - PROCESS | 32457 | __end_tp_report 07:41:20 INFO - PROCESS | 32457 | __start_cc_report 07:41:20 INFO - PROCESS | 32457 | _x_x_mozilla_cycle_collect,1074 07:41:20 INFO - PROCESS | 32457 | __end_cc_report 07:41:20 INFO - PROCESS | 32457 | __startTimestamp1474382480339__endTimestamp 07:41:20 INFO - PROCESS | 32457 | 07:41:20 INFO - PROCESS | 32457 | ------- Summary: start ------- 07:41:20 INFO - PROCESS | 32457 | Number of tests: 6 07:41:20 INFO - PROCESS | 32457 | 07:41:20 INFO - PROCESS | 32457 | [#0] dojo.html Cycles:165 Average:5263.36 Median:5416.21 stddev:581.19 (10.7%) stddev-sans-first:555.55 07:41:20 INFO - PROCESS | 32457 | Values: 3014.7 3046.2 3047.1 3076.3 3092.7 5144.7 5215.5 5322.4 5366.5 5396.6 5231.6 5318.4 5370.1 5401.2 5434.8 4788.1 4804.0 4838.3 4890.2 4892.3 4785.7 4876.6 4880.0 4884.1 4910.9 4831.3 4838.1 4873.7 4894.8 4927.3 4751.8 4810.0 4826.9 4868.9 4869.9 4794.2 4797.0 4803.3 4833.1 4895.2 5341.2 5366.4 5387.8 5389.3 5401.2 5508.4 5535.9 5546.2 5582.9 5599.6 5593.1 5624.7 5656.2 5666.3 5715.9 5242.9 5250.2 5251.1 5252.7 5315.8 5631.2 5679.2 5706.3 5715.9 5749.7 5523.3 5531.2 5531.5 5582.8 5600.6 5591.3 5597.0 5601.6 5601.9 5602.8 5472.1 5478.0 5587.7 5591.2 5618.9 5675.3 5709.5 5713.5 5767.6 5773.5 4391.4 4417.9 4420.0 4420.6 4472.7 3826.6 3835.6 3937.7 3955.4 3988.6 5319.3 5378.0 5386.8 5405.7 5474.1 5651.3 5652.4 5661.1 5666.2 5675.2 5257.9 5412.9 5416.2 5421.0 5486.1 4874.0 4921.2 4943.1 4951.0 5023.1 5885.2 5887.3 5890.0 5895.4 5897.6 5615.9 5624.9 5684.1 5686.0 5720.5 5574.8 5640.9 5892.8 5907.7 5920.9 5666.9 5713.0 5715.3 5722.7 5723.1 5749.2 5757.7 5757.8 5759.2 5774.3 5310.3 5563.9 5566.2 5569.9 5573.9 5408.2 5408.2 5413.1 5460.9 5546.4 5383.9 5390.6 5393.7 5394.7 5397.7 5467.0 5504.1 5523.3 5526.0 5550.1 5255.6 5361.1 5387.6 5420.1 5466.4 07:41:20 INFO - PROCESS | 32457 | 07:41:20 INFO - PROCESS | 32457 | [#1] ext.html Cycles:180 Average:59445.60 Median:215180.50 stddev:115152.91 (53.5%) stddev-sans-first:115391.13 07:41:20 INFO - PROCESS | 32457 | Values: 578.2 608.6 614.8 641.8 655.6 32371.7 35760.0 35827.0 35844.0 35879.0 35915.1 44739.0 45733.8 46054.5 50227.0 8993.6 9128.2 9136.3 9139.8 9144.9 9017.8 9079.2 9117.4 9122.0 9124.0 8280.5 8485.5 8512.6 8739.6 10723.6 10993.3 11065.8 11075.8 11076.0 11080.4 10837.6 10911.4 10913.1 10918.2 10922.2 44086.6 44212.8 44380.6 44411.0 44411.0 3312.5 3417.6 3428.6 3472.3 3480.7 33101.5 33252.5 33287.5 33408.2 33953.0 4324.8 4562.1 4701.5 4746.8 4752.2 358379.0 358554.0 358784.0 358841.0 360379.2 58939.7 59141.0 59228.4 59234.4 59277.7 63692.0 63705.0 63779.4 63782.4 63847.4 17758.0 17886.9 17960.3 17965.1 17971.9 13840.2 13903.0 13971.7 13979.7 13990.9 13711.9 17855.1 18232.5 18264.7 18286.9 5546.2 6590.4 6766.9 6802.2 6857.3 887.9 1007.5 1059.8 1070.6 1116.0 31291.2 31833.0 34030.7 34950.0 35505.8 1084.9 1086.6 1105.3 1139.3 1205.1 15751.6 16981.7 16984.2 17006.3 17010.6 527794.0 531138.0 534411.0 535786.0 536231.0 57901.7 58137.4 58140.7 58142.0 58160.7 1226.1 1234.5 1298.4 1380.4 1389.4 29284.0 32841.7 32949.3 33281.0 33899.5 50214.2 50448.2 50997.7 51028.0 51054.5 392195.0 392894.0 393599.0 393862.0 394085.0 25882.5 26081.6 26539.5 28393.6 28748.3 48276.0 48611.5 48897.8 49029.3 49346.0 40682.0 41378.0 41412.4 41630.4 43247.0 41384.0 41715.6 42697.4 42801.6 43100.0 40252.4 41224.2 41547.8 41713.6 41842.2 34246.3 36973.2 37110.8 37452.8 38166.0 27038.7 27509.0 27518.9 27617.4 29628.9 07:41:20 INFO - PROCESS | 32457 | 07:41:20 INFO - PROCESS | 32457 | [#2] jquery.html Cycles:180 Average:27932.94 Median:173510.44 stddev:74966.08 (43.2%) stddev-sans-first:75147.81 07:41:20 INFO - PROCESS | 32457 | Values: 368.4 369.1 376.7 389.2 399.2 14185.0 18295.5 19378.6 19886.2 20040.8 15538.5 19488.4 19787.8 19960.2 19989.3 5692.3 5952.4 5954.0 5955.5 5957.6 4268.5 4383.9 4444.4 5796.0 6552.8 6580.3 6584.8 6585.9 6600.8 6610.5 6390.0 6486.3 6506.2 6508.8 6522.2 5540.0 5714.1 5764.1 5774.4 5775.5 23355.6 23602.5 23721.8 23850.5 23896.3 2530.4 2750.6 2876.3 3046.4 3127.9 13728.5 14480.5 14749.7 14768.5 15177.2 1817.8 1830.5 1860.8 1876.4 1884.5 77814.6 78319.6 79065.0 80728.6 80993.6 9632.3 9635.6 9796.3 10009.5 10416.3 13102.3 13111.6 13114.9 13125.9 13129.5 8075.9 8254.0 8324.0 8375.0 8418.5 8284.4 8404.1 8424.6 8615.7 8630.4 6055.5 6196.5 6208.6 6212.0 6362.5 1674.4 1737.9 1741.9 1746.8 1777.4 930.2 990.0 1007.9 1018.2 1052.9 19375.5 19758.0 20990.7 21144.0 21150.3 955.5 981.7 988.1 1006.0 1007.7 10529.1 11047.9 11099.7 11119.7 11164.7 343893.0 348606.0 506069.0 510470.0 514320.0 95135.5 97172.1 100095.5 100666.0 101392.0 1015.6 1039.9 1059.9 1061.3 1082.9 17575.0 17773.0 18312.5 18458.5 18724.5 40964.8 41886.0 42377.6 43057.4 43881.0 57003.4 58000.4 58988.7 59943.7 60324.4 7441.9 7561.9 7797.5 8180.7 8302.8 9508.0 11401.4 11532.3 12408.6 12566.3 11227.9 11235.2 11478.6 11485.2 11580.6 11029.3 11059.8 11061.2 11081.2 11316.2 5986.9 6966.0 11288.4 11390.8 11697.3 8390.5 8469.5 8599.1 8651.2 8666.5 8972.2 10558.2 10961.3 11088.6 11517.4 07:41:20 INFO - PROCESS | 32457 | 07:41:20 INFO - PROCESS | 32457 | [#3] mootools.html Cycles:180 Average:5886.90 Median:4840.15 stddev:3365.04 (69.5%) stddev-sans-first:3357.14 07:41:20 INFO - PROCESS | 32457 | Values: 1342.3 1476.7 1484.8 1635.7 1734.2 7433.0 8379.2 8680.9 8725.9 8962.3 8783.8 8881.2 8884.2 8886.2 8888.9 2415.3 2455.2 2458.2 2462.7 2463.4 2340.2 2381.6 2386.7 2401.0 2401.3 2381.1 2394.1 2400.7 2425.3 2436.7 2299.5 2316.1 2319.7 2326.0 2339.2 2321.4 2350.8 2357.7 2362.0 2366.1 8935.9 9102.7 9105.9 9115.7 9119.9 5281.5 5449.9 5450.7 5452.8 5531.4 3237.8 3346.0 3376.0 3378.5 3390.8 1100.4 1150.5 2057.4 2120.4 2120.5 12386.8 12556.6 12614.8 12617.9 12628.5 4762.7 4777.8 4804.7 4848.8 4905.4 10678.4 10692.7 10698.2 10705.0 10711.4 5538.9 5544.4 5545.4 5550.1 5550.8 4208.5 4331.3 4831.5 4955.7 5076.6 3917.5 3962.9 4091.9 4116.3 4123.5 1232.2 1239.7 1241.8 1266.3 1286.4 858.0 880.7 893.2 915.3 942.6 5754.0 5777.7 5922.1 6019.1 6274.0 498.3 509.2 510.7 516.4 520.1 4193.2 4206.1 4351.5 4409.6 4419.1 10895.9 11052.6 11100.4 11124.9 11251.2 11006.8 11025.3 11025.4 11027.7 11046.6 7833.6 8161.6 8164.1 8172.3 8406.3 5887.7 6056.7 6114.8 6118.8 6175.3 10432.1 10570.7 10589.7 10634.3 10740.1 10759.8 10995.6 10997.9 11002.6 11007.4 6893.6 7123.0 7517.8 8112.5 8251.6 8070.2 8455.0 8522.5 8523.4 8531.4 7477.8 7500.4 7527.9 7542.5 7732.9 7198.3 7342.0 7421.0 7436.8 7687.9 6840.4 6913.8 6932.6 6963.2 6965.1 4995.7 5000.0 5006.4 5256.6 5420.9 5335.8 5364.2 5381.4 5540.1 5540.3 07:41:20 INFO - PROCESS | 32457 | 07:41:20 INFO - PROCESS | 32457 | [#4] prototype.html Cycles:180 Average:4603.16 Median:5170.47 stddev:1353.29 (26.2%) stddev-sans-first:1318.03 07:41:20 INFO - PROCESS | 32457 | Values: 302.8 321.8 331.4 337.7 338.7 4950.2 5206.3 5270.8 5271.7 5302.3 5247.7 5265.1 5321.9 5324.5 5332.4 4155.5 4166.9 4166.9 4172.5 4178.3 3923.4 4125.9 4181.9 4193.6 4195.9 4116.9 4122.8 4129.1 4142.5 4145.4 4114.6 4118.9 4120.5 4136.5 4142.3 3642.6 3646.9 3647.5 3654.2 3659.1 5068.5 5079.2 5125.1 5130.0 5134.6 4648.6 4691.2 4703.2 4704.5 4710.4 5442.8 5453.7 5463.9 5491.9 5520.3 3650.4 3958.2 3961.4 3963.2 3968.3 5333.2 5558.6 5625.9 5665.3 5672.4 3882.1 3975.7 3978.6 3990.5 4005.8 4049.6 4056.4 4059.6 4060.5 4064.2 4357.8 4494.6 4538.8 4661.4 4670.3 5418.4 5458.9 5462.5 5477.2 5483.0 1390.9 1411.3 1411.9 1425.8 1429.5 858.5 908.3 915.6 923.9 926.4 4740.6 4747.3 4762.0 4764.8 4792.9 4888.0 4939.7 4956.6 4958.5 4971.6 4738.2 4745.4 4745.9 4748.4 4974.4 2960.6 2989.2 2993.5 2999.9 3014.7 5838.9 5854.8 5870.1 5889.7 5977.4 5618.8 5771.2 5824.0 5831.8 5872.1 5878.6 5890.5 5894.9 5911.2 5912.4 5396.9 5651.9 5657.7 5776.5 5783.5 5633.7 5636.3 5637.4 5643.8 5670.0 5610.2 5685.6 5802.1 5891.8 5920.9 4121.2 4132.8 4144.4 4154.6 4158.3 5681.7 5814.0 5817.6 5823.4 5825.0 5534.4 5540.4 5546.8 5573.2 5574.9 5410.5 5490.9 5530.2 5531.9 5600.4 5244.9 5424.8 5435.2 5459.6 5509.2 5555.2 5559.6 5560.4 5561.4 5573.1 5370.1 5419.2 5527.3 5546.9 5549.4 07:41:20 INFO - PROCESS | 32457 | 07:41:20 INFO - PROCESS | 32457 | [#5] yui.html Cycles:180 Average:21913.14 Median:3627.46 stddev:32444.07 (894.4%) stddev-sans-first:32496.05 07:41:20 INFO - PROCESS | 32457 | Values: 718.4 770.0 772.5 802.7 830.6 12797.0 12881.5 15332.6 17340.8 17363.9 11368.2 12041.8 16855.0 16893.2 16918.3 791.1 813.1 815.9 816.3 816.4 663.5 678.3 771.8 813.3 815.0 838.9 854.4 854.7 855.0 855.4 849.5 850.4 850.8 851.5 854.5 624.8 624.9 625.0 625.2 625.3 16342.1 16412.6 16520.3 16580.3 16588.8 15279.4 15286.5 15297.2 15297.5 15305.7 11392.3 11706.0 11794.9 11988.6 12000.3 3441.6 3537.8 3602.1 3646.5 3648.8 83476.6 85739.0 85825.0 86731.6 86781.1 11969.8 11976.8 11979.6 12124.1 12214.3 22779.2 22803.5 22834.0 22949.0 22998.9 8166.9 8308.2 8386.0 8644.4 8849.3 4810.8 4901.3 4915.8 4937.1 5001.1 3518.9 3616.1 3620.7 3622.7 3632.2 549.7 555.7 571.9 573.5 574.3 712.9 722.7 738.7 755.7 769.7 9450.7 9786.3 9929.2 10100.2 10102.6 652.8 653.4 675.5 685.5 761.3 4587.2 4678.2 4774.7 4782.3 4878.9 106745.9 125003.0 125924.0 130277.0 134695.0 107632.0 108466.0 113654.0 114514.0 118344.4 61034.7 80489.6 96158.6 100228.5 103543.5 9077.3 9304.8 9334.6 9443.9 9464.4 46775.8 47286.8 50704.0 56005.7 57249.7 66312.7 68995.0 73616.3 75407.2 76792.6 12872.0 12904.7 12969.7 13317.1 13863.4 15142.8 15193.0 15246.9 15359.2 15416.2 13516.0 14056.9 14460.5 14609.7 14921.9 14019.6 14110.2 14181.7 14387.0 14567.9 13491.2 13688.6 13704.5 13734.9 13888.6 11119.6 11459.5 11569.2 12118.0 12230.0 11484.3 11708.4 11975.4 12309.3 12357.8 07:41:20 INFO - PROCESS | 32457 | -------- Summary: end -------- 07:41:20 INFO - PROCESS | 32457 | 07:41:21 INFO - TEST-INFO | 32457: exit 0 07:41:21 INFO - TEST-OK | dromaeo_css | took 1115690ms 07:41:21 INFO - TEST-START | kraken 07:41:21 INFO - operating with platform_type : linux_ 07:41:21 INFO - Initialising browser for kraken test... 07:41:21 INFO - TEST-INFO | started process 332 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjEzrdb/profile http://localhost:39988/getInfo.html) 07:41:25 INFO - PROCESS | 332 | __metrics Screen width/height:1600/1200 07:41:25 INFO - PROCESS | 332 | colorDepth:24 07:41:25 INFO - PROCESS | 332 | Browser inner width/height: 1024/697 07:41:25 INFO - PROCESS | 332 | __metrics 07:41:26 INFO - PROCESS | 332 | 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" 07:41:26 INFO - PROCESS | 332 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:41:26 INFO - PROCESS | 332 | console.error: PushService: 07:41:26 INFO - PROCESS | 332 | stateChangeProcessEnqueue: Error transitioning state 07:41:26 INFO - PROCESS | 332 | UnknownError 07:41:27 INFO - TEST-INFO | 332: exit 0 07:41:27 INFO - Browser initialized. 07:41:27 INFO - Running cycle 1/1 for kraken test... 07:41:27 INFO - TEST-INFO | started process 417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjEzrdb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:41:39 INFO - PROCESS | 417 | RSS: Main: 174018560 07:41:39 INFO - PROCESS | 417 | 07:41:41 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39988/tests/kraken/driver.html?testName=audio-beat-detection) 07:41:42 INFO - PROCESS | 417 | RSS: Main: 233177088 07:41:42 INFO - PROCESS | 417 | 07:41:45 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39988/tests/kraken/driver.html?testName=audio-dft) 07:41:45 INFO - PROCESS | 417 | RSS: Main: 217616384 07:41:45 INFO - PROCESS | 417 | 07:41:49 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39988/tests/kraken/driver.html?testName=audio-fft) 07:41:49 INFO - PROCESS | 417 | RSS: Main: 299634688 07:41:49 INFO - PROCESS | 417 | 07:41:51 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39988/tests/kraken/driver.html?testName=audio-oscillator) 07:41:52 INFO - PROCESS | 417 | RSS: Main: 209506304 07:41:52 INFO - PROCESS | 417 | 07:41:54 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39988/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:41:55 INFO - PROCESS | 417 | RSS: Main: 213442560 07:41:55 INFO - PROCESS | 417 | 07:41:58 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39988/tests/kraken/driver.html?testName=imaging-darkroom) 07:41:58 INFO - PROCESS | 417 | RSS: Main: 249761792 07:41:58 INFO - PROCESS | 417 | 07:42:02 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39988/tests/kraken/driver.html?testName=imaging-desaturate) 07:42:02 INFO - PROCESS | 417 | RSS: Main: 249393152 07:42:02 INFO - PROCESS | 417 | 07:42:05 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39988/tests/kraken/driver.html?testName=json-parse-financial) 07:42:06 INFO - PROCESS | 417 | RSS: Main: 248320000 07:42:06 INFO - PROCESS | 417 | 07:42:07 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39988/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:42:08 INFO - PROCESS | 417 | RSS: Main: 211042304 07:42:08 INFO - PROCESS | 417 | 07:42:09 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39988/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:42:10 INFO - PROCESS | 417 | RSS: Main: 219168768 07:42:10 INFO - PROCESS | 417 | 07:42:12 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39988/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:42:13 INFO - PROCESS | 417 | RSS: Main: 223133696 07:42:13 INFO - PROCESS | 417 | 07:42:15 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39988/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:42:16 INFO - PROCESS | 417 | RSS: Main: 224354304 07:42:16 INFO - PROCESS | 417 | 07:42:19 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39988/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:42:19 INFO - PROCESS | 417 | RSS: Main: 221872128 07:42:19 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | Cycle 1(1): loaded http://localhost:39988/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39988/tests/kraken/driver.html?testName=ai-astar) 07:42:21 INFO - PROCESS | 417 | RSS: Main: 272609280 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | __start_tp_report 07:42:21 INFO - PROCESS | 417 | _x_x_mozilla_page_load 07:42:21 INFO - PROCESS | 417 | _x_x_mozilla_page_load_details 07:42:21 INFO - PROCESS | 417 | |i|pagename|runs| 07:42:21 INFO - PROCESS | 417 | |0;ai-astar;110;115;112;111;114;112;113;112;112;113 07:42:21 INFO - PROCESS | 417 | |1;audio-beat-detection;155;118;123;166;120;119;118;119;154;122 07:42:21 INFO - PROCESS | 417 | |2;audio-dft;161;163;162;161;162;162;163;163;161;159 07:42:21 INFO - PROCESS | 417 | |3;audio-fft;75;86;74;117;76;75;76;77;105;75 07:42:21 INFO - PROCESS | 417 | |4;audio-oscillator;84;83;130;84;83;83;101;81;80;113 07:42:21 INFO - PROCESS | 417 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 07:42:21 INFO - PROCESS | 417 | |6;imaging-darkroom;146;146;146;145;145;146;145;145;145;145 07:42:21 INFO - PROCESS | 417 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 07:42:21 INFO - PROCESS | 417 | |8;json-parse-financial;54;52;53;51;53;54;53;54;54;53 07:42:21 INFO - PROCESS | 417 | |9;json-stringify-tinderbox;61;63;61;62;62;62;61;61;62;62 07:42:21 INFO - PROCESS | 417 | |10;stanford-crypto-aes;79;81;84;80;80;80;79;81;78;85 07:42:21 INFO - PROCESS | 417 | |11;stanford-crypto-ccm;128;130;129;130;128;124;123;129;174;127 07:42:21 INFO - PROCESS | 417 | |12;stanford-crypto-pbkdf2;168;170;163;164;167;164;168;168;165;167 07:42:21 INFO - PROCESS | 417 | |13;stanford-crypto-sha256-iterative;67;64;66;65;65;67;64;66;68;63 07:42:21 INFO - PROCESS | 417 | __end_tp_report 07:42:21 INFO - PROCESS | 417 | __start_cc_report 07:42:21 INFO - PROCESS | 417 | _x_x_mozilla_cycle_collect,1622 07:42:21 INFO - PROCESS | 417 | __end_cc_report 07:42:21 INFO - PROCESS | 417 | __startTimestamp1474382541806__endTimestamp 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | ------- Summary: start ------- 07:42:21 INFO - PROCESS | 417 | Number of tests: 14 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#0] ai-astar Cycles:10 Average:112.40 Median:112.50 stddev:1.43 (1.3%) stddev-sans-first:1.22 07:42:21 INFO - PROCESS | 417 | Values: 110.0 115.0 112.0 111.0 114.0 112.0 113.0 112.0 112.0 113.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:122.50 stddev:18.92 (15.4%) stddev-sans-first:18.03 07:42:21 INFO - PROCESS | 417 | Values: 155.0 118.0 123.0 166.0 120.0 119.0 118.0 119.0 154.0 122.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.25 (0.8%) stddev-sans-first:1.30 07:42:21 INFO - PROCESS | 417 | Values: 161.0 163.0 162.0 161.0 162.0 162.0 163.0 163.0 161.0 159.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#3] audio-fft Cycles:10 Average:83.60 Median:75.50 stddev:15.10 (20.0%) stddev-sans-first:15.69 07:42:21 INFO - PROCESS | 417 | Values: 75.0 86.0 74.0 117.0 76.0 75.0 76.0 77.0 105.0 75.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:83.00 stddev:17.00 (20.5%) stddev-sans-first:17.77 07:42:21 INFO - PROCESS | 417 | Values: 84.0 83.0 130.0 84.0 83.0 83.0 101.0 81.0 80.0 113.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 07:42:21 INFO - PROCESS | 417 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 07:42:21 INFO - PROCESS | 417 | Values: 146.0 146.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 07:42:21 INFO - PROCESS | 417 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:53.50 stddev:0.99 (1.9%) stddev-sans-first:1.00 07:42:21 INFO - PROCESS | 417 | Values: 54.0 52.0 53.0 51.0 53.0 54.0 53.0 54.0 54.0 53.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 07:42:21 INFO - PROCESS | 417 | Values: 61.0 63.0 61.0 62.0 62.0 62.0 61.0 61.0 62.0 62.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:80.50 stddev:2.21 (2.7%) stddev-sans-first:2.26 07:42:21 INFO - PROCESS | 417 | Values: 79.0 81.0 84.0 80.0 80.0 80.0 79.0 81.0 78.0 85.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#11] stanford-crypto-ccm Cycles:10 Average:132.20 Median:129.00 stddev:14.88 (11.5%) stddev-sans-first:15.70 07:42:21 INFO - PROCESS | 417 | Values: 128.0 130.0 129.0 130.0 128.0 124.0 123.0 129.0 174.0 127.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.40 Median:167.50 stddev:2.27 (1.4%) stddev-sans-first:2.33 07:42:21 INFO - PROCESS | 417 | Values: 168.0 170.0 163.0 164.0 167.0 164.0 168.0 168.0 165.0 167.0 07:42:21 INFO - PROCESS | 417 | 07:42:21 INFO - PROCESS | 417 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:1.58 (2.4%) stddev-sans-first:1.58 07:42:21 INFO - PROCESS | 417 | Values: 67.0 64.0 66.0 65.0 65.0 67.0 64.0 66.0 68.0 63.0 07:42:21 INFO - PROCESS | 417 | -------- Summary: end -------- 07:42:21 INFO - PROCESS | 417 | 07:42:23 INFO - TEST-INFO | 417: exit 0 07:42:23 INFO - TEST-OK | kraken | took 61813ms 07:42:23 INFO - SUITE-END | took 1177s 07:42:23 INFO - Completed test suite (00:19:37) 07:42:23 INFO - javascript benchmark 07:42:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3014.6683864774873, 3046.238440389902, 3047.085874237728, 3076.292452735869, 3092.6907309269063, 5144.6655967362185, 5215.4523775003645, 5322.387925388577, 5366.543843773279, 5396.622236443451, 5231.5814734821215, 5318.361796584415, 5370.113931201347, 5401.216823560581, 5434.809781657643, 4788.138135135674, 4803.951960480385, 4838.274258861164, 4890.168671325883, 4892.290617860408, 4785.688930219542, 4876.634252431082, 4880.023995200956, 4884.145274576953, 4910.901781964359, 4831.275308703687, 4838.104950584118, 4873.707577545341, 4894.755262236892, 4927.310176575283, 4751.786169622375, 4809.95190048102, 4826.927596086061, 4868.926966095476, 4869.853904382874, 4794.161021821203, 4797.0, 4803.279508073782, 4833.081714474274, 4895.167821470359, 5341.1988201769655, 5366.382865970424, 5387.78448862045, 5389.272448219519, 5401.162819762943, 5508.44915508446, 5535.861603459905, 5546.168074788774, 5582.86042848928, 5599.63602365849, 5593.105103183511, 5624.690642014688, 5656.208130861683, 5666.320041594994, 5715.885682286373, 5242.868928276824, 5250.212468129735, 5251.081060814342, 5252.737363131828, 5315.760790764348, 5631.18347839561, 5679.204911312419, 5706.3152421709665, 5715.914261286084, 5749.712514374264, 5523.28197334344, 5531.197976293495, 5531.502164805187, 5582.8325150245555, 5600.635958662674, 5591.27313449249, 5596.972015139898, 5601.551875849922, 5601.887962240814, 5602.775888964477, 5472.069748142825, 5477.972610136924, 5587.664740115606, 5591.191199124313, 5618.943810561924, 5675.262215911905, 5709.51469125129, 5713.542916566665, 5767.567432442584, 5773.451522105393, 4391.385206071152, 4417.911641767211, 4420.049689316812, 4420.58004489573, 4472.709273897186, 3826.559945606291, 3835.5589107252463, 3937.7243592948794, 3955.4462375267485, 3988.581198974124, 5319.255304257407, 5377.973110134424, 5386.811461598825, 5405.729713514309, 5474.069408200616, 5651.265335506358, 5652.434756524314, 5661.065924122556, 5666.15007748841, 5675.233843431084, 5257.894842103136, 5412.891742165135, 5416.214648875891, 5421.0, 5486.067368547357, 4874.025194961022, 4921.237208232729, 4943.060818444484, 4950.950490495121, 5023.09584274834, 5885.176075349369, 5887.293524776968, 5889.970550147222, 5895.4399332063385, 5897.557683173779, 5615.943840561706, 5624.887502250014, 5684.147377893184, 5686.0, 5720.513756330592, 5574.832755017356, 5640.915386269127, 5892.793752218823, 5907.675077870758, 5920.911186332122, 5666.886662266649, 5712.971435142797, 5715.2855893013375, 5722.742476588647, 5723.112917497794, 5749.223854779719, 5757.740901659519, 5757.827265181883, 5759.222504961944, 5774.335951365647, 5310.309659744208, 5563.860903477445, 5566.192902029264, 5569.916451253153, 5573.860653483695, 5408.188771684278, 5408.242846001642, 5413.10683737179, 5460.945390546202, 5546.362168350692, 5383.865403364947, 5390.568754499709, 5393.6763794172475, 5394.676319420847, 5397.676139431646, 5466.988607107698, 5504.119340905436, 5523.337199536081, 5526.049820653568, 5550.056490396723, 5255.63210575256, 5361.088614935392, 5387.622866399314, 5420.105682562491, 5466.426025267289], "value": 5224.946270063279, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.1991941161492, 608.5557542993614, 614.8278482025032, 641.7593402474071, 655.6262930129822, 32371.67628323719, 35759.999999999935, 35827.02951337679, 35844.0, 35879.0, 35915.10212244695, 44739.0, 45733.771331143296, 46054.539454605445, 50227.0, 8993.59528821203, 9128.178463938244, 9136.314776391764, 9139.771505712377, 9144.908550914506, 9017.819643607125, 9079.2282655974, 9117.407368521024, 9122.0, 9124.0, 8280.461769984962, 8485.490870547756, 8512.616932238065, 8739.563021848913, 10723.624673136443, 10993.340399576051, 11065.778684426266, 11075.833862492069, 11076.0, 11080.445977701083, 10837.620683276047, 10911.399873006982, 10913.072388846958, 10918.235723499358, 10922.235443518957, 44086.55913440857, 44212.77893610511, 44380.556194438286, 44411.0, 44411.0, 3312.4865645824934, 3417.6069751978594, 3428.6228514863346, 3472.2881809229048, 3480.669336413062, 33101.503477447855, 33252.50121248183, 33287.50068748971, 33408.16479588005, 33953.0, 4324.848630297956, 4562.087582483517, 4701.48283688794, 4746.786394612251, 4752.239641657318, 358379.0, 358554.0, 358784.0, 358841.0, 360379.19810401043, 58939.705301473216, 59141.0, 59228.40771592315, 59234.40765592289, 59277.703611481666, 63692.0, 63705.0, 63779.362206377344, 63782.36217637857, 63847.36152638507, 17758.0, 17886.910565447088, 17960.281588736565, 17965.101744912965, 17971.910140449214, 13840.238786866617, 13903.0, 13971.720565588632, 13979.650508737363, 13990.86009139916, 13711.862881371058, 17855.107244637715, 18232.544186395182, 18264.726029109574, 18286.908565457088, 5546.195801608825, 6590.429732404366, 6766.8646627068165, 6802.217744959294, 6857.348551887562, 887.8712586674895, 1007.4660429972126, 1059.7986382587283, 1070.5931745936493, 1115.966521004371, 31291.21771955674, 31833.0, 34030.65969340324, 34950.0, 35505.82247088748, 1084.8589683341115, 1086.625114335553, 1105.303658695017, 1139.2594813371209, 1205.1443475132578, 15751.606209844846, 16981.745273820903, 16984.23570939286, 17006.319747209975, 17010.574735631708, 527794.0, 531138.0, 534411.0, 535786.0, 536231.0, 57901.71049144727, 58137.4186258132, 58140.70929645325, 58142.0, 58160.70919645375, 1226.1171956191529, 1234.481517762542, 1298.4481595322138, 1380.3926272440094, 1389.4442223110836, 29284.0, 32841.67158328386, 32949.341013180085, 33281.0, 33899.4915076279, 50214.246786298965, 50448.24327635015, 50997.7450112747, 51028.0, 51054.48945510497, 392195.0, 392894.0, 393599.0, 393862.0, 394085.0, 25882.48235035251, 26081.608775867997, 26539.469210616066, 28393.574096388158, 28748.281292967844, 48276.0, 48611.5138848607, 48897.75551122222, 49029.2645610309, 49346.0, 40682.0, 41378.0, 41412.378814317206, 41630.37554436625, 43247.0, 41384.0, 41715.58284417117, 42697.43815371088, 42801.571984279755, 43100.0, 40252.3962140574, 41224.175516488904, 41547.7922610385, 41713.58286417097, 41842.163156737304, 34246.315073697886, 36973.17051252681, 37110.81444592868, 37452.812735936146, 38166.0, 27038.729612704406, 27509.0, 27518.862405687843, 27617.447651047267, 29628.851855740584], "value": 20358.023809455957, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.37376460017975, 369.0660936691734, 376.6891218559412, 389.188541890159, 399.1796857457925, 14185.0, 18295.451136465912, 19378.612427751435, 19886.204551817948, 20040.799592004074, 15538.476893901592, 19488.41534753956, 19787.802121978777, 19960.20159193634, 19989.300374486895, 5692.345380281279, 5952.404759524056, 5953.987822070237, 5955.464008239258, 5957.553183511232, 4268.487781466213, 4383.890402739925, 4444.444444444444, 5795.971020144893, 6552.803415897531, 6580.309067547911, 6584.835379115512, 6585.934140658604, 6600.768973085943, 6610.537262391635, 6390.0, 6486.286508484063, 6506.186726659168, 6508.8047358579315, 6522.249941256769, 5540.0, 5714.087274026151, 5764.135379693038, 5774.422557744234, 5775.451332123442, 23355.649665255034, 23602.527949440915, 23721.7627823723, 23850.522989540113, 23896.283111506684, 2530.392705750625, 2750.559910414344, 2876.3240638449947, 3046.375493023947, 3127.874885004597, 13728.45086196561, 14480.493182738659, 14749.705005899821, 14768.483103091448, 15177.24113794306, 1817.8363947244811, 1830.469163942455, 1860.7581014468167, 1876.4317979655543, 1884.4723477426342, 77814.61092694501, 78319.60840195819, 79065.0, 80728.59635701784, 80993.59503202506, 9632.325737198329, 9635.614575416905, 9796.28565001122, 10009.499525023719, 10416.270861039655, 13102.279374634296, 13111.60665180046, 13114.934425327812, 13125.934370328278, 13129.540466083641, 8075.919240807634, 8254.0, 8324.042735085422, 8374.958125209336, 8418.536980466133, 8284.378671599654, 8404.07555168931, 8424.578771061422, 8615.741527754177, 8630.352723545759, 6055.485283750931, 6196.504279657616, 6208.627482351073, 6212.0371342441995, 6362.4688297994135, 1674.4055860169465, 1737.8522825559717, 1741.9216135273944, 1746.7729195204543, 1777.4489908128521, 930.1582068228264, 990.0, 1007.8840933292619, 1018.1600179851652, 1052.9157667386592, 19375.515612109528, 19758.0, 20990.685139722915, 21144.0, 21150.259740908994, 955.5126885288553, 981.7005813227034, 988.1008282462924, 1005.959761609542, 1007.7027276953316, 10529.10502607287, 11047.944760276147, 11099.722506937229, 11119.722006949729, 11164.665060048212, 343893.0, 348606.0, 506069.0, 510470.0, 514320.0, 95135.52432237794, 97172.08483745498, 100095.49952250338, 100666.0, 101392.0, 1015.5886865819355, 1039.870016247969, 1059.8569193158826, 1061.2571200159919, 1082.9187810914054, 17575.0, 17773.022483763518, 18312.542186445444, 18458.538536536693, 18724.531886702942, 40964.79517602393, 41886.0, 42377.57622423736, 43057.354139688556, 43881.0, 57003.42996570147, 58000.4199957995, 58988.70505647444, 59943.70028150006, 60324.39675603188, 7441.888371674321, 7561.865720141803, 7797.493162944391, 8180.672773089248, 8302.792430189294, 9507.952460237655, 11401.372924489233, 11532.250403724067, 12408.62774116778, 12566.308853013172, 11227.887721122685, 11235.15736319763, 11478.58949620589, 11485.196036277715, 11580.594679186188, 11029.283096598696, 11059.778804424028, 11061.22571420025, 11081.224314297922, 11316.15128865322, 5986.9401305986385, 6965.965170174117, 11288.379139147426, 11390.772184556097, 11697.2981621103, 8390.454620449651, 8469.491830490191, 8599.097094804956, 8651.226498567905, 8666.480011199348, 8972.19250267479, 10558.155347572023, 10961.342319460859, 11088.5564577416, 11517.366544840113], "value": 9265.290870042043, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.261756034181, 1476.7194233095715, 1484.8292446368666, 1635.6892190483807, 1734.2369357482708, 7432.959385686008, 8379.162083791618, 8680.869786953193, 8725.86911196331, 8962.327825413104, 8783.824323513525, 8881.245094166987, 8884.156005179513, 8886.20024197822, 8888.911110888888, 2415.2995631266954, 2455.2020593307157, 2458.21337172105, 2462.704475462947, 2463.3718401807514, 2340.20433052762, 2381.595128828095, 2386.6658667786523, 2400.95198096038, 2401.2796161151587, 2381.1189859751917, 2394.066314137479, 2400.699912510936, 2425.333033415807, 2436.671049408325, 2299.5171014086895, 2316.096722278313, 2319.7100362454694, 2326.0230703104735, 2339.1929784224417, 2321.419645088728, 2350.8354415190943, 2357.7406485286606, 2361.9527609447887, 2366.0890557135453, 8935.910640893573, 9102.68140615082, 9105.863412048824, 9115.680951166678, 9119.863202051974, 5281.498257665516, 5449.945500544984, 5450.700211488366, 5452.754626041838, 5531.3915469298345, 3237.7733558650903, 3346.0463767826054, 3375.9662403375896, 3378.5438965739554, 3390.762646614738, 1100.4222783038888, 1150.5052827284264, 2057.3827851644446, 2120.448683342342, 2120.5228823514585, 12386.75226495474, 12556.623301300975, 12614.81077783834, 12617.936910315482, 12628.494860205581, 4762.690425122356, 4777.791016323179, 4804.73573953429, 4848.781804818792, 4905.411350637946, 10678.412687302272, 10692.732681682864, 10698.197635177392, 10705.0, 10711.357318560911, 5538.861528461821, 5544.390117087117, 5545.390007099216, 5550.056490396642, 5550.777968881281, 4208.473940757405, 4331.306990881444, 4831.51684831514, 4955.653104282737, 5076.6446348755235, 3917.5103112110987, 3962.920741585152, 4091.8772436826944, 4116.341385378325, 4123.463949686581, 1232.1990706040965, 1239.7334573066835, 1241.76406482768, 1266.259238345578, 1286.414681319997, 857.9957100214459, 880.7269746378643, 893.1738142218422, 915.2586405011829, 942.5767091691458, 5754.0, 5777.711114444261, 5922.082077278029, 6019.127226552126, 6274.0, 498.2924247568462, 509.19547115557333, 510.6578592343139, 516.4293455731438, 520.0638850069859, 4193.22425351311, 4206.116715489781, 4351.5430879757805, 4409.559044095565, 4419.1382680377665, 10895.891041089488, 11052.557897684163, 11100.389478578594, 11124.888751112547, 11251.156163287786, 11006.779864402828, 11025.283356581796, 11025.393603351733, 11027.724306892393, 11046.558137674403, 7833.608319583998, 8161.5919204039565, 8164.142765009708, 8172.305354044973, 8406.327493800603, 5887.67617781009, 6056.727447264796, 6114.847128821815, 6118.785842495491, 6175.320714721467, 10432.113270372103, 10570.682879513626, 10589.735256618646, 10634.30876992993, 10740.087092597218, 10759.838602420814, 10995.615153469591, 10997.945010274898, 11002.559897604006, 11007.394593297446, 6893.551919125241, 7123.0, 7517.774466766005, 8112.472689275178, 8251.628676709683, 8070.192980701883, 8455.0, 8522.531260780717, 8523.445976011539, 8531.36014798905, 7477.813054673459, 7500.36246919018, 7527.892190186756, 7542.547447153188, 7732.8840067397905, 7198.280171982759, 7341.963290183515, 7420.962895185489, 7436.814079648052, 7687.923120768721, 6840.3501667341525, 6913.827154321182, 6932.61870597122, 6963.2340442552295, 6965.094537710065, 4995.700257984533, 5000.049990501793, 5006.397952655261, 5256.553192978639, 5420.945790542202, 5335.786568537215, 5364.168553874156, 5381.354237491526, 5540.141278101901, 5540.31294822019], "value": 4666.414982013694, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.7714075872716, 321.8471226167569, 331.39189587107654, 337.6994474917323, 338.7408632396216, 4950.2327139293375, 5206.323177986861, 5270.814066834965, 5271.710055946922, 5302.337207849019, 5247.685138891669, 5265.078611243036, 5321.909008653217, 5324.509137441513, 5332.413434522199, 4155.501339839224, 4166.874943765174, 4166.916541729135, 4172.499300083994, 4178.289690752564, 3923.39187425948, 4125.9174816503655, 4181.895452613679, 4193.601607847249, 4195.916081678364, 4116.897077573055, 4122.835086596532, 4129.050318426764, 4142.482189726285, 4145.39891715701, 4114.629683328515, 4118.91762164758, 4120.505539335284, 4136.462259906193, 4142.337226043834, 3642.581103173116, 3646.890593282206, 3647.5075864758182, 3654.177809992757, 3659.1035196376793, 5068.493150684939, 5079.187330027178, 5125.1287281162295, 5129.974005198975, 5134.614903882224, 4648.628109751212, 4691.225947718623, 4703.247480403119, 4704.482506924235, 4710.387649605529, 5442.809501667462, 5453.700046497441, 5463.890722185534, 5491.862703432406, 5520.309961254843, 3650.408210170782, 3958.2083583283456, 3961.4057891316247, 3963.207358528306, 3968.3055465293746, 5333.173358129496, 5558.555315574744, 5625.943740562583, 5665.263515742927, 5672.376038635747, 3882.1265215326325, 3975.7018223633345, 3978.622030907059, 3990.521137463465, 4005.776793879367, 4049.635532802062, 4056.3712624542654, 4059.6143366380147, 4060.451839001697, 4064.227796718614, 4357.803898824561, 4494.595486406239, 4538.773061346919, 4661.417322834645, 4670.276107203321, 5418.431064738145, 5458.863528411742, 5462.481064298965, 5477.178423236546, 5482.972585137049, 1390.8609139086213, 1411.2661416063795, 1411.9011669183265, 1425.7647488164337, 1429.4568064135563, 858.4677499949946, 908.2643059122132, 915.6200176926634, 923.8891333040078, 926.3561824531886, 4740.620750339965, 4747.287906813936, 4762.0, 4764.785584648699, 4792.880177995578, 4887.95112048882, 4939.678920870132, 4956.553910148032, 4958.504149585085, 4971.6271279654175, 4738.218193997952, 4745.359376484131, 4745.857624271277, 4748.361815173828, 4974.403071631427, 2960.585518027478, 2989.2228020714338, 2993.4555627716704, 2999.880004799827, 3014.728674419313, 5838.9124163137585, 5854.765809367663, 5870.148828419855, 5889.734961926638, 5977.372375900555, 5618.820047789928, 5771.220885180531, 5824.0, 5831.7667293307795, 5872.11918212263, 5878.559108066912, 5890.528757699374, 5894.941050589525, 5911.201987731773, 5912.438318359749, 5396.92218495874, 5651.943480565306, 5657.660540367591, 5776.451237132634, 5783.450572195635, 5633.732435187663, 5636.323641163087, 5637.379888212376, 5643.7742490299925, 5669.971650141723, 5610.204734484925, 5685.630434021776, 5802.129680547782, 5891.823245302648, 5920.911186332295, 4121.196366708463, 4132.8140233688955, 4144.3990621359135, 4154.584541545822, 4158.293090174738, 5681.659100453986, 5813.970930145491, 5817.621854579439, 5823.388544202967, 5825.038868586806, 5534.363548192009, 5540.418256083214, 5546.7781288748, 5573.219749235192, 5574.91637625444, 5410.48600382963, 5490.940897163564, 5530.198121272473, 5531.944680553143, 5600.439956004367, 5244.868878278074, 5424.755885985219, 5435.184722291688, 5459.590530710292, 5509.173623956439, 5555.194496798023, 5559.555235581225, 5560.416156303691, 5561.388247292875, 5573.108302671553, 5370.140777475585, 5419.2413062170535, 5527.309086364205, 5546.861328466821, 5549.38956714753], "value": 4171.168440499497, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.364247640838, 769.9720872635033, 772.5094564951256, 802.7110240313483, 830.6137645994611, 12797.0, 12881.484740610387, 15332.61668458289, 17340.82659173408, 17363.913180434098, 11368.204225704183, 12041.75916481672, 16855.0, 16893.1553422329, 16918.323267069303, 791.0507391130652, 813.0812182234067, 815.8653822119372, 816.302061737215, 816.4203415574949, 663.4592806862415, 678.2640834694363, 771.8456308738247, 813.3005615170948, 814.9918500815005, 838.8573942429771, 854.3933806997009, 854.700854700856, 854.9569525179271, 855.3627547477138, 849.4521033933079, 850.3579797253066, 850.8383407152683, 851.4848516647432, 854.4744981836158, 624.8437890527368, 624.9031400132986, 625.0187206086446, 625.2091104752492, 625.262190615074, 16342.10118443485, 16412.589685257844, 16520.256588453427, 16580.253888574924, 16588.83411165885, 15279.388824447009, 15286.464973725866, 15297.158656273901, 15297.541073767694, 15305.693886122215, 11392.292049005473, 11706.0, 11794.941025294904, 11988.580399686056, 12000.339981301026, 3441.6214216436174, 3537.7523573349877, 3602.0994751312173, 3646.4894914711963, 3648.7628304160144, 83476.58261708653, 85738.99999999937, 85825.0, 86731.56634216852, 86781.13218867793, 11969.760604787856, 11976.820347694791, 11979.640610781691, 12124.090693198046, 12214.26714397139, 22779.202727904445, 22803.543929121326, 22834.0, 22949.0, 22998.8850055752, 8166.938298021338, 8308.210719981593, 8385.95807020961, 8644.351673624504, 8849.336299777413, 4810.7835147418455, 4901.3383193269165, 4915.778789954461, 4937.1113199624415, 5001.149804533238, 3518.876839310663, 3616.1321282891918, 3620.692241159531, 3622.7464077514846, 3632.164602141522, 549.7306319903219, 555.7443575955025, 571.8598943258928, 573.5125143627882, 574.3481148896033, 712.8788106021989, 722.6747963416378, 738.7192866710616, 755.738986274225, 769.6767357709775, 9450.669226577174, 9786.266030047775, 9929.2056635469, 10100.242481813893, 10102.646407375707, 652.8086242607261, 653.4380432827764, 675.4731309578439, 685.5201359048685, 761.3072104384983, 4587.17430862448, 4678.204705200124, 4774.665773395898, 4782.263817729151, 4878.902421951541, 106745.93254067516, 125003.0, 125924.0, 130277.0, 134695.0, 107632.0, 108466.0, 113654.0, 114514.0, 118344.40827795806, 61034.69482652647, 80489.59755201187, 96158.55762163573, 100228.49885750671, 103543.4822825881, 9077.319201059814, 9304.767380815263, 9334.626614935327, 9443.905560944302, 9464.384814987005, 46775.766121169174, 47286.76356618195, 50704.0, 56005.719971399885, 57249.69505489052, 66312.6684366575, 68995.0, 73616.26383736094, 75407.24592754002, 76792.61603691945, 12872.0, 12904.741905161656, 12969.740605188032, 13317.134386264861, 13863.445462181804, 15142.848571514143, 15193.0, 15246.923765381545, 15359.232038398259, 15416.229188540752, 13516.0, 14056.929715351358, 14460.493882714054, 14609.707805843611, 14921.85078149248, 14019.579412617637, 14110.223937683115, 14181.716365672422, 14387.0, 14567.854321456649, 13491.190528568317, 13688.589342319747, 13704.52034178799, 13734.862651373758, 13888.583342499742, 11119.591732890307, 11459.48432320531, 11569.190156689288, 12118.0, 12230.0, 11484.253523520945, 11708.414579271173, 11975.401229938294, 12309.261444313011, 12357.821089455272], "value": 7702.587627481732, "unit": "score"}], "name": "dromaeo_css", "value": 7271.100116638946, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 115.0, 112.0, 111.0, 114.0, 112.0, 113.0, 112.0, 112.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 118.0, 123.0, 166.0, 120.0, 119.0, 118.0, 119.0, 154.0, 122.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 162.0, 161.0, 162.0, 162.0, 163.0, 163.0, 161.0, 159.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 86.0, 74.0, 117.0, 76.0, 75.0, 76.0, 77.0, 105.0, 75.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 130.0, 84.0, 83.0, 83.0, 101.0, 81.0, 80.0, 113.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0], "value": 145.4, "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": [54.0, 52.0, 53.0, 51.0, 53.0, 54.0, 53.0, 54.0, 54.0, 53.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 61.0, 62.0, 62.0, 62.0, 61.0, 61.0, 62.0, 62.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 84.0, 80.0, 80.0, 80.0, 79.0, 81.0, 78.0, 85.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 130.0, 129.0, 130.0, 128.0, 124.0, 123.0, 129.0, 174.0, 127.0], "value": 132.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 163.0, 164.0, 167.0, 164.0, 168.0, 168.0, 165.0, 167.0], "value": 166.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 66.0, 65.0, 65.0, 67.0, 64.0, 66.0, 68.0, 63.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1498.4000000000003, "alertThreshold": 2.0}]} 07:42:23 INFO - Return code: 0 07:42:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:42:23 INFO - # TBPL SUCCESS # 07:42:23 INFO - Running post-action listener: _resource_record_post_action 07:42:23 INFO - [mozharness: 2016-09-20 14:42:23.599669Z] Finished run-tests step (success) 07:42:23 INFO - Running post-run listener: _resource_record_post_run 07:42:23 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 2408448; Write bytes: 403914752; Read time: 484; Write time: 394708 07:42:23 INFO - TinderboxPrint: CPU usage
13.1% 07:42:23 INFO - TinderboxPrint: I/O read bytes / time
2,408,448 / 484 07:42:23 INFO - TinderboxPrint: I/O write bytes / time
403,914,752 / 394,708 07:42:23 INFO - TinderboxPrint: CPU idle
8,283.9 (86.9%) 07:42:23 INFO - TinderboxPrint: CPU user
1,198.5 (12.6%) 07:42:23 INFO - TinderboxPrint: Swap in / out
0 / 0 07:42:23 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106381312; Read time: 0; Write time: 127248 07:42:23 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 2408448; Write bytes: 297037824; Read time: 484; Write time: 267456 07:42:24 INFO - Running post-run listener: _upload_blobber_files 07:42:24 INFO - Blob upload gear active. 07:42:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:42:24 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 07:42:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:42:24 INFO - (blobuploader) - INFO - Open directory for files ... 07:42:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:42:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:42:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:25 INFO - (blobuploader) - INFO - Done attempting. 07:42:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:42:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:42:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:29 INFO - (blobuploader) - INFO - Done attempting. 07:42:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:42:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:42:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:30 INFO - (blobuploader) - INFO - Done attempting. 07:42:30 INFO - (blobuploader) - INFO - Iteration through files over. 07:42:30 INFO - Return code: 0 07:42:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:42:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:42:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45a8851354787181dcc30c1b99a70c5a843d0ea74416a325898df8142e1c8e2d1d0bfca288e7d3b70f51dae0e32f169e569090ad364462d0bda2079bd81b8778", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8c062f45af2af674427e8b999064b8748df4a62dbd08b0d3dba356893a4c83e1c7bcb5d4ba0611a8a54c194940d1e75b390e6bb8b56af91fef288891fbd6815", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e38551ef93434115c3e04810b67e7dee1e78036d3e1f6323df723f5a7171edf09946a9310f75f0b6861f6420c650f63140ec4c4d3b6c33be445764a4d6ecdf22"} 07:42:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:42:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:42:30 INFO - Contents: 07:42:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45a8851354787181dcc30c1b99a70c5a843d0ea74416a325898df8142e1c8e2d1d0bfca288e7d3b70f51dae0e32f169e569090ad364462d0bda2079bd81b8778", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8c062f45af2af674427e8b999064b8748df4a62dbd08b0d3dba356893a4c83e1c7bcb5d4ba0611a8a54c194940d1e75b390e6bb8b56af91fef288891fbd6815", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e38551ef93434115c3e04810b67e7dee1e78036d3e1f6323df723f5a7171edf09946a9310f75f0b6861f6420c650f63140ec4c4d3b6c33be445764a4d6ecdf22"} 07:42:30 INFO - Running post-run listener: copy_logs_to_upload_dir 07:42:30 INFO - Copying logs to upload dir... 07:42:30 INFO - mkdir: /builds/slave/test/build/upload/logs 07:42:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.453490 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-09-20 07:42:30.101472) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 07:42:30.105155) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45a8851354787181dcc30c1b99a70c5a843d0ea74416a325898df8142e1c8e2d1d0bfca288e7d3b70f51dae0e32f169e569090ad364462d0bda2079bd81b8778", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8c062f45af2af674427e8b999064b8748df4a62dbd08b0d3dba356893a4c83e1c7bcb5d4ba0611a8a54c194940d1e75b390e6bb8b56af91fef288891fbd6815", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e38551ef93434115c3e04810b67e7dee1e78036d3e1f6323df723f5a7171edf09946a9310f75f0b6861f6420c650f63140ec4c4d3b6c33be445764a4d6ecdf22"} build_url:https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011328 build_url: 'https://queue.taskcluster.net/v1/task/GngbyshqSlOYBc5bYsZ9sA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45a8851354787181dcc30c1b99a70c5a843d0ea74416a325898df8142e1c8e2d1d0bfca288e7d3b70f51dae0e32f169e569090ad364462d0bda2079bd81b8778", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8c062f45af2af674427e8b999064b8748df4a62dbd08b0d3dba356893a4c83e1c7bcb5d4ba0611a8a54c194940d1e75b390e6bb8b56af91fef288891fbd6815", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e38551ef93434115c3e04810b67e7dee1e78036d3e1f6323df723f5a7171edf09946a9310f75f0b6861f6420c650f63140ec4c4d3b6c33be445764a4d6ecdf22"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 07:42:30.133173) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 07:42:30.134017) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1474381310.248311-801423099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 07:42:30.212469) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 07:42:30.212909) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 07:42:30.213389) ========= ========= Total master_lag: 0.17 =========