builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1474165149.39 results: success (0) buildid: 20160917163004 builduid: 82db576dbede4acda10282dbf203727c revision: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:09.390511) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:09.391304) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:09.391651) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:09.497567) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:09.497907) ========= 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=cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 19:19:10-- 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% 111M=0s 2016-09-17 19:19:10 (111 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123754 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:09.650604) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:09.650975) ========= 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=cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019461 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:09.710095) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:09.710458) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 --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 eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 --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=cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 19:19:10,512 truncating revision to first 12 chars 2016-09-17 19:19:10,512 Setting DEBUG logging. 2016-09-17 19:19:10,512 attempt 1/10 2016-09-17 19:19:10,512 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/eaf5eb6f8fa0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 19:19:11,051 unpacking tar archive at: mozilla-central-eaf5eb6f8fa0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.906889 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:10.633519) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:10.633996) ========= script_repo_revision: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:10.634730) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:10.635073) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 19:19:10.703950) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-17 19:19:10.704278) ========= /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=cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:19:11 INFO - MultiFileLogger online at 20160917 19:19:11 in /builds/slave/test 19:19:11 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 19:19:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:19:11 INFO - {'append_to_log': False, 19:19:11 INFO - 'base_work_dir': '/builds/slave/test', 19:19:11 INFO - 'blob_upload_branch': 'Firefox', 19:19:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:19:11 INFO - 'branch': 'Firefox', 19:19:11 INFO - 'buildbot_json_path': 'buildprops.json', 19:19:11 INFO - 'config_files': ('talos/linux_config.py',), 19:19:11 INFO - 'default_actions': ('clobber', 19:19:11 INFO - 'read-buildbot-config', 19:19:11 INFO - 'download-and-extract', 19:19:11 INFO - 'populate-webroot', 19:19:11 INFO - 'create-virtualenv', 19:19:11 INFO - 'install', 19:19:11 INFO - 'run-tests'), 19:19:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:19:11 INFO - 'download_minidump_stackwalk': True, 19:19:11 INFO - 'download_symbols': 'ondemand', 19:19:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:19:11 INFO - 'tooltool.py': '/tools/tooltool.py', 19:19:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:19:11 INFO - '/tools/misc-python/virtualenv.py')}, 19:19:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:19:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:19:11 INFO - 'installer_path': 'installer.exe', 19:19:11 INFO - 'log_level': 'info', 19:19:11 INFO - 'log_name': 'talos', 19:19:11 INFO - 'log_to_console': True, 19:19:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:19:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:19:11 INFO - 'opt_config_files': (), 19:19:11 INFO - 'pip_index': False, 19:19:11 INFO - 'sps_profile': False, 19:19:11 INFO - 'sps_profile_interval': 0, 19:19:11 INFO - 'suite': 'dromaeojs', 19:19:11 INFO - 'system_bits': '32', 19:19:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:19:11 INFO - 'title': 'talos-linux64-ix-054', 19:19:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:19:11 INFO - 'use_talos_json': True, 19:19:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:19:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:19:11 INFO - 'work_dir': 'build'} 19:19:11 INFO - [mozharness: 2016-09-18 02:19:11.607068Z] Running clobber step. 19:19:11 INFO - Running pre-action listener: _resource_record_pre_action 19:19:11 INFO - Running main action method: clobber 19:19:11 INFO - rmtree: /builds/slave/test/build 19:19:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:19:12 INFO - Running post-action listener: _resource_record_post_action 19:19:12 INFO - [mozharness: 2016-09-18 02:19:12.074673Z] Finished clobber step (success) 19:19:12 INFO - [mozharness: 2016-09-18 02:19:12.074776Z] Running read-buildbot-config step. 19:19:12 INFO - Running pre-action listener: _resource_record_pre_action 19:19:12 INFO - Running main action method: read_buildbot_config 19:19:12 INFO - Using buildbot properties: 19:19:12 INFO - { 19:19:12 INFO - "project": "", 19:19:12 INFO - "product": "firefox", 19:19:12 INFO - "script_repo_revision": "production", 19:19:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:19:12 INFO - "repository": "", 19:19:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:19:12 INFO - "buildid": "20160917163004", 19:19:12 INFO - "pgo_build": "True", 19:19:12 INFO - "basedir": "/builds/slave/test", 19:19:12 INFO - "buildnumber": 880, 19:19:12 INFO - "slavename": "talos-linux64-ix-054", 19:19:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:19:12 INFO - "platform": "ubuntu64_hw", 19:19:12 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:19:12 INFO - "branch": "mozilla-central", 19:19:12 INFO - "repo_path": "mozilla-central", 19:19:12 INFO - "revision": "eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7", 19:19:12 INFO - "stage_platform": "linux64-pgo", 19:19:12 INFO - "builduid": "82db576dbede4acda10282dbf203727c", 19:19:12 INFO - "slavebuilddir": "test" 19:19:12 INFO - } 19:19:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 19:19:12 INFO - Running post-action listener: _resource_record_post_action 19:19:12 INFO - [mozharness: 2016-09-18 02:19:12.077325Z] Finished read-buildbot-config step (success) 19:19:12 INFO - [mozharness: 2016-09-18 02:19:12.077416Z] Running download-and-extract step. 19:19:12 INFO - Running pre-action listener: _resource_record_pre_action 19:19:12 INFO - Running main action method: download_and_extract 19:19:12 INFO - mkdir: /builds/slave/test/build/tests 19:19:12 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')]} 19:19:12 INFO - trying https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:19:12 INFO - Downloading https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:19:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:19:12 INFO - Downloaded 1361 bytes. 19:19:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:19:12 INFO - Using the following test package requirements: 19:19:12 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:19:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:19:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:19:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:19:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:19:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:19:12 INFO - u'jsshell-linux-x86_64.zip'], 19:19:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:19:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:19:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:19:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:19:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:19:12 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:19:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:19:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:19:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:19:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:19:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:19:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:19:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:19:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:19:12 INFO - Fetch https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:19:13 INFO - Expected file size: 21936433 19:19:18 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:19:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:19:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:19:18 INFO - Fetch https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:19:18 INFO - Expected file size: 21936433 19:19:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 19:19:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:19:23 INFO - Fetch https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 19:19:24 INFO - Expected file size: 14414048 19:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:25 INFO - trying https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:19:25 INFO - Downloading https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:19:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:19:27 INFO - Downloaded 59567506 bytes. 19:19:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:19:27 INFO - mkdir: /builds/slave/test/properties 19:19:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:19:27 INFO - Writing to file /builds/slave/test/properties/build_url 19:19:27 INFO - Contents: 19:19:27 INFO - build_url:https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:19:28 INFO - Running post-action listener: _resource_record_post_action 19:19:28 INFO - Running post-action listener: set_extra_try_arguments 19:19:28 INFO - [mozharness: 2016-09-18 02:19:28.603555Z] Finished download-and-extract step (success) 19:19:28 INFO - [mozharness: 2016-09-18 02:19:28.603786Z] Running populate-webroot step. 19:19:28 INFO - Running pre-action listener: _resource_record_pre_action 19:19:28 INFO - Running main action method: populate_webroot 19:19:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:19:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:19:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:19:28 INFO - '--tppagecycles', 19:19:28 INFO - '7'], 19:19:28 INFO - 'tests': ['tsvgm']}}, 19:19:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:19:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:19:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:19:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:19:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:19:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:19:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:28 INFO - 'talos_options': ['--disable-e10s'], 19:19:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:19:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:19:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:28 INFO - 'talos_options': ['--disable-e10s'], 19:19:28 INFO - 'tests': ['damp', 'tps']}, 19:19:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:28 INFO - 'tests': ['damp', 'tps']}, 19:19:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:19:28 INFO - 'tests': ['dromaeo_dom']}, 19:19:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:19:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:19:28 INFO - 'tests': ['basic_compositor_video']}, 19:19:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:19:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:19:28 INFO - 'tests': ['a11yr', 19:19:28 INFO - 'ts_paint', 19:19:28 INFO - 'tpaint', 19:19:28 INFO - 'sessionrestore', 19:19:28 INFO - 'sessionrestore_no_auto_restore', 19:19:28 INFO - 'tabpaint']}, 19:19:28 INFO - 'other-e10s': {'tests': ['a11yr', 19:19:28 INFO - 'ts_paint', 19:19:28 INFO - 'tpaint', 19:19:28 INFO - 'sessionrestore', 19:19:28 INFO - 'sessionrestore_no_auto_restore', 19:19:28 INFO - 'tabpaint']}, 19:19:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:19:28 INFO - 'ts_paint', 19:19:28 INFO - 'tpaint', 19:19:28 INFO - 'sessionrestore', 19:19:28 INFO - 'sessionrestore_no_auto_restore', 19:19:28 INFO - 'tabpaint']}, 19:19:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:19:28 INFO - 'ts_paint', 19:19:28 INFO - 'tpaint', 19:19:28 INFO - 'sessionrestore', 19:19:28 INFO - 'sessionrestore_no_auto_restore', 19:19:28 INFO - 'tabpaint']}, 19:19:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:19:28 INFO - 'tests': ['a11yr', 19:19:28 INFO - 'ts_paint', 19:19:28 INFO - 'tpaint', 19:19:28 INFO - 'sessionrestore', 19:19:28 INFO - 'sessionrestore_no_auto_restore', 19:19:28 INFO - 'tabpaint']}, 19:19:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:19:28 INFO - 'tests': ['a11yr', 19:19:28 INFO - 'ts_paint', 19:19:28 INFO - 'tpaint', 19:19:28 INFO - 'sessionrestore', 19:19:28 INFO - 'sessionrestore_no_auto_restore', 19:19:28 INFO - 'tabpaint']}, 19:19:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:19:28 INFO - 'tests': ['tsvgx', 19:19:28 INFO - 'tsvgr_opacity', 19:19:28 INFO - 'tart', 19:19:28 INFO - 'tscrollx', 19:19:28 INFO - 'cart']}, 19:19:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:19:28 INFO - 'tsvgr_opacity', 19:19:28 INFO - 'tart', 19:19:28 INFO - 'tscrollx', 19:19:28 INFO - 'cart']}, 19:19:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:28 INFO - 'talos_options': ['--disable-e10s'], 19:19:28 INFO - 'tests': ['tp5o']}, 19:19:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:28 INFO - 'tests': ['tp5o']}, 19:19:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:28 INFO - 'talos_options': ['--disable-e10s', 19:19:28 INFO - '--xperf_path', 19:19:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:19:28 INFO - 'tests': ['tp5n']}, 19:19:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:19:28 INFO - 'talos_options': ['--xperf_path', 19:19:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:19:28 INFO - 'tests': ['tp5n']}}, 19:19:28 INFO - 'talos.zip': {'path': '', 19:19:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:19:28 INFO - Running post-action listener: _resource_record_post_action 19:19:28 INFO - [mozharness: 2016-09-18 02:19:28.620612Z] Finished populate-webroot step (success) 19:19:28 INFO - [mozharness: 2016-09-18 02:19:28.620702Z] Running create-virtualenv step. 19:19:28 INFO - Running pre-action listener: _resource_record_pre_action 19:19:28 INFO - Running main action method: create_virtualenv 19:19:28 INFO - Creating virtualenv /builds/slave/test/build/venv 19:19:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:19:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:19:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:19:28 INFO - Using real prefix '/usr' 19:19:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:19:29 INFO - Installing distribute.............................................................................................................................................................................................done. 19:19:31 INFO - Installing pip.................done. 19:19:31 INFO - Return code: 0 19:19:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:19:31 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')]} 19:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:31 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')]} 19:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7fc0d1fa8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1571790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156e930>, '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 0x156f830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x156fcc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', '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 19:19:31 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 19:19:31 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 19:19:31 INFO - Using env: {'DISPLAY': ':0', 19:19:31 INFO - 'HOME': '/home/cltbld', 19:19:31 INFO - 'LANG': 'en_US.UTF-8', 19:19:31 INFO - 'LANGUAGE': 'en_US:en', 19:19:31 INFO - 'LOGNAME': 'cltbld', 19:19:31 INFO - 'MAIL': '/var/mail/cltbld', 19:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:31 INFO - 'MOZ_NO_REMOTE': '1', 19:19:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:31 INFO - 'NO_EM_RESTART': '1', 19:19:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:31 INFO - 'PWD': '/builds/slave/test', 19:19:31 INFO - 'SHELL': '/bin/bash', 19:19:31 INFO - 'SHLVL': '1', 19:19:31 INFO - 'TERM': 'linux', 19:19:31 INFO - 'TMOUT': '86400', 19:19:31 INFO - 'USER': 'cltbld', 19:19:31 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', 19:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:31 INFO - '_': '/tools/buildbot/bin/python'} 19:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:19:31 INFO - Cleaning up... 19:19:31 INFO - Return code: 0 19:19:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:19:31 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')]} 19:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:31 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')]} 19:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7fc0d1fa8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1571790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156e930>, '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 0x156f830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x156fcc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', '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 19:19:31 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 19:19:31 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 19:19:31 INFO - Using env: {'DISPLAY': ':0', 19:19:31 INFO - 'HOME': '/home/cltbld', 19:19:31 INFO - 'LANG': 'en_US.UTF-8', 19:19:31 INFO - 'LANGUAGE': 'en_US:en', 19:19:31 INFO - 'LOGNAME': 'cltbld', 19:19:31 INFO - 'MAIL': '/var/mail/cltbld', 19:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:31 INFO - 'MOZ_NO_REMOTE': '1', 19:19:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:31 INFO - 'NO_EM_RESTART': '1', 19:19:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:31 INFO - 'PWD': '/builds/slave/test', 19:19:31 INFO - 'SHELL': '/bin/bash', 19:19:31 INFO - 'SHLVL': '1', 19:19:31 INFO - 'TERM': 'linux', 19:19:31 INFO - 'TMOUT': '86400', 19:19:31 INFO - 'USER': 'cltbld', 19:19:31 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', 19:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:31 INFO - '_': '/tools/buildbot/bin/python'} 19:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:31 INFO - Downloading/unpacking psutil>=3.1.1 19:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:31 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 19:19:31 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 19:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:19:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:19:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:19:33 INFO - Installing collected packages: psutil 19:19:33 INFO - Running setup.py install for psutil 19:19:33 INFO - building 'psutil._psutil_linux' extension 19:19:33 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 19:19:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:19:33 INFO - building 'psutil._psutil_posix' extension 19:19:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:19:34 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 19:19:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:19:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:19:34 INFO - Successfully installed psutil 19:19:34 INFO - Cleaning up... 19:19:34 INFO - Return code: 0 19:19:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:19:34 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')]} 19:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:34 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')]} 19:19:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7fc0d1fa8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1571790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156e930>, '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 0x156f830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x156fcc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', '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 19:19:34 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 19:19:34 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 19:19:34 INFO - Using env: {'DISPLAY': ':0', 19:19:34 INFO - 'HOME': '/home/cltbld', 19:19:34 INFO - 'LANG': 'en_US.UTF-8', 19:19:34 INFO - 'LANGUAGE': 'en_US:en', 19:19:34 INFO - 'LOGNAME': 'cltbld', 19:19:34 INFO - 'MAIL': '/var/mail/cltbld', 19:19:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:34 INFO - 'MOZ_NO_REMOTE': '1', 19:19:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:34 INFO - 'NO_EM_RESTART': '1', 19:19:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:34 INFO - 'PWD': '/builds/slave/test', 19:19:34 INFO - 'SHELL': '/bin/bash', 19:19:34 INFO - 'SHLVL': '1', 19:19:34 INFO - 'TERM': 'linux', 19:19:34 INFO - 'TMOUT': '86400', 19:19:34 INFO - 'USER': 'cltbld', 19:19:34 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', 19:19:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:34 INFO - '_': '/tools/buildbot/bin/python'} 19:19:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:19:34 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 19:19:34 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 19:19:34 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 19:19:34 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 19:19:34 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 19:19:34 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 19:19:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:19:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:19:36 INFO - Installing collected packages: mozsystemmonitor 19:19:36 INFO - Running setup.py install for mozsystemmonitor 19:19:36 INFO - Successfully installed mozsystemmonitor 19:19:36 INFO - Cleaning up... 19:19:36 INFO - Return code: 0 19:19:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:19:36 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')]} 19:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:36 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')]} 19:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7fc0d1fa8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1571790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156e930>, '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 0x156f830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x156fcc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', '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 19:19:36 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 19:19:36 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 19:19:36 INFO - Using env: {'DISPLAY': ':0', 19:19:36 INFO - 'HOME': '/home/cltbld', 19:19:36 INFO - 'LANG': 'en_US.UTF-8', 19:19:36 INFO - 'LANGUAGE': 'en_US:en', 19:19:36 INFO - 'LOGNAME': 'cltbld', 19:19:36 INFO - 'MAIL': '/var/mail/cltbld', 19:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:36 INFO - 'MOZ_NO_REMOTE': '1', 19:19:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:36 INFO - 'NO_EM_RESTART': '1', 19:19:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:36 INFO - 'PWD': '/builds/slave/test', 19:19:36 INFO - 'SHELL': '/bin/bash', 19:19:36 INFO - 'SHLVL': '1', 19:19:36 INFO - 'TERM': 'linux', 19:19:36 INFO - 'TMOUT': '86400', 19:19:36 INFO - 'USER': 'cltbld', 19:19:36 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', 19:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:36 INFO - '_': '/tools/buildbot/bin/python'} 19:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:36 INFO - Downloading/unpacking jsonschema==2.5.1 19:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:36 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 19:19:36 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 19:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:19:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:38 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 19:19:38 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 19:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:38 INFO - Downloading functools32-3.2.3-2.tar.gz 19:19:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:19:38 INFO - warning: no files found matching '*.txt' 19:19:38 INFO - no previously-included directories found matching 'build' 19:19:38 INFO - no previously-included directories found matching 'dist' 19:19:38 INFO - no previously-included directories found matching '.git*' 19:19:38 INFO - Installing collected packages: jsonschema, functools32 19:19:38 INFO - Running setup.py install for functools32 19:19:38 INFO - warning: no files found matching '*.txt' 19:19:38 INFO - no previously-included directories found matching 'build' 19:19:38 INFO - no previously-included directories found matching 'dist' 19:19:38 INFO - no previously-included directories found matching '.git*' 19:19:39 INFO - Successfully installed jsonschema functools32 19:19:39 INFO - Cleaning up... 19:19:39 INFO - Return code: 0 19:19:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:19:39 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')]} 19:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:39 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')]} 19:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:39 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 0x7fc0d1fa8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1571790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156e930>, '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 0x156f830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x156fcc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', '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 19:19:39 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 19:19:39 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 19:19:39 INFO - Using env: {'DISPLAY': ':0', 19:19:39 INFO - 'HOME': '/home/cltbld', 19:19:39 INFO - 'LANG': 'en_US.UTF-8', 19:19:39 INFO - 'LANGUAGE': 'en_US:en', 19:19:39 INFO - 'LOGNAME': 'cltbld', 19:19:39 INFO - 'MAIL': '/var/mail/cltbld', 19:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:39 INFO - 'MOZ_NO_REMOTE': '1', 19:19:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:39 INFO - 'NO_EM_RESTART': '1', 19:19:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:39 INFO - 'PWD': '/builds/slave/test', 19:19:39 INFO - 'SHELL': '/bin/bash', 19:19:39 INFO - 'SHLVL': '1', 19:19:39 INFO - 'TERM': 'linux', 19:19:39 INFO - 'TMOUT': '86400', 19:19:39 INFO - 'USER': 'cltbld', 19:19:39 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', 19:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:39 INFO - '_': '/tools/buildbot/bin/python'} 19:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:19:39 INFO - Cleaning up... 19:19:39 INFO - Return code: 0 19:19:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:19:39 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')]} 19:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:39 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')]} 19:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:39 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 0x7fc0d1fa8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1571790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156e930>, '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 0x156f830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x156fcc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', '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 19:19:39 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 19:19:39 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 19:19:39 INFO - Using env: {'DISPLAY': ':0', 19:19:39 INFO - 'HOME': '/home/cltbld', 19:19:39 INFO - 'LANG': 'en_US.UTF-8', 19:19:39 INFO - 'LANGUAGE': 'en_US:en', 19:19:39 INFO - 'LOGNAME': 'cltbld', 19:19:39 INFO - 'MAIL': '/var/mail/cltbld', 19:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:39 INFO - 'MOZ_NO_REMOTE': '1', 19:19:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:39 INFO - 'NO_EM_RESTART': '1', 19:19:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:39 INFO - 'PWD': '/builds/slave/test', 19:19:39 INFO - 'SHELL': '/bin/bash', 19:19:39 INFO - 'SHLVL': '1', 19:19:39 INFO - 'TERM': 'linux', 19:19:39 INFO - 'TMOUT': '86400', 19:19:39 INFO - 'USER': 'cltbld', 19:19:39 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', 19:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:39 INFO - '_': '/tools/buildbot/bin/python'} 19:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:39 INFO - Downloading/unpacking blobuploader==1.2.4 19:19:39 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 19:19:39 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 19:19:39 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 19:19:39 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 19:19:39 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 19:19:39 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 19:19:41 INFO - Downloading blobuploader-1.2.4.tar.gz 19:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:19:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:19:41 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 19:19:41 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 19:19:41 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 19:19:41 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 19:19:41 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 19:19:41 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 19:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:19:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:19:42 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 19:19:42 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 19:19:42 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 19:19:42 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 19:19:42 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 19:19:42 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 19:19:42 INFO - Downloading docopt-0.6.1.tar.gz 19:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:19:42 INFO - Installing collected packages: blobuploader, requests, docopt 19:19:42 INFO - Running setup.py install for blobuploader 19:19:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:19:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:19:42 INFO - Running setup.py install for requests 19:19:42 INFO - Running setup.py install for docopt 19:19:42 INFO - Successfully installed blobuploader requests docopt 19:19:42 INFO - Cleaning up... 19:19:42 INFO - Return code: 0 19:19:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19:42 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')]} 19:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:42 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')]} 19:19:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:42 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 0x7fc0d1fa8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1571790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156e930>, '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 0x156f830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x156fcc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', '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 19:19:42 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 19:19:42 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 19:19:42 INFO - Using env: {'DISPLAY': ':0', 19:19:42 INFO - 'HOME': '/home/cltbld', 19:19:42 INFO - 'LANG': 'en_US.UTF-8', 19:19:42 INFO - 'LANGUAGE': 'en_US:en', 19:19:42 INFO - 'LOGNAME': 'cltbld', 19:19:42 INFO - 'MAIL': '/var/mail/cltbld', 19:19:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:42 INFO - 'MOZ_NO_REMOTE': '1', 19:19:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:42 INFO - 'NO_EM_RESTART': '1', 19:19:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:42 INFO - 'PWD': '/builds/slave/test', 19:19:42 INFO - 'SHELL': '/bin/bash', 19:19:42 INFO - 'SHLVL': '1', 19:19:42 INFO - 'TERM': 'linux', 19:19:42 INFO - 'TMOUT': '86400', 19:19:42 INFO - 'USER': 'cltbld', 19:19:42 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', 19:19:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:42 INFO - '_': '/tools/buildbot/bin/python'} 19:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:19:43 INFO - Running setup.py (path:/tmp/pip-p4CziM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:19:43 INFO - Running setup.py (path:/tmp/pip-ASClBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:19:43 INFO - Running setup.py (path:/tmp/pip-D2DDZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:19:43 INFO - Running setup.py (path:/tmp/pip-Mn311B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:19:43 INFO - Running setup.py (path:/tmp/pip-RgrNoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:19:43 INFO - Running setup.py (path:/tmp/pip-XTcgnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:19:43 INFO - Running setup.py (path:/tmp/pip-Ic2u_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:19:43 INFO - Running setup.py (path:/tmp/pip-GTcOiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:19:43 INFO - Running setup.py (path:/tmp/pip-9MovsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:19:43 INFO - Running setup.py (path:/tmp/pip-zrJBGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:19:43 INFO - Running setup.py (path:/tmp/pip-UjVsU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:19:43 INFO - Running setup.py (path:/tmp/pip-rVeAy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:19:44 INFO - Running setup.py (path:/tmp/pip-DAjKGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:19:44 INFO - Running setup.py (path:/tmp/pip-3CMdNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:44 INFO - Running setup.py (path:/tmp/pip-CARXrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:19:44 INFO - Running setup.py (path:/tmp/pip-IK6PVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:19:44 INFO - Running setup.py (path:/tmp/pip-ErUHfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:19:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:19:44 INFO - Running setup.py install for manifestparser 19:19:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:19:44 INFO - Running setup.py install for mozcrash 19:19:44 INFO - Running setup.py install for mozdebug 19:19:44 INFO - Running setup.py install for mozdevice 19:19:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:19:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:19:44 INFO - Running setup.py install for mozfile 19:19:44 INFO - Running setup.py install for mozhttpd 19:19:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:19:44 INFO - Running setup.py install for mozinfo 19:19:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:19:45 INFO - Running setup.py install for mozInstall 19:19:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:19:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:19:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:19:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:19:45 INFO - Running setup.py install for mozleak 19:19:45 INFO - Running setup.py install for mozlog 19:19:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:19:45 INFO - Running setup.py install for moznetwork 19:19:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:19:45 INFO - Running setup.py install for mozprocess 19:19:45 INFO - Running setup.py install for mozprofile 19:19:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:19:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:19:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:19:45 INFO - Running setup.py install for mozrunner 19:19:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:19:45 INFO - Running setup.py install for mozscreenshot 19:19:45 INFO - Running setup.py install for moztest 19:19:46 INFO - Running setup.py install for mozversion 19:19:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:19:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:19:46 INFO - Cleaning up... 19:19:46 INFO - Return code: 0 19:19:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19:46 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')]} 19:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:46 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')]} 19:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:46 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 0x7fc0d1fa8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1571790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156e930>, '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 0x156f830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x156fcc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', '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 19:19:46 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 19:19:46 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 19:19:46 INFO - Using env: {'DISPLAY': ':0', 19:19:46 INFO - 'HOME': '/home/cltbld', 19:19:46 INFO - 'LANG': 'en_US.UTF-8', 19:19:46 INFO - 'LANGUAGE': 'en_US:en', 19:19:46 INFO - 'LOGNAME': 'cltbld', 19:19:46 INFO - 'MAIL': '/var/mail/cltbld', 19:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:46 INFO - 'MOZ_NO_REMOTE': '1', 19:19:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:46 INFO - 'NO_EM_RESTART': '1', 19:19:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:46 INFO - 'PWD': '/builds/slave/test', 19:19:46 INFO - 'SHELL': '/bin/bash', 19:19:46 INFO - 'SHLVL': '1', 19:19:46 INFO - 'TERM': 'linux', 19:19:46 INFO - 'TMOUT': '86400', 19:19:46 INFO - 'USER': 'cltbld', 19:19:46 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', 19:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:46 INFO - '_': '/tools/buildbot/bin/python'} 19:19:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:19:46 INFO - Running setup.py (path:/tmp/pip-u3RYte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:19:46 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)) 19:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:19:46 INFO - Running setup.py (path:/tmp/pip-JFXNez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:19:46 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)) 19:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:19:46 INFO - Running setup.py (path:/tmp/pip-oJeKYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:19:46 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)) 19:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:19:46 INFO - Running setup.py (path:/tmp/pip-4BN0aO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:19:46 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)) 19:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:19:46 INFO - Running setup.py (path:/tmp/pip-EyYjOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:19:46 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)) 19:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:19:46 INFO - Running setup.py (path:/tmp/pip-ui3L4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:19:46 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)) 19:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:19:46 INFO - Running setup.py (path:/tmp/pip-OfeYfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:19:46 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)) 19:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:19:46 INFO - Running setup.py (path:/tmp/pip-CnD1vD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:19:46 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)) 19:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:19:46 INFO - Running setup.py (path:/tmp/pip-lK6IC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:19:46 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)) 19:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:19:47 INFO - Running setup.py (path:/tmp/pip-FMpKOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:19:47 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)) 19:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:19:47 INFO - Running setup.py (path:/tmp/pip-AERiMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:19:47 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)) 19:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:19:47 INFO - Running setup.py (path:/tmp/pip-x7wjlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:19:47 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)) 19:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:19:47 INFO - Running setup.py (path:/tmp/pip-OMtC4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:19:47 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)) 19:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:19:47 INFO - Running setup.py (path:/tmp/pip-grXTEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:19:47 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)) 19:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:47 INFO - Running setup.py (path:/tmp/pip-5z9QMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:19:47 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)) 19:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:19:47 INFO - Running setup.py (path:/tmp/pip-fmpQBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:19:47 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)) 19:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:19:47 INFO - Running setup.py (path:/tmp/pip-BvUZ9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:19:47 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)) 19:19:47 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)) 19:19:47 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)) 19:19:47 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)) 19:19:47 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)) 19:19:47 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)) 19:19:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:47 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 19:19:47 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 19:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:19:49 INFO - Downloading blessings-1.6.tar.gz 19:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:19:49 INFO - Installing collected packages: blessings 19:19:49 INFO - Running setup.py install for blessings 19:19:49 INFO - Successfully installed blessings 19:19:49 INFO - Cleaning up... 19:19:49 INFO - Return code: 0 19:19:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:19:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:19:49 INFO - Reading from file tmpfile_stdout 19:19:49 INFO - Current package versions: 19:19:49 INFO - argparse == 1.2.1 19:19:49 INFO - blessings == 1.6 19:19:49 INFO - blobuploader == 1.2.4 19:19:49 INFO - docopt == 0.6.1 19:19:49 INFO - functools32 == 3.2.3-2 19:19:49 INFO - jsonschema == 2.5.1 19:19:49 INFO - manifestparser == 1.1 19:19:49 INFO - mozInstall == 1.12 19:19:49 INFO - mozcrash == 0.17 19:19:49 INFO - mozdebug == 0.1 19:19:49 INFO - mozdevice == 0.48 19:19:49 INFO - mozfile == 1.2 19:19:49 INFO - mozhttpd == 0.7 19:19:49 INFO - mozinfo == 0.9 19:19:49 INFO - mozleak == 0.1 19:19:49 INFO - mozlog == 3.4 19:19:49 INFO - moznetwork == 0.27 19:19:49 INFO - mozprocess == 0.23 19:19:49 INFO - mozprofile == 0.28 19:19:49 INFO - mozrunner == 6.12 19:19:49 INFO - mozscreenshot == 0.1 19:19:49 INFO - mozsystemmonitor == 0.3 19:19:49 INFO - moztest == 0.7 19:19:49 INFO - mozversion == 1.4 19:19:49 INFO - psutil == 3.1.1 19:19:49 INFO - requests == 1.2.3 19:19:49 INFO - wsgiref == 0.1.2 19:19:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:19:49 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')]} 19:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:49 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')]} 19:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:49 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 0x7fc0d1fa8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1571790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156e930>, '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 0x156f830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x156fcc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', '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 19:19:49 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 19:19:49 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 19:19:49 INFO - Using env: {'DISPLAY': ':0', 19:19:49 INFO - 'HOME': '/home/cltbld', 19:19:49 INFO - 'LANG': 'en_US.UTF-8', 19:19:49 INFO - 'LANGUAGE': 'en_US:en', 19:19:49 INFO - 'LOGNAME': 'cltbld', 19:19:49 INFO - 'MAIL': '/var/mail/cltbld', 19:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:49 INFO - 'MOZ_NO_REMOTE': '1', 19:19:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:49 INFO - 'NO_EM_RESTART': '1', 19:19:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:49 INFO - 'PWD': '/builds/slave/test', 19:19:49 INFO - 'SHELL': '/bin/bash', 19:19:49 INFO - 'SHLVL': '1', 19:19:49 INFO - 'TERM': 'linux', 19:19:49 INFO - 'TMOUT': '86400', 19:19:49 INFO - 'USER': 'cltbld', 19:19:49 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', 19:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:49 INFO - '_': '/tools/buildbot/bin/python'} 19:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:50 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)) 19:19:50 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)) 19:19:50 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)) 19:19:50 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)) 19:19:50 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)) 19:19:50 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)) 19:19:50 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)) 19:19:50 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)) 19:19:50 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)) 19:19:50 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)) 19:19:50 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)) 19:19:50 INFO - Cleaning up... 19:19:50 INFO - Return code: 0 19:19:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:19:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0d1fa8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14a48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1571790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x156e930>, '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 0x156f830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x156fcc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', '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 19:19:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:19:50 INFO - Using env: {'DISPLAY': ':0', 19:19:50 INFO - 'HOME': '/home/cltbld', 19:19:50 INFO - 'LANG': 'en_US.UTF-8', 19:19:50 INFO - 'LANGUAGE': 'en_US:en', 19:19:50 INFO - 'LOGNAME': 'cltbld', 19:19:50 INFO - 'MAIL': '/var/mail/cltbld', 19:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:50 INFO - 'MOZ_NO_REMOTE': '1', 19:19:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:50 INFO - 'NO_EM_RESTART': '1', 19:19:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:50 INFO - 'PWD': '/builds/slave/test', 19:19:50 INFO - 'SHELL': '/bin/bash', 19:19:50 INFO - 'SHLVL': '1', 19:19:50 INFO - 'TERM': 'linux', 19:19:50 INFO - 'TMOUT': '86400', 19:19:50 INFO - 'USER': 'cltbld', 19:19:50 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', 19:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:50 INFO - '_': '/tools/buildbot/bin/python'} 19:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:19:50 INFO - Cleaning up... 19:19:50 INFO - Return code: 0 19:19:50 INFO - Running post-action listener: _resource_record_post_action 19:19:50 INFO - Running post-action listener: _start_resource_monitoring 19:19:50 INFO - Starting resource monitoring. 19:19:50 INFO - [mozharness: 2016-09-18 02:19:50.462637Z] Finished create-virtualenv step (success) 19:19:50 INFO - [mozharness: 2016-09-18 02:19:50.463142Z] Running install step. 19:19:50 INFO - Running pre-action listener: _resource_record_pre_action 19:19:50 INFO - Running main action method: install 19:19:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:19:50 INFO - Reading from file tmpfile_stdout 19:19:50 INFO - Detecting whether we're running mozinstall >=1.0... 19:19:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:19:50 INFO - Reading from file tmpfile_stdout 19:19:50 INFO - Output received: 19:19:50 INFO - Usage: mozinstall [options] installer 19:19:50 INFO - Options: 19:19:50 INFO - -h, --help show this help message and exit 19:19:50 INFO - -d DEST, --destination=DEST 19:19:50 INFO - Directory to install application into. [default: 19:19:50 INFO - "/builds/slave/test"] 19:19:50 INFO - --app=APP Application being installed. [default: firefox] 19:19:50 INFO - mkdir: /builds/slave/test/build/application 19:19:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:20:07 INFO - Reading from file tmpfile_stdout 19:20:07 INFO - Output received: 19:20:07 INFO - /builds/slave/test/build/application/firefox/firefox 19:20:07 INFO - Running post-action listener: _resource_record_post_action 19:20:07 INFO - [mozharness: 2016-09-18 02:20:07.738522Z] Finished install step (success) 19:20:07 INFO - [mozharness: 2016-09-18 02:20:07.738769Z] Running run-tests step. 19:20:07 INFO - Running pre-action listener: _resource_record_pre_action 19:20:07 INFO - Running main action method: run_tests 19:20:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:20:07 INFO - Python 2.7.3 19:20:07 INFO - Return code: 0 19:20:07 INFO - grabbing minidump binary from tooltool 19:20:07 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')]} 19:20:07 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 0x156e930>, '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 0x156f830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x156fcc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:20:07 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 19:20:07 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 19:20:07 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 19:20:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:20:07 INFO - Return code: 0 19:20:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:20:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:20:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:20:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:20:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:20:07 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/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 19:20:07 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/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --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 19:20:07 INFO - Using env: {'DISPLAY': ':0', 19:20:07 INFO - 'HOME': '/home/cltbld', 19:20:07 INFO - 'LANG': 'en_US.UTF-8', 19:20:07 INFO - 'LANGUAGE': 'en_US:en', 19:20:07 INFO - 'LOGNAME': 'cltbld', 19:20:07 INFO - 'MAIL': '/var/mail/cltbld', 19:20:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:20:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:20:07 INFO - 'MOZ_NO_REMOTE': '1', 19:20:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:20:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:20:07 INFO - 'NO_EM_RESTART': '1', 19:20:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:20:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:20:07 INFO - 'PWD': '/builds/slave/test', 19:20:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:20:07 INFO - 'SHELL': '/bin/bash', 19:20:07 INFO - 'SHLVL': '1', 19:20:07 INFO - 'TERM': 'linux', 19:20:07 INFO - 'TMOUT': '86400', 19:20:07 INFO - 'USER': 'cltbld', 19:20:07 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714', 19:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:20:07 INFO - '_': '/tools/buildbot/bin/python'} 19:20:07 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/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 19:20:08 INFO - mozversion application_buildid: 20160917163004 19:20:08 INFO - mozversion application_changeset: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 19:20:08 INFO - mozversion application_display_name: Nightly 19:20:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:20:08 INFO - mozversion application_name: Firefox 19:20:08 INFO - mozversion application_remotingname: firefox 19:20:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:20:08 INFO - mozversion application_vendor: Mozilla 19:20:08 INFO - mozversion application_version: 51.0a1 19:20:08 INFO - mozversion platform_buildid: 20160917163004 19:20:08 INFO - mozversion platform_changeset: eaf5eb6f8fa0d8e7a09f3774c0da53c0dd6dadd7 19:20:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:20:08 INFO - mozversion platform_version: 51.0a1 19:20:08 INFO - using testdate: 1474165208 19:20:08 INFO - actual date: 1474165208 19:20:08 INFO - starting webserver on 'localhost:43207' 19:20:08 INFO - SUITE-START | Running 2 tests 19:20:08 INFO - TEST-START | dromaeo_css 19:20:08 INFO - operating with platform_type : linux_ 19:20:08 INFO - Initialising browser for dromaeo_css test... 19:20:08 INFO - TEST-INFO | started process 3523 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQVvVPQ/profile http://localhost:43207/getInfo.html) 19:20:12 INFO - PROCESS | 3523 | __metrics Screen width/height:1600/1200 19:20:12 INFO - PROCESS | 3523 | colorDepth:24 19:20:12 INFO - PROCESS | 3523 | Browser inner width/height: 1024/697 19:20:12 INFO - PROCESS | 3523 | __metrics 19:20:12 INFO - PROCESS | 3523 | 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" 19:20:12 INFO - PROCESS | 3523 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:20:13 INFO - PROCESS | 3523 | console.error: PushService: 19:20:13 INFO - PROCESS | 3523 | stateChangeProcessEnqueue: Error transitioning state 19:20:13 INFO - PROCESS | 3523 | UnknownError 19:20:14 INFO - TEST-INFO | 3523: exit 0 19:20:14 INFO - Browser initialized. 19:20:14 INFO - Running cycle 1/1 for dromaeo_css test... 19:20:14 INFO - TEST-INFO | started process 3605 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQVvVPQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:20:26 INFO - PROCESS | 3605 | RSS: Main: 172793856 19:20:26 INFO - PROCESS | 3605 | 19:21:08 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:21:12 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:21:15 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.08339 seconds. 19:21:17 INFO - PROCESS | 3605 | 1474165277708 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] 19:21:23 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.06675 seconds. 19:21:47 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:21:53 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 19:22:02 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:22:14 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:22:18 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 19:22:35 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:22:36 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:22:53 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:22:55 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 19:23:08 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:23:18 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 19:23:24 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 19:23:26 INFO - PROCESS | 3605 | Cycle 1(1): loaded http://localhost:43207/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43207/tests/dromaeo/cssquery-ext.html) 19:23:28 INFO - PROCESS | 3605 | RSS: Main: 323305472 19:23:28 INFO - PROCESS | 3605 | 19:23:40 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:23:48 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:23:54 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:23:56 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:23:58 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:24:00 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:24:05 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:24:31 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 19:24:35 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:24:39 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:25:19 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:25:27 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00297 seconds. 19:25:30 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:25:32 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:25:58 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:26:03 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 19:26:04 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:26:14 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:26:18 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:26:20 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:26:24 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:26:30 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:26:30 INFO - PROCESS | 3605 | Cycle 1(1): loaded http://localhost:43207/tests/dromaeo/cssquery-ext.html (next: http://localhost:43207/tests/dromaeo/cssquery-jquery.html) 19:26:31 INFO - PROCESS | 3605 | RSS: Main: 276312064 19:26:31 INFO - PROCESS | 3605 | 19:26:34 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 19:26:41 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 19:26:43 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:26:47 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:27:00 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:27:18 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:27:20 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 19:27:22 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:27:28 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:27:32 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:27:40 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:27:46 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:27:54 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:28:00 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 19:28:04 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:28:19 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 19:28:28 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:28:32 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:28:37 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:28:49 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:28:57 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:29:09 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:29:19 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:29:31 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:29:33 INFO - PROCESS | 3605 | Cycle 1(1): loaded http://localhost:43207/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43207/tests/dromaeo/cssquery-mootools.html) 19:29:34 INFO - PROCESS | 3605 | RSS: Main: 214368256 19:29:34 INFO - PROCESS | 3605 | 19:29:39 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:29:42 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:29:47 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:30:14 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:30:19 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:30:33 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:30:47 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:31:17 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:31:21 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:31:31 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:31:49 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:31:51 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:32:28 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:32:32 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:32:36 INFO - PROCESS | 3605 | Cycle 1(1): loaded http://localhost:43207/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43207/tests/dromaeo/cssquery-prototype.html) 19:32:36 INFO - PROCESS | 3605 | RSS: Main: 256483328 19:32:36 INFO - PROCESS | 3605 | 19:32:55 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:33:02 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:33:05 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:33:11 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:33:15 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:33:23 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:33:25 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:33:27 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 19:33:36 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:33:44 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:33:53 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 19:34:14 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:34:22 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:34:44 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:34:55 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 19:34:57 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:35:03 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:35:05 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 19:35:07 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:35:11 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:35:18 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:35:21 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:35:31 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:35:33 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 19:35:39 INFO - PROCESS | 3605 | Cycle 1(1): loaded http://localhost:43207/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43207/tests/dromaeo/cssquery-yui.html) 19:35:40 INFO - PROCESS | 3605 | RSS: Main: 215953408 19:35:40 INFO - PROCESS | 3605 | 19:35:43 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 19:35:46 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:36:02 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:36:08 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 19:36:20 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:36:25 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:36:35 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:36:39 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:36:52 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 19:37:05 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:37:08 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 19:37:15 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:37:19 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 19:37:42 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 19:37:56 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 19:38:01 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 19:38:06 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:38:08 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 19:38:10 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 19:38:37 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 19:38:39 INFO - PROCESS | 3605 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 19:38:43 INFO - PROCESS | 3605 | Cycle 1(1): loaded http://localhost:43207/tests/dromaeo/cssquery-yui.html (next: http://localhost:43207/tests/dromaeo/cssquery-dojo.html) 19:38:43 INFO - PROCESS | 3605 | RSS: Main: 305733632 19:38:43 INFO - PROCESS | 3605 | 19:38:43 INFO - PROCESS | 3605 | __start_tp_report 19:38:43 INFO - PROCESS | 3605 | _x_x_mozilla_page_load 19:38:43 INFO - PROCESS | 3605 | _x_x_mozilla_page_load_details 19:38:43 INFO - PROCESS | 3605 | |i|pagename|runs| 19:38:43 INFO - PROCESS | 3605 | |0;dojo.html;3012.367402845403;3026.6519350274725;3039.7416219621336;3058.9752432934965;3077.415291094692;5117.334746482952;5173.560247378972;5195.532402083812;5278.47215278472;5317.441668624797;5210.140326846066;5229.189475631283;5272.657277276974;5275.28783614212;5281.9207711884355;4803.135435621587;4817.590504807095;4856.927146092811;4872.902541949159;4877.390326209215;4787.066522028206;4885.8778530536665;4902.485239049902;4918.877028074291;4929.753512324387;4860.295257187688;4874.634402419832;4901.044296362211;4920.8031678732805;4965.28003439501;4765.928511072337;4766.213574760161;4768.713877167381;4785.808567657289;4814.374131362935;4726.031163611451;4727.57451829337;4729.574338309569;4758.571728544413;4786.234202527578;5174.508421699932;5201.765920533585;5215.973920130413;5221.71280579568;5222.608304377187;5444.945550544483;5496.587755918322;5568.9443105568835;5599.132134519115;5604.523615492688;5643.153526970947;5678.375378708339;5700.857478563028;5701.14482827575;5750.942490575125;5291.259223708684;5299.311089558333;5301.549368303698;5307.920381194285;5371.221172929982;5594.13290939905;5632.633878797865;5637.041702910515;5640.689762063043;5778.219940308089;5475.534579560782;5479.287692599936;5512.200730893998;5527.226188333637;5547.639403438844;5573.136163894603;5582.581306401955;5585.553155747531;5586.636868603528;5590.748416321276;5406.135018397038;5439.945600544023;5481.75332110056;5510.338759348823;5513.421090785409;5550.139728342114;5558.138488534284;5560.7219639017885;5604;5627.437256274422;4282.614564689131;4321.827126914951;4335.67482438812;4339.913201735948;4345.782710864444;3848.8652897148463;3859.710521710883;3878.5733569307354;3881.068543549528;3901.1027463683513;5213.374395072616;5270.077736396115;5272.578193744491;5282.471752824764;5309.33633295838;5603.943960560424;5613.550915926716;5618.887622247532;5619.915701264485;5621.437856214346;5227.764750586232;5293.841184764463;5304.416514183436;5322.33470816148;5387.030334539821;4813.590844778233;4847.878803029882;4849.515048495193;4887.144749668865;4926.211806111005;5795.565332599987;5795.565332600072;5796.739146738322;5799.449052340021;5800.970995145081;5395.838124856261;5603.80386686464;5663.11977577687;5669.036263835241;5672.092465205547;5547;5748.568857335633;5775.104858746901;5775.960327140987;5890.4698577128265;5558.972205138948;5573.442655734556;5581.190727344593;5581.218629391808;5584.748686309207;5606.831795046155;5677.091665333692;5680.68756218412;5684.204211410489;5690.715464226855;5377.435369286325;5416.945830541802;5434.266374039454;5454.672719636835;5521.116621340566;5422.267993820784;5472.20653005304;5480.862978425571;5485.725713714219;5526.281583394133;5464.562834973272;5470.234167216513;5500.752466138953;5503.9174412383045;5561.555075594024;5386.569074473954;5429.837104886859;5436.483534064258;5440.401555828935;5512.972435137799;5400.324959380077;5401.054815407367;5402.648827826179;5449.536789372789;5463.617546771688 19:38:43 INFO - PROCESS | 3605 | |1;ext.html;553.568216790903;588.8998870192067;593.0333556303225;598.4225222660132;624.5191202773863;31893.68106318936;42522.787386063064;43069.13861722764;43175.56824431755;43505;44974.32538511925;44992.550074499246;45339;45449.31826022612;45521.0895782085;8118.10700822912;8943.463392196472;9093.40892841963;9182.86225706615;9186.770330741752;8176.591170441484;8453.70412035579;8455.112213217615;10585.629502967398;10831.404272764994;10945.890541094606;11052.392118433483;11053.28153670009;11053.28153670013;11058.336499810037;10771.24601277911;10807.4055926924;10829.891701082966;10855.131589472823;10868.67393978182;10579.31234469757;10657.626983055634;10688.572457101707;10741.83887241692;10742.516586753536;42605;43252;43380.34929476061;43401.34897976533;43421.782891085655;3289.703926646613;3334.299797042624;3347.313800670857;3597.9280414391565;3625.4743062255825;32320;32386.838065809756;32476;32524.186895327806;32536;4266.296061149907;4458.08609235106;4458.152950939344;4571.679982401233;4574.062317224994;264370;265350;266920;267412.2923239202;267558;33017.834910825775;60164.2843929826;60529.39470605326;60559.697201514595;60564.69717651384;34629;35055;35176.296474070376;35214.00680942097;35278.11804704851;15724.29240684172;15962.840371596367;15969.121698306704;15970.441034563735;15979.60050998711;12138.453769580214;13262.801057984138;13387.933060334635;13391.531296404579;13396;8199.877001844978;8365.58172091393;8706.085860984633;8756.299496040301;8881.644734210558;3315.3203593263324;3408.1309266136977;3415.1462134466383;3444.156181735491;3456.170519075454;987.09680642213;996.3573495095623;1006.7785087280932;1086.1148164246115;1098.8406681031206;32175.35649287048;32617.347653046807;32624.83687581547;32790.20979020979;33229.16927076794;1091.028984204059;1094.3215206571977;1096.3969816600838;1113.0817075912405;1215.4408971873038;16707.415240466526;17187.742183867253;17335;17420.77715622614;17540.824591754175;554514;565981;566614;586106;923988;59068.11397829036;59734.402655973754;59897.40102599005;59999.40000600025;60021.399786002454;1246.077902352271;1270.9618711438857;1344.4554955243143;1382.156884300576;1388.5001399496173;29245.561316579842;32246.35507289888;33397.83301083479;35111;35352.64647353494;56973.14540281816;57517.42482575121;57695.71152144212;57709.42290577209;57712.71143644255;491136;492821;493341;494210;494521;28486.287842804097;29201.70798292075;29304.7069529302;29629.703702962695;30995.0701478956;37229.62770372262;41044.58955410407;46953;48282;52856.471435286694;41064.384034238916;42034.78982605068;42206.577934221496;42217.36673949832;44202.11595768002;41147.58852411557;41694.16611667689;42030.789846050575;42784.57215427806;43457.565424345354;40359.1928161441;40866.79566602148;41160.79419602883;41726;44977.32534012058;33556.651034427356;37263.25473490569;37407.625923740416;37424;38167.2366552673;28312.906884371274;28679.42641147207;28700.569491458064;28725.425491489634;29111.854440728508 19:38:43 INFO - PROCESS | 3605 | |2;jquery.html;387.54850599052105;391.14144452925837;400.8236375994561;405.42024904386733;424.10302210824096;14025.158490490578;17793.555161120978;18067.186976586054;19604.60790784185;19629.411117666474;14938;15535.611609709764;18247.90876045618;18999;19282.421527354167;5521.4478552144865;5675.063614503602;5734.111212762008;5735.684537350444;5736.311642602894;5478.863028424282;5615.943840561604;5651.095824668054;5673.404292549285;5684.488396044355;5645.15322701596;5688.829335119953;5692.633767895717;5698.629589076718;5700.087985922253;5400.432954539795;5538.0585300499015;5658.7170641467965;5667.829965101054;5670.518005969477;4994.450610432849;5001.474845141244;5007.899842003176;5011.423686276052;5015.448300686921;22279.331620051424;22405.43986400353;22466.213682521196;22597.435064123365;23517.17689880846;2453.570625140593;2460.520198561281;2844.061459718288;3024.1381206356305;3197.3533630180477;13894;14231.501897433644;14279.857201427956;14558.417663293456;14941.925290373478;1779.8576113910856;1785.6785778559854;1792;1836.8438682711985;1884.4158310923658;78172;81075;81385.59307203486;82507.5874620629;83191;10248.948755256326;10420.166386689047;10517.579296828042;10753.408562529135;10816.35101893873;14590.63523411902;14690.11859288446;14695.41218351254;14724.631884202767;14770.556883293519;7721.4481338583755;8093.55485448306;8132.6746930122135;8334.249917507454;8390.077091519928;7996.68013279474;8024.879626805603;8105.513669179868;8273.179900421897;8315.625796839156;5795.13073039039;6175.104609831549;6187.566870319124;6504.674766261763;6732.293109223461;1681.159420289855;1741.8519425848692;1771.5836778357075;1800.4778614201734;1803.9098045097692;946.9914541013799;955.4553904274496;973.5570315506427;1003.9247056470793;1008.9243306752023;18837.739471263536;19062;19251.903740481208;19527;20014.89992550057;952.0872298937587;978.1587834462357;989.099919073654;1013.5287091502487;1028.1209565821264;8919.464832110094;10235.539400726837;10903.781924361469;10932.945335273433;10960.671179864617;471680;475018;477374;477712;478551;87526.56236718904;90856;91402.54298728597;91500.54249728708;91941;999.205631522941;1006.9848952265722;1053.7471006958274;1056.9471526423647;1059.0891833023593;16626.833731663013;16830.57923551921;17373.47879563615;17862.732059018865;18036.54908627295;29038;34649.30701386009;34870.30259394849;39702.801485992386;39799.80100099481;55953.72023139858;57405.71297143488;57454.42545574491;57632.71183644223;58130;7676.923230767621;8427.23028164068;8523.95738021306;8616.913830861862;8761.649534018568;12906.870931290567;13282.335883205995;13351.666208344868;13435.059545831698;13517.797233041709;11275.887241127484;11510.769784604428;11617.302961822317;11806.291622502677;11861.110416718953;11487.36819474937;11509.251898626562;11514.136439767219;11812.173147879566;11814.46834892449;11668.474918628852;11773.411329433666;11828.76342473163;11848.70378240551;11888.227265227733;7891.723789667334;7938.960305198437;8001.11973686195;8226.712065077694;8273.172682731678;10662;11650;11842.585509507126;11897.583584574844;11990.640280791591 19:38:43 INFO - PROCESS | 3605 | |3;mootools.html;1441.4594527052354;1467.963300917477;1608.887377883548;1731.5151757507886;1762.16297258802;7626.31363177314;7922.801929951754;8197.385196110294;8913.242374398176;8937.731868043953;8228.053773816013;8435.114312997139;8435.536045517494;8689.869651955225;8710.08544102868;2290.198430549311;2295.1737374545232;2302.654601809725;2325.6744055832196;2326.2323433267065;2013.577148798754;2077.210659949225;2216.1135545781653;2259.830512711545;2261.050358849287;2219.7225346831647;2250.5948929192737;2259.1754009786473;2259.2770313499686;2259.344790010895;2205.933821985335;2212.214663794354;2252.3017864462067;2254.966175507369;2254.9887250563806;2126.627840127987;2202.449387653087;2208.7018252535863;2224.3104637562406;2230.4423894026495;8689.3048556116;8695.69565065226;8732.345074119403;8790.120987901222;8801.779955501099;5135.435102138762;5283.418823929364;5284.128118860383;5286.867828304323;5287.762050707727;3208.454562724319;3219.3239419721885;3323.3443212466336;3349.279904820475;3371.2414706690797;1043.2644985285356;1103.150574057979;1106.717786964327;1124.0951034417292;1129.610284451871;12017.158798884082;12140.696482588006;12145;12149.514019439212;12163.02695784344;4112.588741125864;4354;4447.688661793643;4497.302918047708;4515.435570553681;9602.183814375856;9604.135627793532;9607.855882161773;9610.183134433652;9611.663591774255;5062.772175252049;5077.492250774893;5095.694258344512;5108.386993560796;5112.437631860493;4593;4679.344891715163;4740.881477963078;4751.168545504523;4760.976195119002;3593;3611.2777444511203;3644.2347107107803;3679.1170119171743;3722.739408241397;1018.4144117132678;1051;1111.3332000799455;1115.4199816095586;1239.2155296373194;805.5488926201346;847.2247893177673;986.7187851462298;987.2891518106951;989.8960609136082;5857.970710146507;5981.491573216325;5981.940180598225;5984.551158663117;5985.311689155716;964.9469279189711;988.9258305627106;1020.5560581147224;1023.8413045977885;1045.132539991805;3903.3559462688904;4249.3413520904305;4280.464941882265;4284.507281662586;4313.633341166036;9979.451130187765;10507.264491485523;10521.316114452537;10530.789384212272;10688.091512221392;10163.9491802542;10172.694819155437;10231;10267.281290309607;10318.742031449123;7759.999999999887;7969.402294827795;8088.11030786613;8109.797255068553;8118.594070296462;6112.510999120149;6209.2548894133;6224;6242.375762423721;6278.215223097113;9675.467849268358;9732.70801875945;9736.367136136128;9822.607095716092;9893.455859927773;9265.953670231605;9674.9032509674;9711.757206069622;9917.206623469961;9970.651027214013;7630;7752.65113069902;7780.572068536285;7785.805354865947;7819.921800781919;8278.917210828056;8283.337333013224;8329.250367466957;8332.291755200877;8335.333173346238;7425.7029718810645;7444.664990075568;7444.776656700091;7474.140473845578;7662.463627546018;7208.387287080657;7383.261673832574;7424.109106907205;7429.07136607924;7455.179930207564;6732.293109223461;6815.89776153368;6820.556663816836;6821.556598821061;6822.556533825285;5125.359330083739;5225.190095535198;5250.763715632882;5260.658057226268;5499.972500137474;5213.478652134756;5262.9210561842365;5269.262303277467;5357.196420536795;5478.671279723229 19:38:43 INFO - PROCESS | 3605 | |4;prototype.html;322.4660978722995;339.88613814372184;340.7393344091769;342.7292438973208;345.78734078541686;5103.413107492639;5199.50604692554;5225.372955245366;5268.9473105268935;5317.734113294329;5011.674241174321;5158.92261616076;5188.429272779991;5202.141646628311;5230.712310822903;4197;4199.811008504609;4200.537940826506;4206.978965105185;4251.787410629471;3966.5636779954184;4107.79461026949;4205.095904380558;4205.411242426063;4219.375540416946;4073.3279008963486;4081.653059489947;4087.795610219492;4089.795510224492;4093.9590604094024;4075.816588253506;4077.755334679914;4078.4697989261354;4089;4125.958740412588;3636.181859081712;3637.8362973665985;3643.872464463731;3654.470101835219;3660.103274697716;5027.974860125676;5069.264956581275;5077.49225077493;5082.33929589151;5084.694918304914;4596.839110631146;4600.125976064537;4620;4632.239748895398;4633.119707255644;5371.4897084776885;5386.542143917733;5395.7841686332895;5408.864778380533;5414.6734050157565;3749.8687545935763;3907.339380763193;3907.7335145615475;3908.3160446921675;3922.5096862892137;5653.462921022497;5656.8868622627315;5670.5463562914865;5680.869673872298;5739.368669446316;4082.5915059304493;4083.8570650027104;4093.16090201508;4107;4109.219248342865;4053.310937140693;4100.36444351126;4111.753294802322;4118.176364727066;4164.021454958082;4621.329907163443;4624.97687511567;4637.39713837199;4652.720836749806;4668.3697700810635;5462.289902312754;5474.534664553557;5511.696856672921;5516.255305533701;5517.310336207974;1324.9138805977773;1346.8316460442445;1361.4547488600313;1379.489588852111;1392.296890070508;954.5275088830934;956.5078477575005;965.6234068713208;972.9610815567298;980.2305190425482;5248.343957005374;5253.530840504956;5256;5256.421793602701;5278.788848446019;5537.8061767837935;5547.306586676666;5554.305711786027;5556.6943818089585;5579.302587176603;5106.821261255838;5261.500157485033;5274;5280.1815718563685;5366.7048312343195;2961.540961151025;2984.7313741763346;2986.3430045390414;2990.3122281874857;2995.7453616442845;5762.262925110623;5770.970240623622;5776.942230577724;5785.739641716133;5811.796587119515;5656.349519805192;5734.598578099576;5755.942440575541;5772.393898640582;5776.227410842735;5745.885082298414;5748.298299799171;5764.769409223584;5769.624974376652;5782.913256301074;5393.164059570773;5548.722563871709;5644.331184729958;5654.519365853784;5665.376808550974;5518.779248830002;5528.889422211614;5537.169424586345;5563.388027316911;5576.4664717203095;5769.624974376652;5771.971140144273;5796.840631873726;5807.303123625192;5827.154078308296;3905.5508616508328;4020.557738648688;4050.5949405060437;4061.248668996245;4069.308217603074;5451.37309209446;5617.634853734494;5637.210790489253;5652.604317697722;5676.290463692038;5286.933578524524;5426.213199086189;5505.642133261325;5517.144842549411;5523.2819733436;5432.348118225838;5475.561955043508;5490.533304669148;5498.670079795225;5506.256655351476;5219.721168313636;5413.323334583177;5486.067368547437;5487.20435536837;5520.337559492887;5456.863578410572;5466.840597963862;5481.342238931354;5484.643498172606;5495.890082198413;5387.272718182995;5410.3507579090765;5413.945860541502;5449.485082122088;5482.17767334903 19:38:43 INFO - PROCESS | 3605 | |5;yui.html;736.318905012863;748.610722424339;786.3984052200071;799.9000124984377;806.632981993193;14361.928190359047;15347.3093710783;15378.923105384485;15437.614059648515;17001.82998170018;15444.922775386136;15493.225338733075;15585.688286234297;15613.375464981416;15689.529314120566;794.3605397654885;811.1523457986389;813.6948644258404;816.3510009542427;818.5088946632033;665.8135721997849;676.5399528320739;677.7492327838681;682.911221541199;808.6724876425028;701.5019336271254;825.4964471672275;855.9828803423928;856.4689892266804;864.9135086491332;836.9874451883227;842.9789255268606;845.0282175498227;845.4377838737232;846.0059430169526;619.9721012554446;620.0296535921314;620.5842085802523;620.6989610039127;621.1335187413578;11125.499352529074;11707.063434925185;14590.416383344653;15131.697366052727;15150.772738408823;14193.574192774233;14901.627459313495;15088.321025553876;15221.543353699406;15223.923880380527;11372.886271137266;11487.597934072352;11619;11651.708707282301;11653.126015548867;3763.8870833875026;3766.0025265838485;3783.6027217142355;3826.559945606235;3874.728768986172;79261.60369198174;79541.60229198817;79640;80106.59946700287;80659.19340806635;10911.181661375429;11051.557937682403;11290.43547822622;11306.16125066579;11327.660170194908;22636.320910372717;22693.43266418353;22725.09099635996;22762.886185568965;23459.530809384058;7996.9200307997335;8182.345412367115;8235.917640823634;8289.8342033159;8457.23884850354;4570.794314255866;4670.509596492388;4721.527847215251;4752.596029337545;4914.189158788831;3447.3105378924315;3539.2921415716446;3540.946885796715;3540.9645903540636;3541.15012397028;543.1093007467756;547.9671219726829;555.097965805566;555.849920521465;557.0557904352085;716.3910675925422;731.2285538756556;731.491613328742;755.2372104174823;769.9749460471719;8935.374523783274;9726.818926088281;9791.46146961924;9792.21662267017;9909.157721593601;637.9013047075164;671.664167916042;693.760652574871;725.3218240944733;737.3463449395349;4582.633389328846;4670.95329046712;4702;4710.646701497333;4761.595264402565;98205;101112;102478;113147;120476;96351;97917;97928.51035744775;100286.99713002777;100757;67328.6633566829;79031.67746580375;86056;87831;88120.55939720261;8761.561921904007;9155.496447695441;9460;9554.378965367228;9643.662471813454;48726.85821705195;49333.2600010993;49706.00587988292;50230.748846255534;50577.24134138064;70943.64528177326;72495.63752181205;74451;74573;75518.24481755332;12956.28740419286;13053.34733263352;13405.530806421728;13457.125286856322;13539.59381218565;13906.721865562835;13907.721845563234;14250.073745206528;14910.627234318796;14911.701765964403;12475.376231188586;12526.624201273977;12541.74916501683;13233.801492977806;13350.799738004132;12260.68497168719;12913.677158071123;13259.66850828737;13306.800397993844;13522.661933451742;12985.675358116123;13119;13263.137896036727;13551.525696600966;13744.86255137436;10592.576296948344;10597.735056623646;10879.51042203087;11151.386673732699;11284.605038823602;10381.169506439315;10839.512221950188;10902.085784788394;10920.617778377718;11054.723631909266 19:38:43 INFO - PROCESS | 3605 | __end_tp_report 19:38:43 INFO - PROCESS | 3605 | __start_cc_report 19:38:43 INFO - PROCESS | 3605 | _x_x_mozilla_cycle_collect,1216 19:38:43 INFO - PROCESS | 3605 | __end_cc_report 19:38:43 INFO - PROCESS | 3605 | __startTimestamp1474166323390__endTimestamp 19:38:43 INFO - PROCESS | 3605 | 19:38:43 INFO - PROCESS | 3605 | ------- Summary: start ------- 19:38:43 INFO - PROCESS | 3605 | Number of tests: 6 19:38:43 INFO - PROCESS | 3605 | 19:38:43 INFO - PROCESS | 3605 | [#0] dojo.html Cycles:165 Average:5217.01 Median:5416.95 stddev:573.42 (10.6%) stddev-sans-first:548.48 19:38:43 INFO - PROCESS | 3605 | Values: 3012.4 3026.7 3039.7 3059.0 3077.4 5117.3 5173.6 5195.5 5278.5 5317.4 5210.1 5229.2 5272.7 5275.3 5281.9 4803.1 4817.6 4856.9 4872.9 4877.4 4787.1 4885.9 4902.5 4918.9 4929.8 4860.3 4874.6 4901.0 4920.8 4965.3 4765.9 4766.2 4768.7 4785.8 4814.4 4726.0 4727.6 4729.6 4758.6 4786.2 5174.5 5201.8 5216.0 5221.7 5222.6 5444.9 5496.6 5568.9 5599.1 5604.5 5643.2 5678.4 5700.9 5701.1 5750.9 5291.3 5299.3 5301.5 5307.9 5371.2 5594.1 5632.6 5637.0 5640.7 5778.2 5475.5 5479.3 5512.2 5527.2 5547.6 5573.1 5582.6 5585.6 5586.6 5590.7 5406.1 5439.9 5481.8 5510.3 5513.4 5550.1 5558.1 5560.7 5604.0 5627.4 4282.6 4321.8 4335.7 4339.9 4345.8 3848.9 3859.7 3878.6 3881.1 3901.1 5213.4 5270.1 5272.6 5282.5 5309.3 5603.9 5613.6 5618.9 5619.9 5621.4 5227.8 5293.8 5304.4 5322.3 5387.0 4813.6 4847.9 4849.5 4887.1 4926.2 5795.6 5795.6 5796.7 5799.4 5801.0 5395.8 5603.8 5663.1 5669.0 5672.1 5547.0 5748.6 5775.1 5776.0 5890.5 5559.0 5573.4 5581.2 5581.2 5584.7 5606.8 5677.1 5680.7 5684.2 5690.7 5377.4 5416.9 5434.3 5454.7 5521.1 5422.3 5472.2 5480.9 5485.7 5526.3 5464.6 5470.2 5500.8 5503.9 5561.6 5386.6 5429.8 5436.5 5440.4 5513.0 5400.3 5401.1 5402.6 5449.5 5463.6 19:38:43 INFO - PROCESS | 3605 | 19:38:43 INFO - PROCESS | 3605 | [#1] ext.html Cycles:180 Average:61706.18 Median:19475.29 stddev:133520.64 (685.6%) stddev-sans-first:133816.26 19:38:43 INFO - PROCESS | 3605 | Values: 553.6 588.9 593.0 598.4 624.5 31893.7 42522.8 43069.1 43175.6 43505.0 44974.3 44992.6 45339.0 45449.3 45521.1 8118.1 8943.5 9093.4 9182.9 9186.8 8176.6 8453.7 8455.1 10585.6 10831.4 10945.9 11052.4 11053.3 11053.3 11058.3 10771.2 10807.4 10829.9 10855.1 10868.7 10579.3 10657.6 10688.6 10741.8 10742.5 42605.0 43252.0 43380.3 43401.3 43421.8 3289.7 3334.3 3347.3 3597.9 3625.5 32320.0 32386.8 32476.0 32524.2 32536.0 4266.3 4458.1 4458.2 4571.7 4574.1 264370.0 265350.0 266920.0 267412.3 267558.0 33017.8 60164.3 60529.4 60559.7 60564.7 34629.0 35055.0 35176.3 35214.0 35278.1 15724.3 15962.8 15969.1 15970.4 15979.6 12138.5 13262.8 13387.9 13391.5 13396.0 8199.9 8365.6 8706.1 8756.3 8881.6 3315.3 3408.1 3415.1 3444.2 3456.2 987.1 996.4 1006.8 1086.1 1098.8 32175.4 32617.3 32624.8 32790.2 33229.2 1091.0 1094.3 1096.4 1113.1 1215.4 16707.4 17187.7 17335.0 17420.8 17540.8 554514.0 565981.0 566614.0 586106.0 923988.0 59068.1 59734.4 59897.4 59999.4 60021.4 1246.1 1271.0 1344.5 1382.2 1388.5 29245.6 32246.4 33397.8 35111.0 35352.6 56973.1 57517.4 57695.7 57709.4 57712.7 491136.0 492821.0 493341.0 494210.0 494521.0 28486.3 29201.7 29304.7 29629.7 30995.1 37229.6 41044.6 46953.0 48282.0 52856.5 41064.4 42034.8 42206.6 42217.4 44202.1 41147.6 41694.2 42030.8 42784.6 43457.6 40359.2 40866.8 41160.8 41726.0 44977.3 33556.7 37263.3 37407.6 37424.0 38167.2 28312.9 28679.4 28700.6 28725.4 29111.9 19:38:43 INFO - PROCESS | 3605 | 19:38:43 INFO - PROCESS | 3605 | [#2] jquery.html Cycles:180 Average:28383.84 Median:16031.07 stddev:78515.80 (489.8%) stddev-sans-first:78707.92 19:38:43 INFO - PROCESS | 3605 | Values: 387.5 391.1 400.8 405.4 424.1 14025.2 17793.6 18067.2 19604.6 19629.4 14938.0 15535.6 18247.9 18999.0 19282.4 5521.4 5675.1 5734.1 5735.7 5736.3 5478.9 5615.9 5651.1 5673.4 5684.5 5645.2 5688.8 5692.6 5698.6 5700.1 5400.4 5538.1 5658.7 5667.8 5670.5 4994.5 5001.5 5007.9 5011.4 5015.4 22279.3 22405.4 22466.2 22597.4 23517.2 2453.6 2460.5 2844.1 3024.1 3197.4 13894.0 14231.5 14279.9 14558.4 14941.9 1779.9 1785.7 1792.0 1836.8 1884.4 78172.0 81075.0 81385.6 82507.6 83191.0 10248.9 10420.2 10517.6 10753.4 10816.4 14590.6 14690.1 14695.4 14724.6 14770.6 7721.4 8093.6 8132.7 8334.2 8390.1 7996.7 8024.9 8105.5 8273.2 8315.6 5795.1 6175.1 6187.6 6504.7 6732.3 1681.2 1741.9 1771.6 1800.5 1803.9 947.0 955.5 973.6 1003.9 1008.9 18837.7 19062.0 19251.9 19527.0 20014.9 952.1 978.2 989.1 1013.5 1028.1 8919.5 10235.5 10903.8 10932.9 10960.7 471680.0 475018.0 477374.0 477712.0 478551.0 87526.6 90856.0 91402.5 91500.5 91941.0 999.2 1007.0 1053.7 1056.9 1059.1 16626.8 16830.6 17373.5 17862.7 18036.5 29038.0 34649.3 34870.3 39702.8 39799.8 55953.7 57405.7 57454.4 57632.7 58130.0 7676.9 8427.2 8524.0 8616.9 8761.6 12906.9 13282.3 13351.7 13435.1 13517.8 11275.9 11510.8 11617.3 11806.3 11861.1 11487.4 11509.3 11514.1 11812.2 11814.5 11668.5 11773.4 11828.8 11848.7 11888.2 7891.7 7939.0 8001.1 8226.7 8273.2 10662.0 11650.0 11842.6 11897.6 11990.6 19:38:43 INFO - PROCESS | 3605 | 19:38:43 INFO - PROCESS | 3605 | [#3] mootools.html Cycles:180 Average:5651.23 Median:5237.98 stddev:3173.31 (60.6%) stddev-sans-first:3166.45 19:38:43 INFO - PROCESS | 3605 | Values: 1441.5 1468.0 1608.9 1731.5 1762.2 7626.3 7922.8 8197.4 8913.2 8937.7 8228.1 8435.1 8435.5 8689.9 8710.1 2290.2 2295.2 2302.7 2325.7 2326.2 2013.6 2077.2 2216.1 2259.8 2261.1 2219.7 2250.6 2259.2 2259.3 2259.3 2205.9 2212.2 2252.3 2255.0 2255.0 2126.6 2202.4 2208.7 2224.3 2230.4 8689.3 8695.7 8732.3 8790.1 8801.8 5135.4 5283.4 5284.1 5286.9 5287.8 3208.5 3219.3 3323.3 3349.3 3371.2 1043.3 1103.2 1106.7 1124.1 1129.6 12017.2 12140.7 12145.0 12149.5 12163.0 4112.6 4354.0 4447.7 4497.3 4515.4 9602.2 9604.1 9607.9 9610.2 9611.7 5062.8 5077.5 5095.7 5108.4 5112.4 4593.0 4679.3 4740.9 4751.2 4761.0 3593.0 3611.3 3644.2 3679.1 3722.7 1018.4 1051.0 1111.3 1115.4 1239.2 805.5 847.2 986.7 987.3 989.9 5858.0 5981.5 5981.9 5984.6 5985.3 964.9 988.9 1020.6 1023.8 1045.1 3903.4 4249.3 4280.5 4284.5 4313.6 9979.5 10507.3 10521.3 10530.8 10688.1 10163.9 10172.7 10231.0 10267.3 10318.7 7760.0 7969.4 8088.1 8109.8 8118.6 6112.5 6209.3 6224.0 6242.4 6278.2 9675.5 9732.7 9736.4 9822.6 9893.5 9266.0 9674.9 9711.8 9917.2 9970.7 7630.0 7752.7 7780.6 7785.8 7819.9 8278.9 8283.3 8329.3 8332.3 8335.3 7425.7 7444.7 7444.8 7474.1 7662.5 7208.4 7383.3 7424.1 7429.1 7455.2 6732.3 6815.9 6820.6 6821.6 6822.6 5125.4 5225.2 5250.8 5260.7 5500.0 5213.5 5262.9 5269.3 5357.2 5478.7 19:38:43 INFO - PROCESS | 3605 | 19:38:43 INFO - PROCESS | 3605 | [#4] prototype.html Cycles:180 Average:4620.91 Median:5271.47 stddev:1347.13 (25.6%) stddev-sans-first:1311.71 19:38:43 INFO - PROCESS | 3605 | Values: 322.5 339.9 340.7 342.7 345.8 5103.4 5199.5 5225.4 5268.9 5317.7 5011.7 5158.9 5188.4 5202.1 5230.7 4197.0 4199.8 4200.5 4207.0 4251.8 3966.6 4107.8 4205.1 4205.4 4219.4 4073.3 4081.7 4087.8 4089.8 4094.0 4075.8 4077.8 4078.5 4089.0 4126.0 3636.2 3637.8 3643.9 3654.5 3660.1 5028.0 5069.3 5077.5 5082.3 5084.7 4596.8 4600.1 4620.0 4632.2 4633.1 5371.5 5386.5 5395.8 5408.9 5414.7 3749.9 3907.3 3907.7 3908.3 3922.5 5653.5 5656.9 5670.5 5680.9 5739.4 4082.6 4083.9 4093.2 4107.0 4109.2 4053.3 4100.4 4111.8 4118.2 4164.0 4621.3 4625.0 4637.4 4652.7 4668.4 5462.3 5474.5 5511.7 5516.3 5517.3 1324.9 1346.8 1361.5 1379.5 1392.3 954.5 956.5 965.6 973.0 980.2 5248.3 5253.5 5256.0 5256.4 5278.8 5537.8 5547.3 5554.3 5556.7 5579.3 5106.8 5261.5 5274.0 5280.2 5366.7 2961.5 2984.7 2986.3 2990.3 2995.7 5762.3 5771.0 5776.9 5785.7 5811.8 5656.3 5734.6 5755.9 5772.4 5776.2 5745.9 5748.3 5764.8 5769.6 5782.9 5393.2 5548.7 5644.3 5654.5 5665.4 5518.8 5528.9 5537.2 5563.4 5576.5 5769.6 5772.0 5796.8 5807.3 5827.2 3905.6 4020.6 4050.6 4061.2 4069.3 5451.4 5617.6 5637.2 5652.6 5676.3 5286.9 5426.2 5505.6 5517.1 5523.3 5432.3 5475.6 5490.5 5498.7 5506.3 5219.7 5413.3 5486.1 5487.2 5520.3 5456.9 5466.8 5481.3 5484.6 5495.9 5387.3 5410.4 5413.9 5449.5 5482.2 19:38:43 INFO - PROCESS | 3605 | 19:38:43 INFO - PROCESS | 3605 | [#5] yui.html Cycles:180 Average:20409.76 Median:4706.32 stddev:29142.35 (619.2%) stddev-sans-first:29186.67 19:38:43 INFO - PROCESS | 3605 | Values: 736.3 748.6 786.4 799.9 806.6 14361.9 15347.3 15378.9 15437.6 17001.8 15444.9 15493.2 15585.7 15613.4 15689.5 794.4 811.2 813.7 816.4 818.5 665.8 676.5 677.7 682.9 808.7 701.5 825.5 856.0 856.5 864.9 837.0 843.0 845.0 845.4 846.0 620.0 620.0 620.6 620.7 621.1 11125.5 11707.1 14590.4 15131.7 15150.8 14193.6 14901.6 15088.3 15221.5 15223.9 11372.9 11487.6 11619.0 11651.7 11653.1 3763.9 3766.0 3783.6 3826.6 3874.7 79261.6 79541.6 79640.0 80106.6 80659.2 10911.2 11051.6 11290.4 11306.2 11327.7 22636.3 22693.4 22725.1 22762.9 23459.5 7996.9 8182.3 8235.9 8289.8 8457.2 4570.8 4670.5 4721.5 4752.6 4914.2 3447.3 3539.3 3540.9 3541.0 3541.2 543.1 548.0 555.1 555.8 557.1 716.4 731.2 731.5 755.2 770.0 8935.4 9726.8 9791.5 9792.2 9909.2 637.9 671.7 693.8 725.3 737.3 4582.6 4671.0 4702.0 4710.6 4761.6 98205.0 101112.0 102478.0 113147.0 120476.0 96351.0 97917.0 97928.5 100287.0 100757.0 67328.7 79031.7 86056.0 87831.0 88120.6 8761.6 9155.5 9460.0 9554.4 9643.7 48726.9 49333.3 49706.0 50230.7 50577.2 70943.6 72495.6 74451.0 74573.0 75518.2 12956.3 13053.3 13405.5 13457.1 13539.6 13906.7 13907.7 14250.1 14910.6 14911.7 12475.4 12526.6 12541.7 13233.8 13350.8 12260.7 12913.7 13259.7 13306.8 13522.7 12985.7 13119.0 13263.1 13551.5 13744.9 10592.6 10597.7 10879.5 11151.4 11284.6 10381.2 10839.5 10902.1 10920.6 11054.7 19:38:43 INFO - PROCESS | 3605 | -------- Summary: end -------- 19:38:43 INFO - PROCESS | 3605 | 19:38:44 INFO - TEST-INFO | 3605: exit 0 19:38:44 INFO - TEST-OK | dromaeo_css | took 1116313ms 19:38:44 INFO - TEST-START | kraken 19:38:44 INFO - operating with platform_type : linux_ 19:38:44 INFO - Initialising browser for kraken test... 19:38:44 INFO - TEST-INFO | started process 3910 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV4V4Af/profile http://localhost:43207/getInfo.html) 19:38:48 INFO - PROCESS | 3910 | __metrics Screen width/height:1600/1200 19:38:48 INFO - PROCESS | 3910 | colorDepth:24 19:38:48 INFO - PROCESS | 3910 | Browser inner width/height: 1024/697 19:38:48 INFO - PROCESS | 3910 | __metrics 19:38:49 INFO - PROCESS | 3910 | 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" 19:38:49 INFO - PROCESS | 3910 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:38:49 INFO - PROCESS | 3910 | console.error: PushService: 19:38:49 INFO - PROCESS | 3910 | stateChangeProcessEnqueue: Error transitioning state 19:38:49 INFO - PROCESS | 3910 | UnknownError 19:38:50 INFO - TEST-INFO | 3910: exit 0 19:38:50 INFO - Browser initialized. 19:38:50 INFO - Running cycle 1/1 for kraken test... 19:38:50 INFO - TEST-INFO | started process 3991 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV4V4Af/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:39:02 INFO - PROCESS | 3991 | RSS: Main: 172511232 19:39:02 INFO - PROCESS | 3991 | 19:39:05 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43207/tests/kraken/driver.html?testName=audio-beat-detection) 19:39:06 INFO - PROCESS | 3991 | RSS: Main: 240664576 19:39:06 INFO - PROCESS | 3991 | 19:39:08 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43207/tests/kraken/driver.html?testName=audio-dft) 19:39:09 INFO - PROCESS | 3991 | RSS: Main: 217169920 19:39:09 INFO - PROCESS | 3991 | 19:39:12 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43207/tests/kraken/driver.html?testName=audio-fft) 19:39:12 INFO - PROCESS | 3991 | RSS: Main: 298188800 19:39:12 INFO - PROCESS | 3991 | 19:39:15 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43207/tests/kraken/driver.html?testName=audio-oscillator) 19:39:15 INFO - PROCESS | 3991 | RSS: Main: 207503360 19:39:15 INFO - PROCESS | 3991 | 19:39:17 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43207/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:39:18 INFO - PROCESS | 3991 | RSS: Main: 212701184 19:39:18 INFO - PROCESS | 3991 | 19:39:21 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43207/tests/kraken/driver.html?testName=imaging-darkroom) 19:39:22 INFO - PROCESS | 3991 | RSS: Main: 248651776 19:39:22 INFO - PROCESS | 3991 | 19:39:25 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43207/tests/kraken/driver.html?testName=imaging-desaturate) 19:39:26 INFO - PROCESS | 3991 | RSS: Main: 247386112 19:39:26 INFO - PROCESS | 3991 | 19:39:28 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43207/tests/kraken/driver.html?testName=json-parse-financial) 19:39:29 INFO - PROCESS | 3991 | RSS: Main: 248045568 19:39:29 INFO - PROCESS | 3991 | 19:39:30 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43207/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:39:31 INFO - PROCESS | 3991 | RSS: Main: 210063360 19:39:31 INFO - PROCESS | 3991 | 19:39:33 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43207/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:39:33 INFO - PROCESS | 3991 | RSS: Main: 217374720 19:39:33 INFO - PROCESS | 3991 | 19:39:35 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43207/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:39:36 INFO - PROCESS | 3991 | RSS: Main: 222625792 19:39:36 INFO - PROCESS | 3991 | 19:39:39 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43207/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:39:39 INFO - PROCESS | 3991 | RSS: Main: 223244288 19:39:39 INFO - PROCESS | 3991 | 19:39:42 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43207/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:39:43 INFO - PROCESS | 3991 | RSS: Main: 220475392 19:39:43 INFO - PROCESS | 3991 | 19:39:44 INFO - PROCESS | 3991 | Cycle 1(1): loaded http://localhost:43207/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43207/tests/kraken/driver.html?testName=ai-astar) 19:39:45 INFO - PROCESS | 3991 | RSS: Main: 272478208 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | __start_tp_report 19:39:45 INFO - PROCESS | 3991 | _x_x_mozilla_page_load 19:39:45 INFO - PROCESS | 3991 | _x_x_mozilla_page_load_details 19:39:45 INFO - PROCESS | 3991 | |i|pagename|runs| 19:39:45 INFO - PROCESS | 3991 | |0;ai-astar;110;113;112;112;112;113;113;112;113;112 19:39:45 INFO - PROCESS | 3991 | |1;audio-beat-detection;152;119;120;167;121;119;119;120;149;118 19:39:45 INFO - PROCESS | 3991 | |2;audio-dft;161;166;160;162;160;162;162;162;162;163 19:39:45 INFO - PROCESS | 3991 | |3;audio-fft;75;76;75;112;77;78;77;78;97;74 19:39:45 INFO - PROCESS | 3991 | |4;audio-oscillator;82;82;109;82;82;82;110;82;81;113 19:39:45 INFO - PROCESS | 3991 | |5;imaging-gaussian-blur;117;118;117;118;117;118;118;117;118;118 19:39:45 INFO - PROCESS | 3991 | |6;imaging-darkroom;145;146;146;145;145;146;146;145;145;147 19:39:45 INFO - PROCESS | 3991 | |7;imaging-desaturate;94;96;96;95;94;95;96;94;95;95 19:39:45 INFO - PROCESS | 3991 | |8;json-parse-financial;51;51;51;50;52;52;52;53;51;51 19:39:45 INFO - PROCESS | 3991 | |9;json-stringify-tinderbox;61;63;62;62;62;62;63;64;62;63 19:39:45 INFO - PROCESS | 3991 | |10;stanford-crypto-aes;82;85;78;87;78;79;88;78;86;80 19:39:45 INFO - PROCESS | 3991 | |11;stanford-crypto-ccm;137;124;134;123;122;124;130;123;171;125 19:39:45 INFO - PROCESS | 3991 | |12;stanford-crypto-pbkdf2;171;160;165;166;160;162;159;160;161;164 19:39:45 INFO - PROCESS | 3991 | |13;stanford-crypto-sha256-iterative;66;66;70;66;66;64;64;65;64;65 19:39:45 INFO - PROCESS | 3991 | __end_tp_report 19:39:45 INFO - PROCESS | 3991 | __start_cc_report 19:39:45 INFO - PROCESS | 3991 | _x_x_mozilla_cycle_collect,1620 19:39:45 INFO - PROCESS | 3991 | __end_cc_report 19:39:45 INFO - PROCESS | 3991 | __startTimestamp1474166385117__endTimestamp 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | ------- Summary: start ------- 19:39:45 INFO - PROCESS | 3991 | Number of tests: 14 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.50 stddev:0.92 (0.8%) stddev-sans-first:0.53 19:39:45 INFO - PROCESS | 3991 | Values: 110.0 113.0 112.0 112.0 112.0 113.0 113.0 112.0 113.0 112.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:120.50 stddev:18.26 (15.2%) stddev-sans-first:17.61 19:39:45 INFO - PROCESS | 3991 | Values: 152.0 119.0 120.0 167.0 121.0 119.0 119.0 120.0 149.0 118.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.70 (1.0%) stddev-sans-first:1.76 19:39:45 INFO - PROCESS | 3991 | Values: 161.0 166.0 160.0 162.0 160.0 162.0 162.0 162.0 162.0 163.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#3] audio-fft Cycles:10 Average:81.90 Median:77.00 stddev:12.49 (16.2%) stddev-sans-first:13.00 19:39:45 INFO - PROCESS | 3991 | Values: 75.0 76.0 75.0 112.0 77.0 78.0 77.0 78.0 97.0 74.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:82.00 stddev:13.95 (17.0%) stddev-sans-first:14.46 19:39:45 INFO - PROCESS | 3991 | Values: 82.0 82.0 109.0 82.0 82.0 82.0 110.0 82.0 81.0 113.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 19:39:45 INFO - PROCESS | 3991 | Values: 117.0 118.0 117.0 118.0 117.0 118.0 118.0 117.0 118.0 118.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 19:39:45 INFO - PROCESS | 3991 | Values: 145.0 146.0 146.0 145.0 145.0 146.0 146.0 145.0 145.0 147.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 19:39:45 INFO - PROCESS | 3991 | Values: 94.0 96.0 96.0 95.0 94.0 95.0 96.0 94.0 95.0 95.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:51.50 stddev:0.84 (1.6%) stddev-sans-first:0.88 19:39:45 INFO - PROCESS | 3991 | Values: 51.0 51.0 51.0 50.0 52.0 52.0 52.0 53.0 51.0 51.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#9] json-stringify-tinderbox Cycles:10 Average:62.40 Median:62.50 stddev:0.84 (1.3%) stddev-sans-first:0.73 19:39:45 INFO - PROCESS | 3991 | Values: 61.0 63.0 62.0 62.0 62.0 62.0 63.0 64.0 62.0 63.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:83.50 stddev:4.04 (4.8%) stddev-sans-first:4.28 19:39:45 INFO - PROCESS | 3991 | Values: 82.0 85.0 78.0 87.0 78.0 79.0 88.0 78.0 86.0 80.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#11] stanford-crypto-ccm Cycles:10 Average:131.30 Median:127.50 stddev:14.86 (11.7%) stddev-sans-first:15.62 19:39:45 INFO - PROCESS | 3991 | Values: 137.0 124.0 134.0 123.0 122.0 124.0 130.0 123.0 171.0 125.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.80 Median:163.00 stddev:3.74 (2.3%) stddev-sans-first:2.52 19:39:45 INFO - PROCESS | 3991 | Values: 171.0 160.0 165.0 166.0 160.0 162.0 159.0 160.0 161.0 164.0 19:39:45 INFO - PROCESS | 3991 | 19:39:45 INFO - PROCESS | 3991 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:1.78 (2.7%) stddev-sans-first:1.88 19:39:45 INFO - PROCESS | 3991 | Values: 66.0 66.0 70.0 66.0 66.0 64.0 64.0 65.0 64.0 65.0 19:39:45 INFO - PROCESS | 3991 | -------- Summary: end -------- 19:39:45 INFO - PROCESS | 3991 | 19:39:46 INFO - TEST-INFO | 3991: exit 0 19:39:46 INFO - TEST-OK | kraken | took 62007ms 19:39:46 INFO - SUITE-END | took 1178s 19:39:46 INFO - Completed test suite (00:19:38) 19:39:46 INFO - javascript benchmark 19:39:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3012.367402845403, 3026.6519350274725, 3039.7416219621336, 3058.9752432934965, 3077.415291094692, 5117.334746482952, 5173.560247378972, 5195.532402083812, 5278.47215278472, 5317.441668624797, 5210.140326846066, 5229.189475631283, 5272.657277276974, 5275.28783614212, 5281.9207711884355, 4803.135435621587, 4817.590504807095, 4856.927146092811, 4872.902541949159, 4877.390326209215, 4787.066522028206, 4885.8778530536665, 4902.485239049902, 4918.877028074291, 4929.753512324387, 4860.295257187688, 4874.634402419832, 4901.044296362211, 4920.8031678732805, 4965.28003439501, 4765.928511072337, 4766.213574760161, 4768.713877167381, 4785.808567657289, 4814.374131362935, 4726.031163611451, 4727.57451829337, 4729.574338309569, 4758.571728544413, 4786.234202527578, 5174.508421699932, 5201.765920533585, 5215.973920130413, 5221.71280579568, 5222.608304377187, 5444.945550544483, 5496.587755918322, 5568.9443105568835, 5599.132134519115, 5604.523615492688, 5643.153526970947, 5678.375378708339, 5700.857478563028, 5701.14482827575, 5750.942490575125, 5291.259223708684, 5299.311089558333, 5301.549368303698, 5307.920381194285, 5371.221172929982, 5594.13290939905, 5632.633878797865, 5637.041702910515, 5640.689762063043, 5778.219940308089, 5475.534579560782, 5479.287692599936, 5512.200730893998, 5527.226188333637, 5547.639403438844, 5573.136163894603, 5582.581306401955, 5585.553155747531, 5586.636868603528, 5590.748416321276, 5406.135018397038, 5439.945600544023, 5481.75332110056, 5510.338759348823, 5513.421090785409, 5550.139728342114, 5558.138488534284, 5560.7219639017885, 5604.0, 5627.437256274422, 4282.614564689131, 4321.827126914951, 4335.67482438812, 4339.913201735948, 4345.782710864444, 3848.8652897148463, 3859.710521710883, 3878.5733569307354, 3881.068543549528, 3901.1027463683513, 5213.374395072616, 5270.077736396115, 5272.578193744491, 5282.471752824764, 5309.33633295838, 5603.943960560424, 5613.550915926716, 5618.887622247532, 5619.915701264485, 5621.437856214346, 5227.764750586232, 5293.841184764463, 5304.416514183436, 5322.33470816148, 5387.030334539821, 4813.590844778233, 4847.878803029882, 4849.515048495193, 4887.144749668865, 4926.211806111005, 5795.565332599987, 5795.565332600072, 5796.739146738322, 5799.449052340021, 5800.970995145081, 5395.838124856261, 5603.80386686464, 5663.11977577687, 5669.036263835241, 5672.092465205547, 5547.0, 5748.568857335633, 5775.104858746901, 5775.960327140987, 5890.4698577128265, 5558.972205138948, 5573.442655734556, 5581.190727344593, 5581.218629391808, 5584.748686309207, 5606.831795046155, 5677.091665333692, 5680.68756218412, 5684.204211410489, 5690.715464226855, 5377.435369286325, 5416.945830541802, 5434.266374039454, 5454.672719636835, 5521.116621340566, 5422.267993820784, 5472.20653005304, 5480.862978425571, 5485.725713714219, 5526.281583394133, 5464.562834973272, 5470.234167216513, 5500.752466138953, 5503.9174412383045, 5561.555075594024, 5386.569074473954, 5429.837104886859, 5436.483534064258, 5440.401555828935, 5512.972435137799, 5400.324959380077, 5401.054815407367, 5402.648827826179, 5449.536789372789, 5463.617546771688], "value": 5179.205855546382, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.568216790903, 588.8998870192067, 593.0333556303225, 598.4225222660132, 624.5191202773863, 31893.68106318936, 42522.787386063064, 43069.13861722764, 43175.56824431755, 43505.0, 44974.32538511925, 44992.550074499246, 45339.0, 45449.31826022612, 45521.0895782085, 8118.10700822912, 8943.463392196472, 9093.40892841963, 9182.86225706615, 9186.770330741752, 8176.591170441484, 8453.70412035579, 8455.112213217615, 10585.629502967398, 10831.404272764994, 10945.890541094606, 11052.392118433483, 11053.28153670009, 11053.28153670013, 11058.336499810037, 10771.24601277911, 10807.4055926924, 10829.891701082966, 10855.131589472823, 10868.67393978182, 10579.31234469757, 10657.626983055634, 10688.572457101707, 10741.83887241692, 10742.516586753536, 42605.0, 43252.0, 43380.34929476061, 43401.34897976533, 43421.782891085655, 3289.703926646613, 3334.299797042624, 3347.313800670857, 3597.9280414391565, 3625.4743062255825, 32320.0, 32386.838065809756, 32476.0, 32524.186895327806, 32536.0, 4266.296061149907, 4458.08609235106, 4458.152950939344, 4571.679982401233, 4574.062317224994, 264370.0, 265350.0, 266920.0, 267412.2923239202, 267558.0, 33017.834910825775, 60164.2843929826, 60529.39470605326, 60559.697201514595, 60564.69717651384, 34629.0, 35055.0, 35176.296474070376, 35214.00680942097, 35278.11804704851, 15724.29240684172, 15962.840371596367, 15969.121698306704, 15970.441034563735, 15979.60050998711, 12138.453769580214, 13262.801057984138, 13387.933060334635, 13391.531296404579, 13396.0, 8199.877001844978, 8365.58172091393, 8706.085860984633, 8756.299496040301, 8881.644734210558, 3315.3203593263324, 3408.1309266136977, 3415.1462134466383, 3444.156181735491, 3456.170519075454, 987.09680642213, 996.3573495095623, 1006.7785087280932, 1086.1148164246115, 1098.8406681031206, 32175.35649287048, 32617.347653046807, 32624.83687581547, 32790.20979020979, 33229.16927076794, 1091.028984204059, 1094.3215206571977, 1096.3969816600838, 1113.0817075912405, 1215.4408971873038, 16707.415240466526, 17187.742183867253, 17335.0, 17420.77715622614, 17540.824591754175, 554514.0, 565981.0, 566614.0, 586106.0, 923988.0, 59068.11397829036, 59734.402655973754, 59897.40102599005, 59999.40000600025, 60021.399786002454, 1246.077902352271, 1270.9618711438857, 1344.4554955243143, 1382.156884300576, 1388.5001399496173, 29245.561316579842, 32246.35507289888, 33397.83301083479, 35111.0, 35352.64647353494, 56973.14540281816, 57517.42482575121, 57695.71152144212, 57709.42290577209, 57712.71143644255, 491136.0, 492821.0, 493341.0, 494210.0, 494521.0, 28486.287842804097, 29201.70798292075, 29304.7069529302, 29629.703702962695, 30995.0701478956, 37229.62770372262, 41044.58955410407, 46953.0, 48282.0, 52856.471435286694, 41064.384034238916, 42034.78982605068, 42206.577934221496, 42217.36673949832, 44202.11595768002, 41147.58852411557, 41694.16611667689, 42030.789846050575, 42784.57215427806, 43457.565424345354, 40359.1928161441, 40866.79566602148, 41160.79419602883, 41726.0, 44977.32534012058, 33556.651034427356, 37263.25473490569, 37407.625923740416, 37424.0, 38167.2366552673, 28312.906884371274, 28679.42641147207, 28700.569491458064, 28725.425491489634, 29111.854440728508], "value": 19461.20201021651, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.54850599052105, 391.14144452925837, 400.8236375994561, 405.42024904386733, 424.10302210824096, 14025.158490490578, 17793.555161120978, 18067.186976586054, 19604.60790784185, 19629.411117666474, 14938.0, 15535.611609709764, 18247.90876045618, 18999.0, 19282.421527354167, 5521.4478552144865, 5675.063614503602, 5734.111212762008, 5735.684537350444, 5736.311642602894, 5478.863028424282, 5615.943840561604, 5651.095824668054, 5673.404292549285, 5684.488396044355, 5645.15322701596, 5688.829335119953, 5692.633767895717, 5698.629589076718, 5700.087985922253, 5400.432954539795, 5538.0585300499015, 5658.7170641467965, 5667.829965101054, 5670.518005969477, 4994.450610432849, 5001.474845141244, 5007.899842003176, 5011.423686276052, 5015.448300686921, 22279.331620051424, 22405.43986400353, 22466.213682521196, 22597.435064123365, 23517.17689880846, 2453.570625140593, 2460.520198561281, 2844.061459718288, 3024.1381206356305, 3197.3533630180477, 13894.0, 14231.501897433644, 14279.857201427956, 14558.417663293456, 14941.925290373478, 1779.8576113910856, 1785.6785778559854, 1792.0, 1836.8438682711985, 1884.4158310923658, 78172.0, 81075.0, 81385.59307203486, 82507.5874620629, 83191.0, 10248.948755256326, 10420.166386689047, 10517.579296828042, 10753.408562529135, 10816.35101893873, 14590.63523411902, 14690.11859288446, 14695.41218351254, 14724.631884202767, 14770.556883293519, 7721.4481338583755, 8093.55485448306, 8132.6746930122135, 8334.249917507454, 8390.077091519928, 7996.68013279474, 8024.879626805603, 8105.513669179868, 8273.179900421897, 8315.625796839156, 5795.13073039039, 6175.104609831549, 6187.566870319124, 6504.674766261763, 6732.293109223461, 1681.159420289855, 1741.8519425848692, 1771.5836778357075, 1800.4778614201734, 1803.9098045097692, 946.9914541013799, 955.4553904274496, 973.5570315506427, 1003.9247056470793, 1008.9243306752023, 18837.739471263536, 19062.0, 19251.903740481208, 19527.0, 20014.89992550057, 952.0872298937587, 978.1587834462357, 989.099919073654, 1013.5287091502487, 1028.1209565821264, 8919.464832110094, 10235.539400726837, 10903.781924361469, 10932.945335273433, 10960.671179864617, 471680.0, 475018.0, 477374.0, 477712.0, 478551.0, 87526.56236718904, 90856.0, 91402.54298728597, 91500.54249728708, 91941.0, 999.205631522941, 1006.9848952265722, 1053.7471006958274, 1056.9471526423647, 1059.0891833023593, 16626.833731663013, 16830.57923551921, 17373.47879563615, 17862.732059018865, 18036.54908627295, 29038.0, 34649.30701386009, 34870.30259394849, 39702.801485992386, 39799.80100099481, 55953.72023139858, 57405.71297143488, 57454.42545574491, 57632.71183644223, 58130.0, 7676.923230767621, 8427.23028164068, 8523.95738021306, 8616.913830861862, 8761.649534018568, 12906.870931290567, 13282.335883205995, 13351.666208344868, 13435.059545831698, 13517.797233041709, 11275.887241127484, 11510.769784604428, 11617.302961822317, 11806.291622502677, 11861.110416718953, 11487.36819474937, 11509.251898626562, 11514.136439767219, 11812.173147879566, 11814.46834892449, 11668.474918628852, 11773.411329433666, 11828.76342473163, 11848.70378240551, 11888.227265227733, 7891.723789667334, 7938.960305198437, 8001.11973686195, 8226.712065077694, 8273.172682731678, 10662.0, 11650.0, 11842.585509507126, 11897.583584574844, 11990.640280791591], "value": 9200.98477672148, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.4594527052354, 1467.963300917477, 1608.887377883548, 1731.5151757507886, 1762.16297258802, 7626.31363177314, 7922.801929951754, 8197.385196110294, 8913.242374398176, 8937.731868043953, 8228.053773816013, 8435.114312997139, 8435.536045517494, 8689.869651955225, 8710.08544102868, 2290.198430549311, 2295.1737374545232, 2302.654601809725, 2325.6744055832196, 2326.2323433267065, 2013.577148798754, 2077.210659949225, 2216.1135545781653, 2259.830512711545, 2261.050358849287, 2219.7225346831647, 2250.5948929192737, 2259.1754009786473, 2259.2770313499686, 2259.344790010895, 2205.933821985335, 2212.214663794354, 2252.3017864462067, 2254.966175507369, 2254.9887250563806, 2126.627840127987, 2202.449387653087, 2208.7018252535863, 2224.3104637562406, 2230.4423894026495, 8689.3048556116, 8695.69565065226, 8732.345074119403, 8790.120987901222, 8801.779955501099, 5135.435102138762, 5283.418823929364, 5284.128118860383, 5286.867828304323, 5287.762050707727, 3208.454562724319, 3219.3239419721885, 3323.3443212466336, 3349.279904820475, 3371.2414706690797, 1043.2644985285356, 1103.150574057979, 1106.717786964327, 1124.0951034417292, 1129.610284451871, 12017.158798884082, 12140.696482588006, 12145.0, 12149.514019439212, 12163.02695784344, 4112.588741125864, 4354.0, 4447.688661793643, 4497.302918047708, 4515.435570553681, 9602.183814375856, 9604.135627793532, 9607.855882161773, 9610.183134433652, 9611.663591774255, 5062.772175252049, 5077.492250774893, 5095.694258344512, 5108.386993560796, 5112.437631860493, 4593.0, 4679.344891715163, 4740.881477963078, 4751.168545504523, 4760.976195119002, 3593.0, 3611.2777444511203, 3644.2347107107803, 3679.1170119171743, 3722.739408241397, 1018.4144117132678, 1051.0, 1111.3332000799455, 1115.4199816095586, 1239.2155296373194, 805.5488926201346, 847.2247893177673, 986.7187851462298, 987.2891518106951, 989.8960609136082, 5857.970710146507, 5981.491573216325, 5981.940180598225, 5984.551158663117, 5985.311689155716, 964.9469279189711, 988.9258305627106, 1020.5560581147224, 1023.8413045977885, 1045.132539991805, 3903.3559462688904, 4249.3413520904305, 4280.464941882265, 4284.507281662586, 4313.633341166036, 9979.451130187765, 10507.264491485523, 10521.316114452537, 10530.789384212272, 10688.091512221392, 10163.9491802542, 10172.694819155437, 10231.0, 10267.281290309607, 10318.742031449123, 7759.999999999887, 7969.402294827795, 8088.11030786613, 8109.797255068553, 8118.594070296462, 6112.510999120149, 6209.2548894133, 6224.0, 6242.375762423721, 6278.215223097113, 9675.467849268358, 9732.70801875945, 9736.367136136128, 9822.607095716092, 9893.455859927773, 9265.953670231605, 9674.9032509674, 9711.757206069622, 9917.206623469961, 9970.651027214013, 7630.0, 7752.65113069902, 7780.572068536285, 7785.805354865947, 7819.921800781919, 8278.917210828056, 8283.337333013224, 8329.250367466957, 8332.291755200877, 8335.333173346238, 7425.7029718810645, 7444.664990075568, 7444.776656700091, 7474.140473845578, 7662.463627546018, 7208.387287080657, 7383.261673832574, 7424.109106907205, 7429.07136607924, 7455.179930207564, 6732.293109223461, 6815.89776153368, 6820.556663816836, 6821.556598821061, 6822.556533825285, 5125.359330083739, 5225.190095535198, 5250.763715632882, 5260.658057226268, 5499.972500137474, 5213.478652134756, 5262.9210561842365, 5269.262303277467, 5357.196420536795, 5478.671279723229], "value": 4529.299587583269, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.4660978722995, 339.88613814372184, 340.7393344091769, 342.7292438973208, 345.78734078541686, 5103.413107492639, 5199.50604692554, 5225.372955245366, 5268.9473105268935, 5317.734113294329, 5011.674241174321, 5158.92261616076, 5188.429272779991, 5202.141646628311, 5230.712310822903, 4197.0, 4199.811008504609, 4200.537940826506, 4206.978965105185, 4251.787410629471, 3966.5636779954184, 4107.79461026949, 4205.095904380558, 4205.411242426063, 4219.375540416946, 4073.3279008963486, 4081.653059489947, 4087.795610219492, 4089.795510224492, 4093.9590604094024, 4075.816588253506, 4077.755334679914, 4078.4697989261354, 4089.0, 4125.958740412588, 3636.181859081712, 3637.8362973665985, 3643.872464463731, 3654.470101835219, 3660.103274697716, 5027.974860125676, 5069.264956581275, 5077.49225077493, 5082.33929589151, 5084.694918304914, 4596.839110631146, 4600.125976064537, 4620.0, 4632.239748895398, 4633.119707255644, 5371.4897084776885, 5386.542143917733, 5395.7841686332895, 5408.864778380533, 5414.6734050157565, 3749.8687545935763, 3907.339380763193, 3907.7335145615475, 3908.3160446921675, 3922.5096862892137, 5653.462921022497, 5656.8868622627315, 5670.5463562914865, 5680.869673872298, 5739.368669446316, 4082.5915059304493, 4083.8570650027104, 4093.16090201508, 4107.0, 4109.219248342865, 4053.310937140693, 4100.36444351126, 4111.753294802322, 4118.176364727066, 4164.021454958082, 4621.329907163443, 4624.97687511567, 4637.39713837199, 4652.720836749806, 4668.3697700810635, 5462.289902312754, 5474.534664553557, 5511.696856672921, 5516.255305533701, 5517.310336207974, 1324.9138805977773, 1346.8316460442445, 1361.4547488600313, 1379.489588852111, 1392.296890070508, 954.5275088830934, 956.5078477575005, 965.6234068713208, 972.9610815567298, 980.2305190425482, 5248.343957005374, 5253.530840504956, 5256.0, 5256.421793602701, 5278.788848446019, 5537.8061767837935, 5547.306586676666, 5554.305711786027, 5556.6943818089585, 5579.302587176603, 5106.821261255838, 5261.500157485033, 5274.0, 5280.1815718563685, 5366.7048312343195, 2961.540961151025, 2984.7313741763346, 2986.3430045390414, 2990.3122281874857, 2995.7453616442845, 5762.262925110623, 5770.970240623622, 5776.942230577724, 5785.739641716133, 5811.796587119515, 5656.349519805192, 5734.598578099576, 5755.942440575541, 5772.393898640582, 5776.227410842735, 5745.885082298414, 5748.298299799171, 5764.769409223584, 5769.624974376652, 5782.913256301074, 5393.164059570773, 5548.722563871709, 5644.331184729958, 5654.519365853784, 5665.376808550974, 5518.779248830002, 5528.889422211614, 5537.169424586345, 5563.388027316911, 5576.4664717203095, 5769.624974376652, 5771.971140144273, 5796.840631873726, 5807.303123625192, 5827.154078308296, 3905.5508616508328, 4020.557738648688, 4050.5949405060437, 4061.248668996245, 4069.308217603074, 5451.37309209446, 5617.634853734494, 5637.210790489253, 5652.604317697722, 5676.290463692038, 5286.933578524524, 5426.213199086189, 5505.642133261325, 5517.144842549411, 5523.2819733436, 5432.348118225838, 5475.561955043508, 5490.533304669148, 5498.670079795225, 5506.256655351476, 5219.721168313636, 5413.323334583177, 5486.067368547437, 5487.20435536837, 5520.337559492887, 5456.863578410572, 5466.840597963862, 5481.342238931354, 5484.643498172606, 5495.890082198413, 5387.272718182995, 5410.3507579090765, 5413.945860541502, 5449.485082122088, 5482.17767334903], "value": 4194.266097916642, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.318905012863, 748.610722424339, 786.3984052200071, 799.9000124984377, 806.632981993193, 14361.928190359047, 15347.3093710783, 15378.923105384485, 15437.614059648515, 17001.82998170018, 15444.922775386136, 15493.225338733075, 15585.688286234297, 15613.375464981416, 15689.529314120566, 794.3605397654885, 811.1523457986389, 813.6948644258404, 816.3510009542427, 818.5088946632033, 665.8135721997849, 676.5399528320739, 677.7492327838681, 682.911221541199, 808.6724876425028, 701.5019336271254, 825.4964471672275, 855.9828803423928, 856.4689892266804, 864.9135086491332, 836.9874451883227, 842.9789255268606, 845.0282175498227, 845.4377838737232, 846.0059430169526, 619.9721012554446, 620.0296535921314, 620.5842085802523, 620.6989610039127, 621.1335187413578, 11125.499352529074, 11707.063434925185, 14590.416383344653, 15131.697366052727, 15150.772738408823, 14193.574192774233, 14901.627459313495, 15088.321025553876, 15221.543353699406, 15223.923880380527, 11372.886271137266, 11487.597934072352, 11619.0, 11651.708707282301, 11653.126015548867, 3763.8870833875026, 3766.0025265838485, 3783.6027217142355, 3826.559945606235, 3874.728768986172, 79261.60369198174, 79541.60229198817, 79640.0, 80106.59946700287, 80659.19340806635, 10911.181661375429, 11051.557937682403, 11290.43547822622, 11306.16125066579, 11327.660170194908, 22636.320910372717, 22693.43266418353, 22725.09099635996, 22762.886185568965, 23459.530809384058, 7996.9200307997335, 8182.345412367115, 8235.917640823634, 8289.8342033159, 8457.23884850354, 4570.794314255866, 4670.509596492388, 4721.527847215251, 4752.596029337545, 4914.189158788831, 3447.3105378924315, 3539.2921415716446, 3540.946885796715, 3540.9645903540636, 3541.15012397028, 543.1093007467756, 547.9671219726829, 555.097965805566, 555.849920521465, 557.0557904352085, 716.3910675925422, 731.2285538756556, 731.491613328742, 755.2372104174823, 769.9749460471719, 8935.374523783274, 9726.818926088281, 9791.46146961924, 9792.21662267017, 9909.157721593601, 637.9013047075164, 671.664167916042, 693.760652574871, 725.3218240944733, 737.3463449395349, 4582.633389328846, 4670.95329046712, 4702.0, 4710.646701497333, 4761.595264402565, 98205.0, 101112.0, 102478.0, 113147.0, 120476.0, 96351.0, 97917.0, 97928.51035744775, 100286.99713002777, 100757.0, 67328.6633566829, 79031.67746580375, 86056.0, 87831.0, 88120.55939720261, 8761.561921904007, 9155.496447695441, 9460.0, 9554.378965367228, 9643.662471813454, 48726.85821705195, 49333.2600010993, 49706.00587988292, 50230.748846255534, 50577.24134138064, 70943.64528177326, 72495.63752181205, 74451.0, 74573.0, 75518.24481755332, 12956.28740419286, 13053.34733263352, 13405.530806421728, 13457.125286856322, 13539.59381218565, 13906.721865562835, 13907.721845563234, 14250.073745206528, 14910.627234318796, 14911.701765964403, 12475.376231188586, 12526.624201273977, 12541.74916501683, 13233.801492977806, 13350.799738004132, 12260.68497168719, 12913.677158071123, 13259.66850828737, 13306.800397993844, 13522.661933451742, 12985.675358116123, 13119.0, 13263.137896036727, 13551.525696600966, 13744.86255137436, 10592.576296948344, 10597.735056623646, 10879.51042203087, 11151.386673732699, 11284.605038823602, 10381.169506439315, 10839.512221950188, 10902.085784788394, 10920.617778377718, 11054.723631909266], "value": 7420.984979565527, "unit": "score"}], "name": "dromaeo_css", "value": 7124.322108142505, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 112.0, 112.0, 112.0, 113.0, 113.0, 112.0, 113.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 119.0, 120.0, 167.0, 121.0, 119.0, 119.0, 120.0, 149.0, 118.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 166.0, 160.0, 162.0, 160.0, 162.0, 162.0, 162.0, 162.0, 163.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 112.0, 77.0, 78.0, 77.0, 78.0, 97.0, 74.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 109.0, 82.0, 82.0, 82.0, 110.0, 82.0, 81.0, 113.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 118.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 147.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 95.0, 94.0, 95.0, 96.0, 94.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 50.0, 52.0, 52.0, 52.0, 53.0, 51.0, 51.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 62.0, 62.0, 62.0, 63.0, 64.0, 62.0, 63.0], "value": 62.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 78.0, 87.0, 78.0, 79.0, 88.0, 78.0, 86.0, 80.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 124.0, 134.0, 123.0, 122.0, 124.0, 130.0, 123.0, 171.0, 125.0], "value": 131.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 160.0, 165.0, 166.0, 160.0, 162.0, 159.0, 160.0, 161.0, 164.0], "value": 162.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 70.0, 66.0, 66.0, 64.0, 64.0, 65.0, 64.0, 65.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1490.7999999999997, "alertThreshold": 2.0}]} 19:39:46 INFO - Return code: 0 19:39:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:39:46 INFO - # TBPL SUCCESS # 19:39:46 INFO - Running post-action listener: _resource_record_post_action 19:39:46 INFO - [mozharness: 2016-09-18 02:39:46.938157Z] Finished run-tests step (success) 19:39:46 INFO - Running post-run listener: _resource_record_post_run 19:39:47 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 393433088; Read time: 16; Write time: 270284 19:39:47 INFO - TinderboxPrint: CPU usage
13.1% 19:39:47 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:39:47 INFO - TinderboxPrint: I/O write bytes / time
393,433,088 / 270,284 19:39:47 INFO - TinderboxPrint: CPU idle
8,293.6 (86.9%) 19:39:47 INFO - TinderboxPrint: CPU user
1,197.0 (12.5%) 19:39:47 INFO - TinderboxPrint: Swap in / out
0 / 0 19:39:47 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103620608; Read time: 0; Write time: 92204 19:39:47 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 289812480; Read time: 0; Write time: 178080 19:39:47 INFO - Running post-run listener: _upload_blobber_files 19:39:47 INFO - Blob upload gear active. 19:39:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:39:47 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 19:39:47 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'] 19:39:47 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 19:39:48 INFO - (blobuploader) - INFO - Open directory for files ... 19:39:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:39:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:39:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:49 INFO - (blobuploader) - INFO - Done attempting. 19:39:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:39:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:39:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:52 INFO - (blobuploader) - INFO - Done attempting. 19:39:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:39:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:39:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:39:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:39:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:39:53 INFO - (blobuploader) - INFO - Done attempting. 19:39:53 INFO - (blobuploader) - INFO - Iteration through files over. 19:39:53 INFO - Return code: 0 19:39:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:39:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:39:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4efc08ecd53a442f9855ffc40b35c1328b6b563b5eaa7fe67930a3bd3cb2c6bb027435f11eda0cd9d9f38003f967c92ee0b5a0fbca75786f06e67a7f4d8de653", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83f5ec63425c1efb8e060d835c1cd3550afb7b9c564d7bdb8f8e9677e544736f1a56c0a1943f395b3891e6921496de8bb156fb0415880d052f202f62f75fad05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4af2d952363442084ee7c44a3c3113ab68885323ec4625317c8c88d8de549a2975fc4935fe65310c26ab4025b06f62a8589a579c968a23446fac442e8eddf7f"} 19:39:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:39:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:39:53 INFO - Contents: 19:39:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4efc08ecd53a442f9855ffc40b35c1328b6b563b5eaa7fe67930a3bd3cb2c6bb027435f11eda0cd9d9f38003f967c92ee0b5a0fbca75786f06e67a7f4d8de653", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83f5ec63425c1efb8e060d835c1cd3550afb7b9c564d7bdb8f8e9677e544736f1a56c0a1943f395b3891e6921496de8bb156fb0415880d052f202f62f75fad05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4af2d952363442084ee7c44a3c3113ab68885323ec4625317c8c88d8de549a2975fc4935fe65310c26ab4025b06f62a8589a579c968a23446fac442e8eddf7f"} 19:39:53 INFO - Running post-run listener: copy_logs_to_upload_dir 19:39:53 INFO - Copying logs to upload dir... 19:39:53 INFO - mkdir: /builds/slave/test/build/upload/logs 19:39:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.915600 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-09-17 19:39:52.635149) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 19:39:52.639522) ========= 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=cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714 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/4efc08ecd53a442f9855ffc40b35c1328b6b563b5eaa7fe67930a3bd3cb2c6bb027435f11eda0cd9d9f38003f967c92ee0b5a0fbca75786f06e67a7f4d8de653", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83f5ec63425c1efb8e060d835c1cd3550afb7b9c564d7bdb8f8e9677e544736f1a56c0a1943f395b3891e6921496de8bb156fb0415880d052f202f62f75fad05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4af2d952363442084ee7c44a3c3113ab68885323ec4625317c8c88d8de549a2975fc4935fe65310c26ab4025b06f62a8589a579c968a23446fac442e8eddf7f"} build_url:https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011177 build_url: 'https://queue.taskcluster.net/v1/task/ctaFs_H3QXmMqLsvwrKzPg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4efc08ecd53a442f9855ffc40b35c1328b6b563b5eaa7fe67930a3bd3cb2c6bb027435f11eda0cd9d9f38003f967c92ee0b5a0fbca75786f06e67a7f4d8de653", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83f5ec63425c1efb8e060d835c1cd3550afb7b9c564d7bdb8f8e9677e544736f1a56c0a1943f395b3891e6921496de8bb156fb0415880d052f202f62f75fad05", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4af2d952363442084ee7c44a3c3113ab68885323ec4625317c8c88d8de549a2975fc4935fe65310c26ab4025b06f62a8589a579c968a23446fac442e8eddf7f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 19:39:52.671350) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 19:39:52.671885) ========= 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=cbe409c6de289db6ac707de3000001d4-1474162359.509982-2140160714 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 19:39:52.754713) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 19:39:52.755089) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 19:39:52.755406) ========= ========= Total master_lag: 0.20 =========