builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-029 starttime: 1474064167.47 results: success (0) buildid: 20160916135819 builduid: 89cfe8d4e0af4ef08e0511bac7b2d182 revision: f398b9d4696e2152d2c3040aa89073ff60e5955d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:07.472441) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:07.472897) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:07.473208) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:07.511386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:07.514719) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-16 15:16:08-- 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% 222M=0s 2016-09-16 15:16:08 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.156197 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:07.684496) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:07.687840) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014882 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:07.736957) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:07.737261) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f398b9d4696e2152d2c3040aa89073ff60e5955d --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 f398b9d4696e2152d2c3040aa89073ff60e5955d --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=9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-16 15:16:08,306 truncating revision to first 12 chars 2016-09-16 15:16:08,306 Setting DEBUG logging. 2016-09-16 15:16:08,306 attempt 1/10 2016-09-16 15:16:08,307 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f398b9d4696e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-16 15:16:08,876 unpacking tar archive at: mozilla-central-f398b9d4696e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.914122 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:08.683065) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:08.683328) ========= script_repo_revision: f398b9d4696e2152d2c3040aa89073ff60e5955d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:08.686695) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:08.686962) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 15:16:08.702306) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-09-16 15:16:08.702568) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:16:09 INFO - MultiFileLogger online at 20160916 15:16:09 in /builds/slave/test 15:16:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:16:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:16:09 INFO - {'append_to_log': False, 15:16:09 INFO - 'base_work_dir': '/builds/slave/test', 15:16:09 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:16:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:16:09 INFO - 'branch': 'Firefox-Non-PGO', 15:16:09 INFO - 'buildbot_json_path': 'buildprops.json', 15:16:09 INFO - 'config_files': ('talos/linux_config.py',), 15:16:09 INFO - 'default_actions': ('clobber', 15:16:09 INFO - 'read-buildbot-config', 15:16:09 INFO - 'download-and-extract', 15:16:09 INFO - 'populate-webroot', 15:16:09 INFO - 'create-virtualenv', 15:16:09 INFO - 'install', 15:16:09 INFO - 'run-tests'), 15:16:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:16:09 INFO - 'download_minidump_stackwalk': True, 15:16:09 INFO - 'download_symbols': 'ondemand', 15:16:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:16:09 INFO - 'tooltool.py': '/tools/tooltool.py', 15:16:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:16:09 INFO - '/tools/misc-python/virtualenv.py')}, 15:16:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:16:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:16:09 INFO - 'installer_path': 'installer.exe', 15:16:09 INFO - 'log_level': 'info', 15:16:09 INFO - 'log_name': 'talos', 15:16:09 INFO - 'log_to_console': True, 15:16:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:16:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:16:09 INFO - 'opt_config_files': (), 15:16:09 INFO - 'pip_index': False, 15:16:09 INFO - 'sps_profile': False, 15:16:09 INFO - 'sps_profile_interval': 0, 15:16:09 INFO - 'suite': 'dromaeojs', 15:16:09 INFO - 'system_bits': '32', 15:16:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:16:09 INFO - 'title': 'talos-linux64-ix-029', 15:16:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:16:09 INFO - 'use_talos_json': True, 15:16:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:16:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:16:09 INFO - 'work_dir': 'build'} 15:16:09 INFO - [mozharness: 2016-09-16 22:16:09.372271Z] Running clobber step. 15:16:09 INFO - Running pre-action listener: _resource_record_pre_action 15:16:09 INFO - Running main action method: clobber 15:16:09 INFO - rmtree: /builds/slave/test/build 15:16:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:16:09 INFO - Running post-action listener: _resource_record_post_action 15:16:09 INFO - [mozharness: 2016-09-16 22:16:09.840932Z] Finished clobber step (success) 15:16:09 INFO - [mozharness: 2016-09-16 22:16:09.841033Z] Running read-buildbot-config step. 15:16:09 INFO - Running pre-action listener: _resource_record_pre_action 15:16:09 INFO - Running main action method: read_buildbot_config 15:16:09 INFO - Using buildbot properties: 15:16:09 INFO - { 15:16:09 INFO - "project": "", 15:16:09 INFO - "product": "firefox", 15:16:09 INFO - "script_repo_revision": "production", 15:16:09 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:16:09 INFO - "repository": "", 15:16:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:16:09 INFO - "buildid": "20160916135819", 15:16:09 INFO - "pgo_build": "False", 15:16:09 INFO - "basedir": "/builds/slave/test", 15:16:09 INFO - "buildnumber": 741, 15:16:09 INFO - "slavename": "talos-linux64-ix-029", 15:16:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:16:09 INFO - "platform": "ubuntu64_hw", 15:16:09 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:16:09 INFO - "branch": "mozilla-central", 15:16:09 INFO - "repo_path": "mozilla-central", 15:16:09 INFO - "revision": "f398b9d4696e2152d2c3040aa89073ff60e5955d", 15:16:09 INFO - "stage_platform": "linux64", 15:16:09 INFO - "builduid": "89cfe8d4e0af4ef08e0511bac7b2d182", 15:16:09 INFO - "slavebuilddir": "test" 15:16:09 INFO - } 15:16:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 15:16:09 INFO - Running post-action listener: _resource_record_post_action 15:16:09 INFO - [mozharness: 2016-09-16 22:16:09.843466Z] Finished read-buildbot-config step (success) 15:16:09 INFO - [mozharness: 2016-09-16 22:16:09.843555Z] Running download-and-extract step. 15:16:09 INFO - Running pre-action listener: _resource_record_pre_action 15:16:09 INFO - Running main action method: download_and_extract 15:16:09 INFO - mkdir: /builds/slave/test/build/tests 15:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:09 INFO - trying https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:16:09 INFO - Downloading https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/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 15:16:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/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 15:16:10 INFO - Downloaded 1361 bytes. 15:16:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:16:10 INFO - Using the following test package requirements: 15:16:10 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:16:10 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:16:10 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:16:10 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:10 INFO - u'jsshell-linux-x86_64.zip'], 15:16:10 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:16:10 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:16:10 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:16:10 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:16:10 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:16:10 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:10 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:16:10 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:16:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:16:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:16:10 INFO - Fetch https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:16:11 INFO - Expected file size: 20365039 15:16:16 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 15:16:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:16:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:16:16 INFO - Fetch https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:16:16 INFO - Expected file size: 20365039 15:16:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:16:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:16:21 INFO - Fetch https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 15:16:22 INFO - Expected file size: 14414048 15:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:23 INFO - trying https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:16:23 INFO - Downloading https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/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 15:16:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/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 15:16:25 INFO - Downloaded 55596016 bytes. 15:16:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:16:25 INFO - mkdir: /builds/slave/test/properties 15:16:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:16:25 INFO - Writing to file /builds/slave/test/properties/build_url 15:16:25 INFO - Contents: 15:16:25 INFO - build_url:https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:16:26 INFO - Running post-action listener: _resource_record_post_action 15:16:26 INFO - Running post-action listener: set_extra_try_arguments 15:16:26 INFO - [mozharness: 2016-09-16 22:16:26.247698Z] Finished download-and-extract step (success) 15:16:26 INFO - [mozharness: 2016-09-16 22:16:26.247965Z] Running populate-webroot step. 15:16:26 INFO - Running pre-action listener: _resource_record_pre_action 15:16:26 INFO - Running main action method: populate_webroot 15:16:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:16:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:16:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:16:26 INFO - '--tppagecycles', 15:16:26 INFO - '7'], 15:16:26 INFO - 'tests': ['tsvgm']}}, 15:16:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:16:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:16:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:16:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:16:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:16:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:16:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:26 INFO - 'talos_options': ['--disable-e10s'], 15:16:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:26 INFO - 'talos_options': ['--disable-e10s'], 15:16:26 INFO - 'tests': ['damp', 'tps']}, 15:16:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:26 INFO - 'tests': ['damp', 'tps']}, 15:16:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:16:26 INFO - 'tests': ['dromaeo_dom']}, 15:16:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:16:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:16:26 INFO - 'tests': ['basic_compositor_video']}, 15:16:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:16:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:16:26 INFO - 'tests': ['a11yr', 15:16:26 INFO - 'ts_paint', 15:16:26 INFO - 'tpaint', 15:16:26 INFO - 'sessionrestore', 15:16:26 INFO - 'sessionrestore_no_auto_restore', 15:16:26 INFO - 'tabpaint']}, 15:16:26 INFO - 'other-e10s': {'tests': ['a11yr', 15:16:26 INFO - 'ts_paint', 15:16:26 INFO - 'tpaint', 15:16:26 INFO - 'sessionrestore', 15:16:26 INFO - 'sessionrestore_no_auto_restore', 15:16:26 INFO - 'tabpaint']}, 15:16:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:16:26 INFO - 'ts_paint', 15:16:26 INFO - 'tpaint', 15:16:26 INFO - 'sessionrestore', 15:16:26 INFO - 'sessionrestore_no_auto_restore', 15:16:26 INFO - 'tabpaint']}, 15:16:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:16:26 INFO - 'ts_paint', 15:16:26 INFO - 'tpaint', 15:16:26 INFO - 'sessionrestore', 15:16:26 INFO - 'sessionrestore_no_auto_restore', 15:16:26 INFO - 'tabpaint']}, 15:16:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:16:26 INFO - 'tests': ['a11yr', 15:16:26 INFO - 'ts_paint', 15:16:26 INFO - 'tpaint', 15:16:26 INFO - 'sessionrestore', 15:16:26 INFO - 'sessionrestore_no_auto_restore', 15:16:26 INFO - 'tabpaint']}, 15:16:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:16:26 INFO - 'tests': ['a11yr', 15:16:26 INFO - 'ts_paint', 15:16:26 INFO - 'tpaint', 15:16:26 INFO - 'sessionrestore', 15:16:26 INFO - 'sessionrestore_no_auto_restore', 15:16:26 INFO - 'tabpaint']}, 15:16:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:16:26 INFO - 'tests': ['tsvgx', 15:16:26 INFO - 'tsvgr_opacity', 15:16:26 INFO - 'tart', 15:16:26 INFO - 'tscrollx', 15:16:26 INFO - 'cart']}, 15:16:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:16:26 INFO - 'tsvgr_opacity', 15:16:26 INFO - 'tart', 15:16:26 INFO - 'tscrollx', 15:16:26 INFO - 'cart']}, 15:16:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:26 INFO - 'talos_options': ['--disable-e10s'], 15:16:26 INFO - 'tests': ['tp5o']}, 15:16:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:26 INFO - 'tests': ['tp5o']}, 15:16:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:26 INFO - 'talos_options': ['--disable-e10s', 15:16:26 INFO - '--xperf_path', 15:16:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:26 INFO - 'tests': ['tp5n']}, 15:16:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:26 INFO - 'talos_options': ['--xperf_path', 15:16:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:26 INFO - 'tests': ['tp5n']}}, 15:16:26 INFO - 'talos.zip': {'path': '', 15:16:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:16:26 INFO - Running post-action listener: _resource_record_post_action 15:16:26 INFO - [mozharness: 2016-09-16 22:16:26.268683Z] Finished populate-webroot step (success) 15:16:26 INFO - [mozharness: 2016-09-16 22:16:26.268770Z] Running create-virtualenv step. 15:16:26 INFO - Running pre-action listener: _resource_record_pre_action 15:16:26 INFO - Running main action method: create_virtualenv 15:16:26 INFO - Creating virtualenv /builds/slave/test/build/venv 15:16:26 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 15:16:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:16:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:16:26 INFO - Using real prefix '/usr' 15:16:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:16:26 INFO - Installing distribute.............................................................................................................................................................................................done. 15:16:28 INFO - Installing pip.................done. 15:16:28 INFO - Return code: 0 15:16:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:16:28 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')]} 15:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:28 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')]} 15:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:28 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 0x7f61f44d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19bfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bcf60>, '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 0x19bdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19be1d0>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', '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 15:16:28 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 15:16:28 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 15:16:28 INFO - Using env: {'DISPLAY': ':0', 15:16:28 INFO - 'HOME': '/home/cltbld', 15:16:28 INFO - 'LANG': 'en_US.UTF-8', 15:16:28 INFO - 'LANGUAGE': 'en_US:en', 15:16:28 INFO - 'LOGNAME': 'cltbld', 15:16:28 INFO - 'MAIL': '/var/mail/cltbld', 15:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:28 INFO - 'MOZ_NO_REMOTE': '1', 15:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:28 INFO - 'NO_EM_RESTART': '1', 15:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:28 INFO - 'PWD': '/builds/slave/test', 15:16:28 INFO - 'SHELL': '/bin/bash', 15:16:28 INFO - 'SHLVL': '1', 15:16:28 INFO - 'TERM': 'linux', 15:16:28 INFO - 'TMOUT': '86400', 15:16:28 INFO - 'USER': 'cltbld', 15:16:28 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', 15:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:28 INFO - '_': '/tools/buildbot/bin/python'} 15:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:29 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 15:16:29 INFO - Cleaning up... 15:16:29 INFO - Return code: 0 15:16:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:16:29 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')]} 15:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:29 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')]} 15:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:29 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 0x7f61f44d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19bfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bcf60>, '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 0x19bdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19be1d0>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', '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 15:16:29 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 15:16:29 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 15:16:29 INFO - Using env: {'DISPLAY': ':0', 15:16:29 INFO - 'HOME': '/home/cltbld', 15:16:29 INFO - 'LANG': 'en_US.UTF-8', 15:16:29 INFO - 'LANGUAGE': 'en_US:en', 15:16:29 INFO - 'LOGNAME': 'cltbld', 15:16:29 INFO - 'MAIL': '/var/mail/cltbld', 15:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:29 INFO - 'MOZ_NO_REMOTE': '1', 15:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:29 INFO - 'NO_EM_RESTART': '1', 15:16:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:29 INFO - 'PWD': '/builds/slave/test', 15:16:29 INFO - 'SHELL': '/bin/bash', 15:16:29 INFO - 'SHLVL': '1', 15:16:29 INFO - 'TERM': 'linux', 15:16:29 INFO - 'TMOUT': '86400', 15:16:29 INFO - 'USER': 'cltbld', 15:16:29 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', 15:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:29 INFO - '_': '/tools/buildbot/bin/python'} 15:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:29 INFO - Downloading/unpacking psutil>=3.1.1 15:16:29 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 15:16:29 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 15:16:29 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 15:16:29 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 15:16:29 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 15:16:29 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 15:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:16:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:31 INFO - Installing collected packages: psutil 15:16:31 INFO - Running setup.py install for psutil 15:16:31 INFO - building 'psutil._psutil_linux' extension 15:16:31 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 15:16:31 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 15:16:31 INFO - building 'psutil._psutil_posix' extension 15:16:31 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 15:16:31 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 15:16:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:31 INFO - Successfully installed psutil 15:16:31 INFO - Cleaning up... 15:16:31 INFO - Return code: 0 15:16:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:16: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')]} 15:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16: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')]} 15:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61f44d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19bfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bcf60>, '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 0x19bdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19be1d0>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', '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 15:16: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:16: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 mozsystemmonitor==0.3 15:16:31 INFO - Using env: {'DISPLAY': ':0', 15:16:31 INFO - 'HOME': '/home/cltbld', 15:16:31 INFO - 'LANG': 'en_US.UTF-8', 15:16:31 INFO - 'LANGUAGE': 'en_US:en', 15:16:31 INFO - 'LOGNAME': 'cltbld', 15:16:31 INFO - 'MAIL': '/var/mail/cltbld', 15:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:31 INFO - 'MOZ_NO_REMOTE': '1', 15:16:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:31 INFO - 'NO_EM_RESTART': '1', 15:16:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:31 INFO - 'PWD': '/builds/slave/test', 15:16:31 INFO - 'SHELL': '/bin/bash', 15:16:31 INFO - 'SHLVL': '1', 15:16:31 INFO - 'TERM': 'linux', 15:16:31 INFO - 'TMOUT': '86400', 15:16:31 INFO - 'USER': 'cltbld', 15:16:31 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', 15:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:31 INFO - '_': '/tools/buildbot/bin/python'} 15:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:16:32 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 15:16:32 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 15:16:32 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 15:16:32 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 15:16:32 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 15:16:32 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 15:16:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:16:35 INFO - Installing collected packages: mozsystemmonitor 15:16:35 INFO - Running setup.py install for mozsystemmonitor 15:16:35 INFO - Successfully installed mozsystemmonitor 15:16:35 INFO - Cleaning up... 15:16:35 INFO - Return code: 0 15:16:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:16:35 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')]} 15:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:35 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')]} 15:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:35 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 0x7f61f44d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19bfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bcf60>, '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 0x19bdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19be1d0>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', '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 15:16:35 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 15:16:35 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 15:16:35 INFO - Using env: {'DISPLAY': ':0', 15:16:35 INFO - 'HOME': '/home/cltbld', 15:16:35 INFO - 'LANG': 'en_US.UTF-8', 15:16:35 INFO - 'LANGUAGE': 'en_US:en', 15:16:35 INFO - 'LOGNAME': 'cltbld', 15:16:35 INFO - 'MAIL': '/var/mail/cltbld', 15:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:35 INFO - 'MOZ_NO_REMOTE': '1', 15:16:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:35 INFO - 'NO_EM_RESTART': '1', 15:16:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:35 INFO - 'PWD': '/builds/slave/test', 15:16:35 INFO - 'SHELL': '/bin/bash', 15:16:35 INFO - 'SHLVL': '1', 15:16:35 INFO - 'TERM': 'linux', 15:16:35 INFO - 'TMOUT': '86400', 15:16:35 INFO - 'USER': 'cltbld', 15:16:35 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', 15:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:35 INFO - '_': '/tools/buildbot/bin/python'} 15:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:35 INFO - Downloading/unpacking jsonschema==2.5.1 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:16:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:16:37 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 15:16:37 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 15:16:37 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 15:16:37 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 15:16:37 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 15:16:37 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 15:16:37 INFO - Downloading functools32-3.2.3-2.tar.gz 15:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:16:37 INFO - warning: no files found matching '*.txt' 15:16:37 INFO - no previously-included directories found matching 'build' 15:16:37 INFO - no previously-included directories found matching 'dist' 15:16:37 INFO - no previously-included directories found matching '.git*' 15:16:37 INFO - Installing collected packages: jsonschema, functools32 15:16:37 INFO - Running setup.py install for functools32 15:16:37 INFO - warning: no files found matching '*.txt' 15:16:37 INFO - no previously-included directories found matching 'build' 15:16:37 INFO - no previously-included directories found matching 'dist' 15:16:37 INFO - no previously-included directories found matching '.git*' 15:16:37 INFO - Successfully installed jsonschema functools32 15:16:37 INFO - Cleaning up... 15:16:37 INFO - Return code: 0 15:16:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:16:37 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')]} 15:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:37 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')]} 15:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:37 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 0x7f61f44d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19bfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bcf60>, '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 0x19bdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19be1d0>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', '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 15:16:37 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 15:16:37 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 15:16:37 INFO - Using env: {'DISPLAY': ':0', 15:16:37 INFO - 'HOME': '/home/cltbld', 15:16:37 INFO - 'LANG': 'en_US.UTF-8', 15:16:37 INFO - 'LANGUAGE': 'en_US:en', 15:16:37 INFO - 'LOGNAME': 'cltbld', 15:16:37 INFO - 'MAIL': '/var/mail/cltbld', 15:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:37 INFO - 'MOZ_NO_REMOTE': '1', 15:16:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:37 INFO - 'NO_EM_RESTART': '1', 15:16:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:37 INFO - 'PWD': '/builds/slave/test', 15:16:37 INFO - 'SHELL': '/bin/bash', 15:16:37 INFO - 'SHLVL': '1', 15:16:37 INFO - 'TERM': 'linux', 15:16:37 INFO - 'TMOUT': '86400', 15:16:37 INFO - 'USER': 'cltbld', 15:16:37 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', 15:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:37 INFO - '_': '/tools/buildbot/bin/python'} 15:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:16:37 INFO - Cleaning up... 15:16:37 INFO - Return code: 0 15:16:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:16:37 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')]} 15:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:37 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')]} 15:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:37 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 0x7f61f44d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19bfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bcf60>, '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 0x19bdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19be1d0>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', '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 15:16:37 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 15:16:37 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 15:16:37 INFO - Using env: {'DISPLAY': ':0', 15:16:37 INFO - 'HOME': '/home/cltbld', 15:16:37 INFO - 'LANG': 'en_US.UTF-8', 15:16:37 INFO - 'LANGUAGE': 'en_US:en', 15:16:37 INFO - 'LOGNAME': 'cltbld', 15:16:37 INFO - 'MAIL': '/var/mail/cltbld', 15:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:37 INFO - 'MOZ_NO_REMOTE': '1', 15:16:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:37 INFO - 'NO_EM_RESTART': '1', 15:16:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:37 INFO - 'PWD': '/builds/slave/test', 15:16:37 INFO - 'SHELL': '/bin/bash', 15:16:37 INFO - 'SHLVL': '1', 15:16:37 INFO - 'TERM': 'linux', 15:16:37 INFO - 'TMOUT': '86400', 15:16:37 INFO - 'USER': 'cltbld', 15:16:37 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', 15:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:37 INFO - '_': '/tools/buildbot/bin/python'} 15:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:38 INFO - Downloading/unpacking blobuploader==1.2.4 15:16: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 15:16: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 15:16: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 15:16: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 15:16: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 15:16: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 15:16:40 INFO - Downloading blobuploader-1.2.4.tar.gz 15:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:16:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:16:40 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 15:16:40 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 15:16:40 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 15:16:40 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 15:16:40 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 15:16:40 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 15:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:16:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:16:40 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 15:16:40 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 15:16:40 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 15:16:40 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 15:16:40 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 15:16:40 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 15:16:40 INFO - Downloading docopt-0.6.1.tar.gz 15:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:16:40 INFO - Installing collected packages: blobuploader, requests, docopt 15:16:40 INFO - Running setup.py install for blobuploader 15:16:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:16:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:16:40 INFO - Running setup.py install for requests 15:16:41 INFO - Running setup.py install for docopt 15:16:41 INFO - Successfully installed blobuploader requests docopt 15:16:41 INFO - Cleaning up... 15:16:41 INFO - Return code: 0 15:16:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:41 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')]} 15:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:41 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')]} 15:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:41 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 0x7f61f44d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19bfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bcf60>, '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 0x19bdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19be1d0>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', '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 15:16:41 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 15:16:41 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 15:16:41 INFO - Using env: {'DISPLAY': ':0', 15:16:41 INFO - 'HOME': '/home/cltbld', 15:16:41 INFO - 'LANG': 'en_US.UTF-8', 15:16:41 INFO - 'LANGUAGE': 'en_US:en', 15:16:41 INFO - 'LOGNAME': 'cltbld', 15:16:41 INFO - 'MAIL': '/var/mail/cltbld', 15:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:41 INFO - 'MOZ_NO_REMOTE': '1', 15:16:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:41 INFO - 'NO_EM_RESTART': '1', 15:16:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:41 INFO - 'PWD': '/builds/slave/test', 15:16:41 INFO - 'SHELL': '/bin/bash', 15:16:41 INFO - 'SHLVL': '1', 15:16:41 INFO - 'TERM': 'linux', 15:16:41 INFO - 'TMOUT': '86400', 15:16:41 INFO - 'USER': 'cltbld', 15:16:41 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', 15:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:41 INFO - '_': '/tools/buildbot/bin/python'} 15:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:41 INFO - Running setup.py (path:/tmp/pip-xH7UQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:41 INFO - Running setup.py (path:/tmp/pip-pgozhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:41 INFO - Running setup.py (path:/tmp/pip-WqBxdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:41 INFO - Running setup.py (path:/tmp/pip-OWZI_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:41 INFO - Running setup.py (path:/tmp/pip-jGQy_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:41 INFO - Running setup.py (path:/tmp/pip-kyg1EQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:42 INFO - Running setup.py (path:/tmp/pip-H97FYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:42 INFO - Running setup.py (path:/tmp/pip-TRt_8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:42 INFO - Running setup.py (path:/tmp/pip-PPAJEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:42 INFO - Running setup.py (path:/tmp/pip-lMF9wU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:42 INFO - Running setup.py (path:/tmp/pip-FSJT7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:42 INFO - Running setup.py (path:/tmp/pip-IB1xUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:42 INFO - Running setup.py (path:/tmp/pip-ZW5d5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:42 INFO - Running setup.py (path:/tmp/pip-ZSWRcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:42 INFO - Running setup.py (path:/tmp/pip-rFTBVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:42 INFO - Running setup.py (path:/tmp/pip-WOf_UN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:42 INFO - Running setup.py (path:/tmp/pip-1UQb_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:16:42 INFO - Running setup.py install for manifestparser 15:16:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:16:42 INFO - Running setup.py install for mozcrash 15:16:43 INFO - Running setup.py install for mozdebug 15:16:43 INFO - Running setup.py install for mozdevice 15:16:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:16:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:16:43 INFO - Running setup.py install for mozfile 15:16:43 INFO - Running setup.py install for mozhttpd 15:16:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:16:43 INFO - Running setup.py install for mozinfo 15:16:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:16:43 INFO - Running setup.py install for mozInstall 15:16:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:16:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:16:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:16:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:16:43 INFO - Running setup.py install for mozleak 15:16:43 INFO - Running setup.py install for mozlog 15:16:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:16:43 INFO - Running setup.py install for moznetwork 15:16:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:16:44 INFO - Running setup.py install for mozprocess 15:16:44 INFO - Running setup.py install for mozprofile 15:16:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:16:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:16:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:16:44 INFO - Running setup.py install for mozrunner 15:16:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:16:44 INFO - Running setup.py install for mozscreenshot 15:16:44 INFO - Running setup.py install for moztest 15:16:44 INFO - Running setup.py install for mozversion 15:16:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:16:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:16:44 INFO - Cleaning up... 15:16:44 INFO - Return code: 0 15:16:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:44 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')]} 15:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:44 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')]} 15:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:44 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 0x7f61f44d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19bfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bcf60>, '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 0x19bdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19be1d0>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', '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 15:16:44 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 15:16:44 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 15:16:44 INFO - Using env: {'DISPLAY': ':0', 15:16:44 INFO - 'HOME': '/home/cltbld', 15:16:44 INFO - 'LANG': 'en_US.UTF-8', 15:16:44 INFO - 'LANGUAGE': 'en_US:en', 15:16:44 INFO - 'LOGNAME': 'cltbld', 15:16:44 INFO - 'MAIL': '/var/mail/cltbld', 15:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:44 INFO - 'MOZ_NO_REMOTE': '1', 15:16:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:44 INFO - 'NO_EM_RESTART': '1', 15:16:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:44 INFO - 'PWD': '/builds/slave/test', 15:16:44 INFO - 'SHELL': '/bin/bash', 15:16:44 INFO - 'SHLVL': '1', 15:16:44 INFO - 'TERM': 'linux', 15:16:44 INFO - 'TMOUT': '86400', 15:16:44 INFO - 'USER': 'cltbld', 15:16:44 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', 15:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:44 INFO - '_': '/tools/buildbot/bin/python'} 15:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:44 INFO - Running setup.py (path:/tmp/pip-AAZvNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:44 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)) 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:44 INFO - Running setup.py (path:/tmp/pip-2xd9oT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:45 INFO - Running setup.py (path:/tmp/pip-XjtZnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:45 INFO - Running setup.py (path:/tmp/pip-vh__sH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:45 INFO - Running setup.py (path:/tmp/pip-vDH83r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:45 INFO - Running setup.py (path:/tmp/pip-644kyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:45 INFO - Running setup.py (path:/tmp/pip-0m2zjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:45 INFO - Running setup.py (path:/tmp/pip-LOvg6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:45 INFO - Running setup.py (path:/tmp/pip-p3Mdst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:45 INFO - Running setup.py (path:/tmp/pip-jX3QC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:45 INFO - Running setup.py (path:/tmp/pip-hNWJ4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:45 INFO - Running setup.py (path:/tmp/pip-OLAfnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:45 INFO - Running setup.py (path:/tmp/pip-yV2sS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:45 INFO - Running setup.py (path:/tmp/pip-eXNQ6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:45 INFO - Running setup.py (path:/tmp/pip-qMp3Z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:46 INFO - Running setup.py (path:/tmp/pip-J5KtBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:46 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)) 15:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:46 INFO - Running setup.py (path:/tmp/pip-SOispW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:46 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)) 15:16:46 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)) 15:16:46 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)) 15:16:46 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)) 15:16:46 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)) 15:16:46 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)) 15:16:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:16:46 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 15:16:46 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 15:16:46 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 15:16:46 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 15:16:46 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 15:16:46 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 15:16:48 INFO - Downloading blessings-1.6.tar.gz 15:16:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:16:48 INFO - Installing collected packages: blessings 15:16:48 INFO - Running setup.py install for blessings 15:16:48 INFO - Successfully installed blessings 15:16:48 INFO - Cleaning up... 15:16:48 INFO - Return code: 0 15:16:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:16:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:48 INFO - Reading from file tmpfile_stdout 15:16:48 INFO - Current package versions: 15:16:48 INFO - argparse == 1.2.1 15:16:48 INFO - blessings == 1.6 15:16:48 INFO - blobuploader == 1.2.4 15:16:48 INFO - docopt == 0.6.1 15:16:48 INFO - functools32 == 3.2.3-2 15:16:48 INFO - jsonschema == 2.5.1 15:16:48 INFO - manifestparser == 1.1 15:16:48 INFO - mozInstall == 1.12 15:16:48 INFO - mozcrash == 0.17 15:16:48 INFO - mozdebug == 0.1 15:16:48 INFO - mozdevice == 0.48 15:16:48 INFO - mozfile == 1.2 15:16:48 INFO - mozhttpd == 0.7 15:16:48 INFO - mozinfo == 0.9 15:16:48 INFO - mozleak == 0.1 15:16:48 INFO - mozlog == 3.4 15:16:48 INFO - moznetwork == 0.27 15:16:48 INFO - mozprocess == 0.23 15:16:48 INFO - mozprofile == 0.28 15:16:48 INFO - mozrunner == 6.12 15:16:48 INFO - mozscreenshot == 0.1 15:16:48 INFO - mozsystemmonitor == 0.3 15:16:48 INFO - moztest == 0.7 15:16:48 INFO - mozversion == 1.4 15:16:48 INFO - psutil == 3.1.1 15:16:48 INFO - requests == 1.2.3 15:16:48 INFO - wsgiref == 0.1.2 15:16:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:48 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')]} 15:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:48 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')]} 15:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:48 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 0x7f61f44d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19bfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bcf60>, '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 0x19bdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19be1d0>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', '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 15:16:48 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 15:16:48 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 15:16:48 INFO - Using env: {'DISPLAY': ':0', 15:16:48 INFO - 'HOME': '/home/cltbld', 15:16:48 INFO - 'LANG': 'en_US.UTF-8', 15:16:48 INFO - 'LANGUAGE': 'en_US:en', 15:16:48 INFO - 'LOGNAME': 'cltbld', 15:16:48 INFO - 'MAIL': '/var/mail/cltbld', 15:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:48 INFO - 'MOZ_NO_REMOTE': '1', 15:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:48 INFO - 'NO_EM_RESTART': '1', 15:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:48 INFO - 'PWD': '/builds/slave/test', 15:16:48 INFO - 'SHELL': '/bin/bash', 15:16:48 INFO - 'SHLVL': '1', 15:16:48 INFO - 'TERM': 'linux', 15:16:48 INFO - 'TMOUT': '86400', 15:16:48 INFO - 'USER': 'cltbld', 15:16:48 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', 15:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:48 INFO - '_': '/tools/buildbot/bin/python'} 15:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 INFO - Cleaning up... 15:16:48 INFO - Return code: 0 15:16:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:16:48 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')]} 15:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:48 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')]} 15:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x7f61f44d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19bfca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bcf60>, '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 0x19bdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19be1d0>, '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': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', '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 15:16:48 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 15:16:48 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 15:16:48 INFO - Using env: {'DISPLAY': ':0', 15:16:48 INFO - 'HOME': '/home/cltbld', 15:16:48 INFO - 'LANG': 'en_US.UTF-8', 15:16:48 INFO - 'LANGUAGE': 'en_US:en', 15:16:48 INFO - 'LOGNAME': 'cltbld', 15:16:48 INFO - 'MAIL': '/var/mail/cltbld', 15:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:48 INFO - 'MOZ_NO_REMOTE': '1', 15:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:48 INFO - 'NO_EM_RESTART': '1', 15:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:48 INFO - 'PWD': '/builds/slave/test', 15:16:48 INFO - 'SHELL': '/bin/bash', 15:16:48 INFO - 'SHLVL': '1', 15:16:48 INFO - 'TERM': 'linux', 15:16:48 INFO - 'TMOUT': '86400', 15:16:48 INFO - 'USER': 'cltbld', 15:16:48 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', 15:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:48 INFO - '_': '/tools/buildbot/bin/python'} 15:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:16:48 INFO - Cleaning up... 15:16:48 INFO - Return code: 0 15:16:48 INFO - Running post-action listener: _resource_record_post_action 15:16:48 INFO - Running post-action listener: _start_resource_monitoring 15:16:48 INFO - Starting resource monitoring. 15:16:48 INFO - [mozharness: 2016-09-16 22:16:48.992917Z] Finished create-virtualenv step (success) 15:16:48 INFO - [mozharness: 2016-09-16 22:16:48.993365Z] Running install step. 15:16:48 INFO - Running pre-action listener: _resource_record_pre_action 15:16:48 INFO - Running main action method: install 15:16:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:49 INFO - Reading from file tmpfile_stdout 15:16:49 INFO - Detecting whether we're running mozinstall >=1.0... 15:16:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:16:49 INFO - Reading from file tmpfile_stdout 15:16:49 INFO - Output received: 15:16:49 INFO - Usage: mozinstall [options] installer 15:16:49 INFO - Options: 15:16:49 INFO - -h, --help show this help message and exit 15:16:49 INFO - -d DEST, --destination=DEST 15:16:49 INFO - Directory to install application into. [default: 15:16:49 INFO - "/builds/slave/test"] 15:16:49 INFO - --app=APP Application being installed. [default: firefox] 15:16:49 INFO - mkdir: /builds/slave/test/build/application 15:16:49 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'] 15:16:49 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 15:17:05 INFO - Reading from file tmpfile_stdout 15:17:05 INFO - Output received: 15:17:05 INFO - /builds/slave/test/build/application/firefox/firefox 15:17:05 INFO - Running post-action listener: _resource_record_post_action 15:17:05 INFO - [mozharness: 2016-09-16 22:17:05.340381Z] Finished install step (success) 15:17:05 INFO - [mozharness: 2016-09-16 22:17:05.340625Z] Running run-tests step. 15:17:05 INFO - Running pre-action listener: _resource_record_pre_action 15:17:05 INFO - Running main action method: run_tests 15:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:17:05 INFO - Python 2.7.3 15:17:05 INFO - Return code: 0 15:17:05 INFO - grabbing minidump binary from tooltool 15:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:17:05 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 0x19bcf60>, '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 0x19bdd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19be1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:17:05 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 15:17:05 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 15:17:05 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 15:17:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:17:05 INFO - Return code: 0 15:17:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:17:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:17:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:17:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:17:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 15:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 15:17:05 INFO - Using env: {'DISPLAY': ':0', 15:17:05 INFO - 'HOME': '/home/cltbld', 15:17:05 INFO - 'LANG': 'en_US.UTF-8', 15:17:05 INFO - 'LANGUAGE': 'en_US:en', 15:17:05 INFO - 'LOGNAME': 'cltbld', 15:17:05 INFO - 'MAIL': '/var/mail/cltbld', 15:17:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:17:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:05 INFO - 'MOZ_NO_REMOTE': '1', 15:17:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:17:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:05 INFO - 'NO_EM_RESTART': '1', 15:17:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:05 INFO - 'PWD': '/builds/slave/test', 15:17:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:17:05 INFO - 'SHELL': '/bin/bash', 15:17:05 INFO - 'SHLVL': '1', 15:17:05 INFO - 'TERM': 'linux', 15:17:05 INFO - 'TMOUT': '86400', 15:17:05 INFO - 'USER': 'cltbld', 15:17:05 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557', 15:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:05 INFO - '_': '/tools/buildbot/bin/python'} 15:17:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 15:17:05 INFO - mozversion application_buildid: 20160916135819 15:17:05 INFO - mozversion application_changeset: f398b9d4696e2152d2c3040aa89073ff60e5955d 15:17:05 INFO - mozversion application_display_name: Nightly 15:17:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:17:05 INFO - mozversion application_name: Firefox 15:17:05 INFO - mozversion application_remotingname: firefox 15:17:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:17:05 INFO - mozversion application_vendor: Mozilla 15:17:05 INFO - mozversion application_version: 51.0a1 15:17:05 INFO - mozversion platform_buildid: 20160916135819 15:17:05 INFO - mozversion platform_changeset: f398b9d4696e2152d2c3040aa89073ff60e5955d 15:17:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:17:05 INFO - mozversion platform_version: 51.0a1 15:17:05 INFO - using testdate: 1474064225 15:17:05 INFO - actual date: 1474064225 15:17:05 INFO - starting webserver on 'localhost:58743' 15:17:05 INFO - SUITE-START | Running 2 tests 15:17:05 INFO - TEST-START | dromaeo_css 15:17:05 INFO - operating with platform_type : linux_ 15:17:05 INFO - Initialising browser for dromaeo_css test... 15:17:05 INFO - TEST-INFO | started process 17785 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvxMtJ6/profile http://localhost:58743/getInfo.html) 15:17:10 INFO - PROCESS | 17785 | __metrics Screen width/height:1600/1200 15:17:10 INFO - PROCESS | 17785 | colorDepth:24 15:17:10 INFO - PROCESS | 17785 | Browser inner width/height: 1024/697 15:17:10 INFO - PROCESS | 17785 | __metrics 15:17:10 INFO - PROCESS | 17785 | 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" 15:17:10 INFO - PROCESS | 17785 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:17:11 INFO - PROCESS | 17785 | console.error: PushService: 15:17:11 INFO - PROCESS | 17785 | stateChangeProcessEnqueue: Error transitioning state 15:17:11 INFO - PROCESS | 17785 | UnknownError 15:17:12 INFO - TEST-INFO | 17785: exit 0 15:17:12 INFO - Browser initialized. 15:17:12 INFO - Running cycle 1/1 for dromaeo_css test... 15:17:12 INFO - TEST-INFO | started process 17864 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvxMtJ6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:17:24 INFO - PROCESS | 17864 | RSS: Main: 173989888 15:17:24 INFO - PROCESS | 17864 | 15:17:32 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 15:17:45 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 15:18:06 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:18:08 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:18:13 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.06673 seconds. 15:18:16 INFO - PROCESS | 17864 | 1474064296130 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] 15:18:18 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.08339 seconds. 15:18:22 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 15:18:24 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 15:18:28 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00383 seconds. 15:18:34 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 15:18:49 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 15:18:53 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.06672 seconds. 15:18:57 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 15:18:59 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:19:01 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.06672 seconds. 15:19:06 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 15:19:09 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.10008 seconds. 15:19:13 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.20007 seconds. 15:19:17 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:19:24 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:19:26 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 15:19:30 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 15:19:33 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:20:05 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:20:21 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 15:20:27 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 15:20:28 INFO - PROCESS | 17864 | Cycle 1(1): loaded http://localhost:58743/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58743/tests/dromaeo/cssquery-ext.html) 15:20:29 INFO - PROCESS | 17864 | RSS: Main: 268075008 15:20:29 INFO - PROCESS | 17864 | 15:20:33 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 15:20:46 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:20:50 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:20:52 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:21:01 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:21:12 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:21:28 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:21:29 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:21:31 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:21:35 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:21:37 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:21:43 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:21:49 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:21:51 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:21:53 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:21:55 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:21:57 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:22:09 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:22:18 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 15:22:29 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 15:22:30 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 2.01676 seconds. 15:22:45 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:22:50 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:22:52 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:22:59 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 15:23:04 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:23:22 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:23:29 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 15:23:31 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 15:23:32 INFO - PROCESS | 17864 | Cycle 1(1): loaded http://localhost:58743/tests/dromaeo/cssquery-ext.html (next: http://localhost:58743/tests/dromaeo/cssquery-jquery.html) 15:23:33 INFO - PROCESS | 17864 | RSS: Main: 278667264 15:23:33 INFO - PROCESS | 17864 | 15:23:38 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 15:23:41 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:23:47 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:23:51 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:23:53 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:23:57 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00481 seconds. 15:24:03 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 15:24:07 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:24:11 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:24:18 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:24:22 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:24:30 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:24:36 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:24:38 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:24:42 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:24:53 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00015 seconds. 15:24:54 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:24:56 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:25:01 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:25:02 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:25:04 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 15:25:08 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:25:15 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:25:20 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:25:22 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:25:37 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:25:38 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:25:47 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:25:55 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:25:59 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:26:01 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:26:03 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 15:26:05 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:26:07 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:26:09 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:26:15 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:26:17 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:26:21 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:26:25 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:26:27 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:26:33 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:26:35 INFO - PROCESS | 17864 | Cycle 1(1): loaded http://localhost:58743/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58743/tests/dromaeo/cssquery-mootools.html) 15:26:36 INFO - PROCESS | 17864 | RSS: Main: 220983296 15:26:36 INFO - PROCESS | 17864 | 15:26:39 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.03332 seconds. 15:26:52 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:27:00 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:27:02 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:27:06 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 15:27:08 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:27:10 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:27:23 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 15:27:28 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:27:30 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:27:45 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:27:47 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:28:06 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:28:07 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:28:09 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:28:11 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:28:18 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:28:19 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:28:25 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 15:28:33 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:28:41 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:28:45 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:29:01 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:29:08 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 15:29:12 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:29:14 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 15:29:18 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:29:21 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:29:22 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:29:24 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:29:29 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:29:38 INFO - PROCESS | 17864 | Cycle 1(1): loaded http://localhost:58743/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58743/tests/dromaeo/cssquery-prototype.html) 15:29:39 INFO - PROCESS | 17864 | RSS: Main: 219901952 15:29:39 INFO - PROCESS | 17864 | 15:29:42 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 15:29:55 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 15:30:16 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.05009 seconds. 15:30:20 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:30:50 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:31:13 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:31:25 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 15:31:29 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:31:30 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 2.00012 seconds. 15:31:31 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:31:33 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:31:39 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:31:41 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:31:43 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:31:45 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:31:57 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:31:59 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:32:05 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:32:21 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:32:23 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:32:27 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:32:29 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:32:31 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 15:32:36 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 15:32:42 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 15:32:42 INFO - PROCESS | 17864 | Cycle 1(1): loaded http://localhost:58743/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58743/tests/dromaeo/cssquery-yui.html) 15:32:42 INFO - PROCESS | 17864 | RSS: Main: 218259456 15:32:42 INFO - PROCESS | 17864 | 15:32:46 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 15:32:52 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:32:59 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:33:09 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:33:13 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:33:15 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 15:33:33 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:33:39 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:33:50 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:33:54 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 15:33:58 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:34:00 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:34:16 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:34:26 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 15:34:28 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 15:34:33 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:34:34 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 15:34:36 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 15:34:42 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:34:48 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 15:34:50 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 15:34:52 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 15:34:59 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 15:35:01 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 15:35:29 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 15:35:43 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 15:35:44 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 2.00008 seconds. 15:35:45 INFO - PROCESS | 17864 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 15:35:45 INFO - PROCESS | 17864 | Cycle 1(1): loaded http://localhost:58743/tests/dromaeo/cssquery-yui.html (next: http://localhost:58743/tests/dromaeo/cssquery-dojo.html) 15:35:46 INFO - PROCESS | 17864 | RSS: Main: 311222272 15:35:46 INFO - PROCESS | 17864 | 15:35:46 INFO - PROCESS | 17864 | __start_tp_report 15:35:46 INFO - PROCESS | 17864 | _x_x_mozilla_page_load 15:35:46 INFO - PROCESS | 17864 | _x_x_mozilla_page_load_details 15:35:46 INFO - PROCESS | 17864 | |i|pagename|runs| 15:35:46 INFO - PROCESS | 17864 | |0;dojo.html;2602.310387747247;2637.8812953417096;2661.2282438092952;2663;2671.719469455707;4619.491855895849;4704.364910737049;4707.105649926516;4780.4741478437345;4835.226363781791;4775.0927323808455;4776.498467660889;4777.187878060729;4815.133276010317;4843.66094373394;4337.154254920276;4375.737455752649;4385.057212699269;4394.252976993903;4486.170058539181;4447.288433850585;4465.977670111645;4470.418845550074;4478.57453541913;4494.797734101957;4205.053862880858;4263.893402664927;4389.166058448916;4442.777861106948;4445.555444455561;4377.1683380157665;4394.582514661102;4403.471583409979;4405.405270288534;4408.20652282589;4339.240632889232;4369.169857727022;4370.950971766785;4375.256206444912;4376.715513491645;4760.690555113906;4825.879353016167;4862.416510018785;4872.098661747553;4875.219964805614;5151.5106064923775;5155.793768249266;5161.071007218699;5165.762567311588;5165.819196328111;5161.225816127611;5172.9741351293005;5173.1722924331925;5174.3790745110455;5225;4685.297205419214;4742.07529531738;4768.666193366465;4816.614670826326;4838.274258861128;5210.843674689765;5213.7393130343335;5213.817516386908;5214.139666954986;5215;5009.12340340439;5024.0956627807345;5030.37120359955;5032.622553308516;5051.595872330205;4998.825041123616;5024.371953505812;5044.319016932741;5108.106081435734;5113.130767769489;5014.272930425141;5088.567471764942;5126.512981266774;5132.178851383761;5148.407933087743;5030.4215015272985;5033.421156566968;5036.043151801146;5048.1418158913075;5114.974425127851;4174.478190226222;4202.096549241866;4281.100968796525;4319.3952846601505;4363.847265345697;3721.0325315417645;3730.347189241872;3739.2334571412794;3769.9434508482395;3786.73492855503;4463.152001119777;4466.3523789050405;4469;4514.706544074625;4520.841770538082;5108.846734597968;5167.638265321391;5172.172452407597;5178.326817513699;5236.554892834151;4296.398504209413;4338.197433474817;4370.650347972155;4376.431063961664;4454.933176002362;4574.611158051536;4578.496365399803;4627.097715945359;4636.605888499448;4640.651951103613;4854.320395144753;4856.368672072608;4860.173770459101;4860.295257187759;4918.754062296799;5022.221555658879;5059.721715305694;5069.062223488519;5078.593712503065;5119.334486516729;4829.7585120744525;4848.442429120696;4860.465348811557;4889.90220195587;4907.60739140875;5022.648414611088;5024.799008039638;5028.371453568304;5051.87370315745;5092.719900405513;4974.452810190804;4996.825111121093;5115.079285728604;5124.871878203075;5189.325387699575;4914.5822605078965;4943.975280123576;4966.329545511454;4968.577670898014;5025.723585202849;4955.776990035529;4962.702237865739;4964.702117872939;4968.882001549622;5023.995200959749;4801.615870730263;4893.926591101207;4900.044491324157;4907.582855457326;4971.6022718183185;4926.926096108489;4960.50394960501;5012.072766538202;5030.874228144326;5112.948870511247;4902.779374928065;4919.926201107058;4921.384826896638;4921.704697718148;4952.034353301216 15:35:46 INFO - PROCESS | 17864 | |1;ext.html;553.1785298831237;558.9329280486343;565.2228186243915;567.9006173919563;568.2357229526291;27994.44011119779;39520;40689.593104068954;40888.38667419991;43591.56408435915;40021.39967900484;40354.192916141736;40363;40367.39448908262;40396.59603403965;7429.962850185741;7573.924260757404;7580.431467639922;7585.962070189641;7586.3172314491685;7314.963425182893;7395.075615548057;7400.888986665204;7401.5189012714;7403.51877127985;7363.5581865088;7377.114746230433;7398.519096258726;7402.518836275625;7403.296686814744;7359.558426494401;7376.520526165809;7389.1502477214735;7391.778246652609;7396.741114061036;7150.356467917913;7210.819729506752;7213.026241457389;7216.8917466238045;7217.206107328189;37298.91320649068;37701;37779;37800.43299350512;37816.62183378159;2500.849949003066;2559.372953626355;2608.6347911292432;2775.8473283969374;2785.261905595019;27500;27600.309992250353;27974.300642483897;28075.85962070197;28615.57076643852;3025.7276845084048;3148.071318960889;3155.368926214743;3181.9522707159413;3207.342494788563;238739;238962;239120;239462;239908.99999999825;51514.74242628763;51902;51938.623989440566;51998;52052.47947520476;32711.345773084882;33081.3383732324;33168;35143.4728479073;47304.763476183085;12415.875841241652;12424.875751242553;12429.316387598768;12446.31545265001;12561.937190313989;9942.204623630092;10017.29878908484;10028.29801913859;10031.047050530187;10032.046955539212;17931.551711207325;17987.83079099854;18009.819901801075;18031.819681803016;18101.547461313574;6848.486363522656;6853.828654283583;6863.176418829673;6906.136732908387;6909.723611055501;707.752286699651;785.8074771680974;787.8818177273455;862.6499528238287;874.7594411536878;27788.861055694593;27941;28053.298667533476;28071.298217544318;28409.857950710113;845.9365547583956;851.2594043182471;855.6064210463256;874.0116235564143;971.1065819446127;12990.610281691565;13453.529126480526;13540.187588744708;13723.519676811264;13742.587722368346;370067;371247;371817;373067;373384;48595.5140448598;49094.26358604624;49226.50773492291;49267.75366123218;49384.75307623439;1039.8492218628257;1093.5516438260274;1109.1182509904645;1111.5053801058452;1151.0388825330942;27304.453910921275;28626.284342891595;29090.418191636472;29174.854125730086;29594.704052960056;40860.59139408568;47654;48410.27384589305;48448.5155148444;53010;363742;371360;371786;372226;372367;31688.207794805316;32134;35092.64907350894;36934.261314773015;37997.24005519929;41433;41620.791896040326;41666;42309.57690423056;42325.57674423217;35424.46863297104;35490.8225458871;35610.10974725652;35771.46342804808;36466.453003205505;35023.47464788081;35286;35702.64297357097;35759.28481430409;35902.64097358993;34833.30333393368;34851.65148348484;35289.82355088208;35792;36021.81989090038;30501.389972199988;32897.177570560925;33025.66974330226;33229.16927076746;33559.66440335565;24532.877335613208;24765.62851557264;25225.87387063053;25406.106893002787;25705.742942571083 15:35:46 INFO - PROCESS | 17864 | |2;jquery.html;339.8538628389791;361.84079005237703;364.5406787447816;365.6836836136739;389.10311731458995;15363.3086511107;15964.840351596495;16457.423990160347;16718.41485548006;16741.497755067336;15608.297626606802;16305.021698698087;16469.91765041173;16540.669186616262;16720.665586688257;4724.787384567694;4920.655554111214;4940.654154209206;4948.158813001798;4950.727709975949;3677.2277821657485;3851.903702407434;4948.356713627241;5023.346964894558;5025.1708468102715;3807.333716599584;3853.7687738735626;3893.922121557567;5871.324797648261;5993.340732519441;5963.910541341883;5973.970130149365;5981.58128930975;5982;5984.0126379147605;4682.648801339879;4941.629377796644;4975.477574854661;5035.62232832539;5038.874028149289;12268.386580670931;12302.507899684002;12379.504819807196;12482.063845211645;12485.56300529477;1711.6918954588293;1800.1449311577028;1888.3107665702057;2027.3816485971772;2236.418531181904;8849.823003539957;9182.219511341544;9723.65967191145;9884.703458896245;13457.125286856322;1625.1874062968516;1680.6806706725806;1686.8313168683155;1701.3789966662202;1745.293156271712;63450.682746585975;64413;73005.63497182533;73068.2693173072;75305;9709.174720148867;9727.416355018722;9897.851532227023;10190.286679932482;10271.794564108826;13636.590902272948;13700.314984250947;13740.862591374158;13744.587662369944;13754.174749515061;6758.959636018531;6833.658317084126;7061.576305421691;7108.68010939509;7153.248908864598;6353.5870168438905;6518.120053792677;6574.243961944342;6588.275289718223;6618.768343107968;4209.34755112958;4291.592298731615;4382.145481631115;4413.360062790942;4438.755868427267;1754.438519761163;1819.5360183153211;1834.2296235581089;1883.4537983984492;1885.5474686075243;750.3696894608555;761.4022991951402;767.2902565127235;803.0724513187278;853.3599800149888;15607.063576185465;15900.602484937968;16372.263248153862;16484.42304519336;16983.830161698224;810.5379933437883;815.2662603656756;818.639798488675;824.3240542754884;826.8677011678103;9078.636854525877;9610.135087842127;9634.373765705206;9699.854502182472;9756.756081097887;390985;395766;397230;397876;398189;75260;77858;77865.6106719474;78578;79167.60416197882;878.1416489327072;886.5567216391804;898.380117718772;901.8692289617969;903.9728808135767;14306;14686.045407048508;14859.405623774928;15024.399024039354;15522.5343239703;35562.46656300209;35996.64003359933;36515;36648;37038;50329.49670503248;50641.74679126581;50759.746201268754;50916.49083509117;51747.482525174266;6948.374646281859;7224.963875180591;7375.8241318810005;7475.36459400957;7625.466217364953;9707.466089365153;10994.945025274823;11086.556537738401;11180.440977951233;11509.251898626562;9493;9539.189168920719;9627.950261365559;9953.502324883582;10200.438975856103;9327.300452465957;9641.517924103908;9902.554385052834;9943.105120539187;9947.701568952943;9460.621575137193;9940.155086817413;10226.437545935045;10231.232657550863;10340.479192938354;7524.209957954555;7695.268949449794;7763.689452422066;7805.8829117562145;7907.130215676386;4794.256890182028;6522.673866306571;6777.525573209828;9320.6271749132;10692.83960740574 15:35:46 INFO - PROCESS | 17864 | |3;mootools.html;1149.7757937202248;1339.3169483563383;1382.3019375215517;1433.2618701368797;1660.369059757292;6657.267700552941;7529.32236098751;7676.731314403997;7761.6507257173425;8012.038555373363;7839.412044096687;7900.565468899208;7911.643976021079;7911.762647120596;7913.762587122395;2141.8714877107386;2171.8479706420558;2174.206414658654;2177.68423578581;2178.4227179797367;2164.512984578473;2192.166976548909;2219.1456289328644;2221.577900198957;2222.3333000099988;2154.9353519394444;2159.147136880931;2168.8373371997163;2169.8481106322565;2203.4050806282294;2118.555103428282;2123.4479035450895;2124.904379302935;2125.8724476531456;2127.925522606701;2086.123827992247;2152.3758110148037;2155.9029843656917;2159.1147629471843;2162.1891790578534;7751.961240193819;7818.921810781876;7829.334506566948;7852.057753069611;7871.330936870373;4218.78906054696;4382.693211475198;4418.028033832552;4447.444069491314;4460.174867649495;2774.6531683539556;2860.8855645774142;2899.289674029855;2916.533354663265;2920.2261400728826;984.6553706202843;987.8123156600223;991.8710567626163;1000.6697789729371;1009.9798004039951;10711.303765255312;10798.136149108053;10800.189985751022;10806.891931080667;10810.135189184846;4048.5344185419053;4094.774787386663;4105.261053010487;4149.170165966819;4155.58444155582;8580.184882436159;8583.270422014073;8583.785405364915;8596.527191004554;8601.5699215039;4455.042165934275;4461.866144015685;4462;4463.888402789957;4466.330050492452;3282.163048422685;3524.2951409718157;3648.434492653643;4178.603032711888;4182.079942412664;3070.4473194825146;3403.3533628610485;3427.108951672583;3442.896713098611;3483.4600636901323;975.9072888075622;1028.1980055556678;1030.4281123976116;1033.788073444942;1035.4253389368973;596.4006173795308;662.3806740697463;679.2494293805274;690.4441924250925;691.4952084977995;4716.198246298138;4859.586935110485;4917.385326834145;4922.72924989129;5055.191169412877;829.2205326992607;849.6558893648082;853.0701535326524;857.185673610073;874.9606267717968;3140;3328.750343724182;3436.9484457733156;3443.1736383268335;3551.183227857607;9076.773080673036;9581.904180958241;9585.993470685617;9593;9595.28035397348;9383.343165978453;9463.769337982616;9503.239740820718;9539.90460095404;9561.235101191887;7206.56760594366;7210.819729506699;7347.742829000959;7350.77947661571;7354.77935661931;4785.186518291968;4887.535684110004;4929.531694489017;4971.9254211186135;5101.285819985131;8589.269912057545;8790.604422800861;8916.108389161032;8989.325800564853;9069.365144439826;8002.5598592077995;8957.776055598662;9031.548422578977;9071.818563628822;9120.726378208665;5717.1995920570325;5940.435658612425;6102.267727872683;6153.353897840662;6262;6863.519553631198;6917.23910369869;6918.792436226921;6925.411340036153;6935.306469353024;6249.9062514063235;6295.150154729053;6316.684165791784;6429.32492088324;6494.6752662367635;6183.81448556544;6216.564840461125;6222.222222222223;6237.438630523093;6251.78118765841;5718.170865224436;5722.942770572241;5760.539156867524;5771.365149833431;5775.6823374714795;4191.622753952037;4214.936775948425;4225.133847561304;4229.598188172119;4311.288637374798;4298.76356800379;4309.892252693708;4343.956560434482;4354.455693038371;4377.562243775597 15:35:46 INFO - PROCESS | 17864 | |4;prototype.html;301.7314590014887;310.0373340776888;312.0529193896523;315.5708236797954;316.05657113516133;4785.61715062795;4808.687435316702;4810.494898035704;4810.869445680267;4845.757712114398;4782.187028205205;4787.473377928425;4791.808327666898;4795.304680821278;4806.43790768001;3581.283743251354;3712.9603710960973;3734.7012239020814;3805.2769973705044;3825.3114439400897;3813.3326667833157;3816.370298900678;3821.216650586637;3834.7123965702544;3840.2703486337646;3788.715846311524;3789.829457674411;3791.184895247521;3791.317562838688;3793.753406028613;3594.155373487228;3671.76133551321;3698.704103671727;3792.7534710243744;3799.25914446683;3359.89123589215;3366.562346894888;3372.5447064646205;3374.96625033749;3383.5770528683915;4667.199680191891;4687.531246875319;4689.812407503696;4702.247640377557;4703.6942598730975;4085.9387109193385;4301.591348821857;4359.193549193377;4368.990264478344;4383.890402739925;4974.42794078682;4976.62675299354;4979.975100124476;4981.850544483671;4992.87517812054;3661.9816900915594;3666.1018050577513;3682.085315635881;3703.8888833335045;3715.5912849586525;5068.974655126738;5190.4550022247495;5204.34945631796;5236.895262094775;5269.104252277122;3707.94438083429;3729.8321575529167;3735.7198210134347;3744.045268456526;3765.755225910307;3783.754055986352;3784.7669874773464;3788.507494025814;3790.677792387623;3792.3173828711097;4245.193413251534;4245.766482843474;4247.129338485603;4310.913781724348;4311.676624253131;4801.375821143229;4957.231629097496;4978.427480839678;4989.002199560103;4993.350864387607;1301.8177455156285;1311.9278439685909;1321.1412581821892;1321.2402868350737;1325.8806707396382;806.246159840551;830.969597698849;844.2148801614558;847.9406441549032;848.3594885861136;4234.449521562177;4378.540253273424;4387.846425375096;4401.471823381215;4421.557844215553;5026.974865125651;5129.204973229155;5169.06956747789;5197.29836472079;5204.375474943031;4508.706934049342;4509.7970591323465;4510.594046535762;4513.390692256569;4518.164139634178;2652.5490666586725;2682.3637581075527;2715.2668779429837;2732.180345896223;2759.889604415841;4863.076015557032;4876.414830220397;4885.218365061644;4885.316055752198;4891.1685013547785;5002.474740152305;5235.3717553893775;5244.318238628954;5269.209618557171;5293.364796224478;4837.830675926326;4904.705717656952;4905.533974272438;4923.138450771101;4948.233023881304;5148.76830542619;5155.304033955368;5158.871028224325;5168.896622067613;5170.939957308818;4835.879103022453;5052.520010598987;5060.367454068241;5072;5077.238414237894;4844.346013288302;4888.804447822047;4936.445019818746;4943.901121977612;4944.4066711993355;3679.3377192105677;3688.944665829961;3691.630836916287;3707.4068149096015;3709.6104908984166;5052.949470505395;5215.800365915937;5218.425973142876;5220.399654039682;5223.962654564799;4967.40391153064;4973.701577905337;4976.328195693535;4990.775415106402;5053.570446511941;4958.933829226734;4976.328195693535;5022.945181511777;5064.44291127983;5070.290159377764;4749.620030397629;4965.801367945386;4967.155583550877;4969.776360063734;4970.204767237225;4856.95143048565;4986.50134986513;5081.364829396325;5091.6181286402925;5091.821786237464;4841.782119804688;4975.850724478271;4984.302197692398;4989.501049894981;4994.550490455877 15:35:46 INFO - PROCESS | 17864 | |5;yui.html;530;539.5440852479653;541.7670401727256;552.1524459953971;560.5907687388207;11745.823812642817;12808.551700690477;12879.227246365224;12908;13019.934900325508;11934.820977685342;12191.512339506431;12214.572489962853;12305.323207223599;12353.19704219225;738.4055835052794;748.9438292128085;750.2085300008489;750.7710148404734;751.8609057324385;606.278528551025;621.4996927473383;742.3578604507117;764.9158592554815;771.2711487644178;807.0436532708729;810.4650930385947;810.748667912946;810.7810891059411;810.8256724804166;805.5166899860095;805.8589746794288;806.31060443321;806.592670701298;806.6894245715383;468.98358557450325;470.9152352576535;476.11442716547197;480.2723873331905;557.4926816596918;11829.763404731857;12418.627441176779;12442.75114497705;12531.31077790721;12557.05822063349;11008.944955275172;11063.446827658665;11186.776264474665;11206.663800086011;11780;10879.728006799893;10886.67339979802;10963.50664220104;11191.44042797865;11226.101911847032;3965.8810235692977;3987.6809855211513;4083.4691490106097;4172.061286210609;4175.164967006611;69468;70330;70671;70770.64614676945;71458.64270678614;8947.776305592412;9168.816623667622;9236.8614470783;9358.812823743487;9503;16476.83523164753;16608.916955415145;16612.83387166113;16639.667206656042;17426.302947881944;6820.454363650896;6903.723851045902;7031.226565077837;7069.6111713856235;7294.744683936036;3678.5401824771902;3693.686036686858;3702.7593206441493;3778.981105094512;3795.127120762185;2803.5794630805544;2891.08930686833;2892.75411590013;2901.3907079513115;2902.2309088091674;504.09011733820074;510.8237658008056;517.4256575201598;520.885405210853;526.1975487381696;532.4914706455326;552.3896094815252;565.4967079299427;566.9829905102854;571.9799807006734;7432.554046757212;7464.738734144279;7521.608502427069;7811.828225766067;8000.399970002273;439.34318194299726;462.6576333513176;531.4632221456357;537.3363895588944;537.5806870640906;4105.199486100241;4202.957970420318;4387.583179597933;4390.692651514363;4664.673472856867;61787;91848;101770;107720.46139769407;108340.45829770801;72625.63687181531;90144.54927725319;90647;92487.07512924919;92856.53571732098;70467;73721.6313918427;74010.62994684992;74429;75035;8280;8283.627236774239;8467.576621168793;8522.764199190959;8620.870686939576;30790.38419231558;32868;42402.575974239866;42965.78517107519;48722.459914431485;61692;63703.6814815923;63788.362116378245;64087.18256348656;66590;10556.84164737545;10636.14910807118;10798.352098873778;10951.233413660968;11044.447777610927;12282.447289872156;12379.62861114132;12533.74932501363;13550;13703.588892333246;10997.835032474679;11159.832602511131;11238.269512481662;11778.46996885125;11820.0543956485;9742;10870.510827012646;11138.33170009802;11980.221285616408;12424;10178.236632252463;10564.366138031743;10922.29005114665;11151.498182581965;11831;9689.660861869801;9784.80430391374;9871.358361706467;10046.447445390573;10151.086402223835;9370.39092458988;10236.897631023594;10298.639547615798;10326.173906087344;10427 15:35:46 INFO - PROCESS | 17864 | __end_tp_report 15:35:46 INFO - PROCESS | 17864 | __start_cc_report 15:35:46 INFO - PROCESS | 17864 | _x_x_mozilla_cycle_collect,1168 15:35:46 INFO - PROCESS | 17864 | __end_cc_report 15:35:46 INFO - PROCESS | 17864 | __startTimestamp1474065346182__endTimestamp 15:35:46 INFO - PROCESS | 17864 | 15:35:46 INFO - PROCESS | 17864 | ------- Summary: start ------- 15:35:46 INFO - PROCESS | 17864 | Number of tests: 6 15:35:46 INFO - PROCESS | 17864 | 15:35:46 INFO - PROCESS | 17864 | [#0] dojo.html Cycles:165 Average:4724.67 Median:4875.22 stddev:501.58 (10.3%) stddev-sans-first:474.68 15:35:46 INFO - PROCESS | 17864 | Values: 2602.3 2637.9 2661.2 2663.0 2671.7 4619.5 4704.4 4707.1 4780.5 4835.2 4775.1 4776.5 4777.2 4815.1 4843.7 4337.2 4375.7 4385.1 4394.3 4486.2 4447.3 4466.0 4470.4 4478.6 4494.8 4205.1 4263.9 4389.2 4442.8 4445.6 4377.2 4394.6 4403.5 4405.4 4408.2 4339.2 4369.2 4371.0 4375.3 4376.7 4760.7 4825.9 4862.4 4872.1 4875.2 5151.5 5155.8 5161.1 5165.8 5165.8 5161.2 5173.0 5173.2 5174.4 5225.0 4685.3 4742.1 4768.7 4816.6 4838.3 5210.8 5213.7 5213.8 5214.1 5215.0 5009.1 5024.1 5030.4 5032.6 5051.6 4998.8 5024.4 5044.3 5108.1 5113.1 5014.3 5088.6 5126.5 5132.2 5148.4 5030.4 5033.4 5036.0 5048.1 5115.0 4174.5 4202.1 4281.1 4319.4 4363.8 3721.0 3730.3 3739.2 3769.9 3786.7 4463.2 4466.4 4469.0 4514.7 4520.8 5108.8 5167.6 5172.2 5178.3 5236.6 4296.4 4338.2 4370.7 4376.4 4454.9 4574.6 4578.5 4627.1 4636.6 4640.7 4854.3 4856.4 4860.2 4860.3 4918.8 5022.2 5059.7 5069.1 5078.6 5119.3 4829.8 4848.4 4860.5 4889.9 4907.6 5022.6 5024.8 5028.4 5051.9 5092.7 4974.5 4996.8 5115.1 5124.9 5189.3 4914.6 4944.0 4966.3 4968.6 5025.7 4955.8 4962.7 4964.7 4968.9 5024.0 4801.6 4893.9 4900.0 4907.6 4971.6 4926.9 4960.5 5012.1 5030.9 5112.9 4902.8 4919.9 4921.4 4921.7 4952.0 15:35:46 INFO - PROCESS | 17864 | 15:35:46 INFO - PROCESS | 17864 | [#1] ext.html Cycles:180 Average:47656.38 Median:371303.50 stddev:87832.73 (23.7%) stddev-sans-first:88007.93 15:35:46 INFO - PROCESS | 17864 | Values: 553.2 558.9 565.2 567.9 568.2 27994.4 39520.0 40689.6 40888.4 43591.6 40021.4 40354.2 40363.0 40367.4 40396.6 7430.0 7573.9 7580.4 7586.0 7586.3 7315.0 7395.1 7400.9 7401.5 7403.5 7363.6 7377.1 7398.5 7402.5 7403.3 7359.6 7376.5 7389.2 7391.8 7396.7 7150.4 7210.8 7213.0 7216.9 7217.2 37298.9 37701.0 37779.0 37800.4 37816.6 2500.8 2559.4 2608.6 2775.8 2785.3 27500.0 27600.3 27974.3 28075.9 28615.6 3025.7 3148.1 3155.4 3182.0 3207.3 238739.0 238962.0 239120.0 239462.0 239909.0 51514.7 51902.0 51938.6 51998.0 52052.5 32711.3 33081.3 33168.0 35143.5 47304.8 12415.9 12424.9 12429.3 12446.3 12561.9 9942.2 10017.3 10028.3 10031.0 10032.0 17931.6 17987.8 18009.8 18031.8 18101.5 6848.5 6853.8 6863.2 6906.1 6909.7 707.8 785.8 787.9 862.6 874.8 27788.9 27941.0 28053.3 28071.3 28409.9 845.9 851.3 855.6 874.0 971.1 12990.6 13453.5 13540.2 13723.5 13742.6 370067.0 371247.0 371817.0 373067.0 373384.0 48595.5 49094.3 49226.5 49267.8 49384.8 1039.8 1093.6 1109.1 1111.5 1151.0 27304.5 28626.3 29090.4 29174.9 29594.7 40860.6 47654.0 48410.3 48448.5 53010.0 363742.0 371360.0 371786.0 372226.0 372367.0 31688.2 32134.0 35092.6 36934.3 37997.2 41433.0 41620.8 41666.0 42309.6 42325.6 35424.5 35490.8 35610.1 35771.5 36466.5 35023.5 35286.0 35702.6 35759.3 35902.6 34833.3 34851.7 35289.8 35792.0 36021.8 30501.4 32897.2 33025.7 33229.2 33559.7 24532.9 24765.6 25225.9 25406.1 25705.7 15:35:46 INFO - PROCESS | 17864 | 15:35:46 INFO - PROCESS | 17864 | [#2] jquery.html Cycles:180 Average:24019.90 Median:4963.10 stddev:65462.17 (1319.0%) stddev-sans-first:65621.67 15:35:46 INFO - PROCESS | 17864 | Values: 339.9 361.8 364.5 365.7 389.1 15363.3 15964.8 16457.4 16718.4 16741.5 15608.3 16305.0 16469.9 16540.7 16720.7 4724.8 4920.7 4940.7 4948.2 4950.7 3677.2 3851.9 4948.4 5023.3 5025.2 3807.3 3853.8 3893.9 5871.3 5993.3 5963.9 5974.0 5981.6 5982.0 5984.0 4682.6 4941.6 4975.5 5035.6 5038.9 12268.4 12302.5 12379.5 12482.1 12485.6 1711.7 1800.1 1888.3 2027.4 2236.4 8849.8 9182.2 9723.7 9884.7 13457.1 1625.2 1680.7 1686.8 1701.4 1745.3 63450.7 64413.0 73005.6 73068.3 75305.0 9709.2 9727.4 9897.9 10190.3 10271.8 13636.6 13700.3 13740.9 13744.6 13754.2 6759.0 6833.7 7061.6 7108.7 7153.2 6353.6 6518.1 6574.2 6588.3 6618.8 4209.3 4291.6 4382.1 4413.4 4438.8 1754.4 1819.5 1834.2 1883.5 1885.5 750.4 761.4 767.3 803.1 853.4 15607.1 15900.6 16372.3 16484.4 16983.8 810.5 815.3 818.6 824.3 826.9 9078.6 9610.1 9634.4 9699.9 9756.8 390985.0 395766.0 397230.0 397876.0 398189.0 75260.0 77858.0 77865.6 78578.0 79167.6 878.1 886.6 898.4 901.9 904.0 14306.0 14686.0 14859.4 15024.4 15522.5 35562.5 35996.6 36515.0 36648.0 37038.0 50329.5 50641.7 50759.7 50916.5 51747.5 6948.4 7225.0 7375.8 7475.4 7625.5 9707.5 10994.9 11086.6 11180.4 11509.3 9493.0 9539.2 9628.0 9953.5 10200.4 9327.3 9641.5 9902.6 9943.1 9947.7 9460.6 9940.2 10226.4 10231.2 10340.5 7524.2 7695.3 7763.7 7805.9 7907.1 4794.3 6522.7 6777.5 9320.6 10692.8 15:35:46 INFO - PROCESS | 17864 | 15:35:46 INFO - PROCESS | 17864 | [#3] mootools.html Cycles:180 Average:4954.32 Median:4920.06 stddev:2839.61 (57.7%) stddev-sans-first:2833.18 15:35:46 INFO - PROCESS | 17864 | Values: 1149.8 1339.3 1382.3 1433.3 1660.4 6657.3 7529.3 7676.7 7761.7 8012.0 7839.4 7900.6 7911.6 7911.8 7913.8 2141.9 2171.8 2174.2 2177.7 2178.4 2164.5 2192.2 2219.1 2221.6 2222.3 2154.9 2159.1 2168.8 2169.8 2203.4 2118.6 2123.4 2124.9 2125.9 2127.9 2086.1 2152.4 2155.9 2159.1 2162.2 7752.0 7818.9 7829.3 7852.1 7871.3 4218.8 4382.7 4418.0 4447.4 4460.2 2774.7 2860.9 2899.3 2916.5 2920.2 984.7 987.8 991.9 1000.7 1010.0 10711.3 10798.1 10800.2 10806.9 10810.1 4048.5 4094.8 4105.3 4149.2 4155.6 8580.2 8583.3 8583.8 8596.5 8601.6 4455.0 4461.9 4462.0 4463.9 4466.3 3282.2 3524.3 3648.4 4178.6 4182.1 3070.4 3403.4 3427.1 3442.9 3483.5 975.9 1028.2 1030.4 1033.8 1035.4 596.4 662.4 679.2 690.4 691.5 4716.2 4859.6 4917.4 4922.7 5055.2 829.2 849.7 853.1 857.2 875.0 3140.0 3328.8 3436.9 3443.2 3551.2 9076.8 9581.9 9586.0 9593.0 9595.3 9383.3 9463.8 9503.2 9539.9 9561.2 7206.6 7210.8 7347.7 7350.8 7354.8 4785.2 4887.5 4929.5 4971.9 5101.3 8589.3 8790.6 8916.1 8989.3 9069.4 8002.6 8957.8 9031.5 9071.8 9120.7 5717.2 5940.4 6102.3 6153.4 6262.0 6863.5 6917.2 6918.8 6925.4 6935.3 6249.9 6295.2 6316.7 6429.3 6494.7 6183.8 6216.6 6222.2 6237.4 6251.8 5718.2 5722.9 5760.5 5771.4 5775.7 4191.6 4214.9 4225.1 4229.6 4311.3 4298.8 4309.9 4344.0 4354.5 4377.6 15:35:46 INFO - PROCESS | 17864 | 15:35:46 INFO - PROCESS | 17864 | [#4] prototype.html Cycles:180 Average:4186.17 Median:4798.34 stddev:1201.75 (25.0%) stddev-sans-first:1169.22 15:35:46 INFO - PROCESS | 17864 | Values: 301.7 310.0 312.1 315.6 316.1 4785.6 4808.7 4810.5 4810.9 4845.8 4782.2 4787.5 4791.8 4795.3 4806.4 3581.3 3713.0 3734.7 3805.3 3825.3 3813.3 3816.4 3821.2 3834.7 3840.3 3788.7 3789.8 3791.2 3791.3 3793.8 3594.2 3671.8 3698.7 3792.8 3799.3 3359.9 3366.6 3372.5 3375.0 3383.6 4667.2 4687.5 4689.8 4702.2 4703.7 4085.9 4301.6 4359.2 4369.0 4383.9 4974.4 4976.6 4980.0 4981.9 4992.9 3662.0 3666.1 3682.1 3703.9 3715.6 5069.0 5190.5 5204.3 5236.9 5269.1 3707.9 3729.8 3735.7 3744.0 3765.8 3783.8 3784.8 3788.5 3790.7 3792.3 4245.2 4245.8 4247.1 4310.9 4311.7 4801.4 4957.2 4978.4 4989.0 4993.4 1301.8 1311.9 1321.1 1321.2 1325.9 806.2 831.0 844.2 847.9 848.4 4234.4 4378.5 4387.8 4401.5 4421.6 5027.0 5129.2 5169.1 5197.3 5204.4 4508.7 4509.8 4510.6 4513.4 4518.2 2652.5 2682.4 2715.3 2732.2 2759.9 4863.1 4876.4 4885.2 4885.3 4891.2 5002.5 5235.4 5244.3 5269.2 5293.4 4837.8 4904.7 4905.5 4923.1 4948.2 5148.8 5155.3 5158.9 5168.9 5170.9 4835.9 5052.5 5060.4 5072.0 5077.2 4844.3 4888.8 4936.4 4943.9 4944.4 3679.3 3688.9 3691.6 3707.4 3709.6 5052.9 5215.8 5218.4 5220.4 5224.0 4967.4 4973.7 4976.3 4990.8 5053.6 4958.9 4976.3 5022.9 5064.4 5070.3 4749.6 4965.8 4967.2 4969.8 4970.2 4857.0 4986.5 5081.4 5091.6 5091.8 4841.8 4975.9 4984.3 4989.5 4994.6 15:35:46 INFO - PROCESS | 17864 | 15:35:46 INFO - PROCESS | 17864 | [#5] yui.html Cycles:180 Average:17673.51 Median:473.51 stddev:25877.30 (5464.9%) stddev-sans-first:25917.88 15:35:46 INFO - PROCESS | 17864 | Values: 530.0 539.5 541.8 552.2 560.6 11745.8 12808.6 12879.2 12908.0 13019.9 11934.8 12191.5 12214.6 12305.3 12353.2 738.4 748.9 750.2 750.8 751.9 606.3 621.5 742.4 764.9 771.3 807.0 810.5 810.7 810.8 810.8 805.5 805.9 806.3 806.6 806.7 469.0 470.9 476.1 480.3 557.5 11829.8 12418.6 12442.8 12531.3 12557.1 11008.9 11063.4 11186.8 11206.7 11780.0 10879.7 10886.7 10963.5 11191.4 11226.1 3965.9 3987.7 4083.5 4172.1 4175.2 69468.0 70330.0 70671.0 70770.6 71458.6 8947.8 9168.8 9236.9 9358.8 9503.0 16476.8 16608.9 16612.8 16639.7 17426.3 6820.5 6903.7 7031.2 7069.6 7294.7 3678.5 3693.7 3702.8 3779.0 3795.1 2803.6 2891.1 2892.8 2901.4 2902.2 504.1 510.8 517.4 520.9 526.2 532.5 552.4 565.5 567.0 572.0 7432.6 7464.7 7521.6 7811.8 8000.4 439.3 462.7 531.5 537.3 537.6 4105.2 4203.0 4387.6 4390.7 4664.7 61787.0 91848.0 101770.0 107720.5 108340.5 72625.6 90144.5 90647.0 92487.1 92856.5 70467.0 73721.6 74010.6 74429.0 75035.0 8280.0 8283.6 8467.6 8522.8 8620.9 30790.4 32868.0 42402.6 42965.8 48722.5 61692.0 63703.7 63788.4 64087.2 66590.0 10556.8 10636.1 10798.4 10951.2 11044.4 12282.4 12379.6 12533.7 13550.0 13703.6 10997.8 11159.8 11238.3 11778.5 11820.1 9742.0 10870.5 11138.3 11980.2 12424.0 10178.2 10564.4 10922.3 11151.5 11831.0 9689.7 9784.8 9871.4 10046.4 10151.1 9370.4 10236.9 10298.6 10326.2 10427.0 15:35:46 INFO - PROCESS | 17864 | -------- Summary: end -------- 15:35:46 INFO - PROCESS | 17864 | 15:35:47 INFO - TEST-INFO | 17864: exit 0 15:35:47 INFO - TEST-OK | dromaeo_css | took 1121589ms 15:35:47 INFO - TEST-START | kraken 15:35:47 INFO - operating with platform_type : linux_ 15:35:47 INFO - Initialising browser for kraken test... 15:35:47 INFO - TEST-INFO | started process 18172 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpntFjvC/profile http://localhost:58743/getInfo.html) 15:35:51 INFO - PROCESS | 18172 | __metrics Screen width/height:1600/1200 15:35:51 INFO - PROCESS | 18172 | colorDepth:24 15:35:51 INFO - PROCESS | 18172 | Browser inner width/height: 1024/697 15:35:51 INFO - PROCESS | 18172 | __metrics 15:35:52 INFO - PROCESS | 18172 | 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" 15:35:52 INFO - PROCESS | 18172 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:35:52 INFO - PROCESS | 18172 | console.error: PushService: 15:35:52 INFO - PROCESS | 18172 | stateChangeProcessEnqueue: Error transitioning state 15:35:52 INFO - PROCESS | 18172 | UnknownError 15:35:53 INFO - TEST-INFO | 18172: exit 0 15:35:53 INFO - Browser initialized. 15:35:53 INFO - Running cycle 1/1 for kraken test... 15:35:53 INFO - TEST-INFO | started process 18252 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpntFjvC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:36:05 INFO - PROCESS | 18252 | RSS: Main: 174592000 15:36:05 INFO - PROCESS | 18252 | 15:36:08 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58743/tests/kraken/driver.html?testName=audio-beat-detection) 15:36:09 INFO - PROCESS | 18252 | RSS: Main: 233357312 15:36:09 INFO - PROCESS | 18252 | 15:36:12 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58743/tests/kraken/driver.html?testName=audio-dft) 15:36:13 INFO - PROCESS | 18252 | RSS: Main: 216801280 15:36:13 INFO - PROCESS | 18252 | 15:36:16 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58743/tests/kraken/driver.html?testName=audio-fft) 15:36:16 INFO - PROCESS | 18252 | RSS: Main: 300019712 15:36:16 INFO - PROCESS | 18252 | 15:36:19 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58743/tests/kraken/driver.html?testName=audio-oscillator) 15:36:19 INFO - PROCESS | 18252 | RSS: Main: 209608704 15:36:19 INFO - PROCESS | 18252 | 15:36:21 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58743/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:36:22 INFO - PROCESS | 18252 | RSS: Main: 212783104 15:36:22 INFO - PROCESS | 18252 | 15:36:25 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58743/tests/kraken/driver.html?testName=imaging-darkroom) 15:36:26 INFO - PROCESS | 18252 | RSS: Main: 249536512 15:36:26 INFO - PROCESS | 18252 | 15:36:29 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58743/tests/kraken/driver.html?testName=imaging-desaturate) 15:36:30 INFO - PROCESS | 18252 | RSS: Main: 243679232 15:36:30 INFO - PROCESS | 18252 | 15:36:33 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58743/tests/kraken/driver.html?testName=json-parse-financial) 15:36:33 INFO - PROCESS | 18252 | RSS: Main: 246747136 15:36:33 INFO - PROCESS | 18252 | 15:36:35 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58743/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:36:36 INFO - PROCESS | 18252 | RSS: Main: 210321408 15:36:36 INFO - PROCESS | 18252 | 15:36:38 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58743/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:36:38 INFO - PROCESS | 18252 | RSS: Main: 217808896 15:36:38 INFO - PROCESS | 18252 | 15:36:41 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58743/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:36:41 INFO - PROCESS | 18252 | RSS: Main: 222224384 15:36:41 INFO - PROCESS | 18252 | 15:36:44 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58743/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:36:45 INFO - PROCESS | 18252 | RSS: Main: 222466048 15:36:45 INFO - PROCESS | 18252 | 15:36:48 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58743/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:36:48 INFO - PROCESS | 18252 | RSS: Main: 220778496 15:36:48 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | Cycle 1(1): loaded http://localhost:58743/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58743/tests/kraken/driver.html?testName=ai-astar) 15:36:50 INFO - PROCESS | 18252 | RSS: Main: 272289792 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | __start_tp_report 15:36:50 INFO - PROCESS | 18252 | _x_x_mozilla_page_load 15:36:50 INFO - PROCESS | 18252 | _x_x_mozilla_page_load_details 15:36:50 INFO - PROCESS | 18252 | |i|pagename|runs| 15:36:50 INFO - PROCESS | 18252 | |0;ai-astar;113;116;115;114;114;117;116;113;114;115 15:36:50 INFO - PROCESS | 18252 | |1;audio-beat-detection;163;128;125;156;124;128;123;124;166;125 15:36:50 INFO - PROCESS | 18252 | |2;audio-dft;163;162;162;162;161;161;161;161;162;161 15:36:50 INFO - PROCESS | 18252 | |3;audio-fft;76;75;76;120;76;77;77;77;103;75 15:36:50 INFO - PROCESS | 18252 | |4;audio-oscillator;84;84;127;84;84;85;111;84;83;113 15:36:50 INFO - PROCESS | 18252 | |5;imaging-gaussian-blur;117;117;118;117;117;117;118;118;126;118 15:36:50 INFO - PROCESS | 18252 | |6;imaging-darkroom;146;146;149;147;147;147;147;146;147;146 15:36:50 INFO - PROCESS | 18252 | |7;imaging-desaturate;95;95;95;96;96;96;96;96;96;95 15:36:50 INFO - PROCESS | 18252 | |8;json-parse-financial;63;60;63;62;63;63;63;63;63;62 15:36:50 INFO - PROCESS | 18252 | |9;json-stringify-tinderbox;66;67;66;67;67;66;66;67;67;66 15:36:50 INFO - PROCESS | 18252 | |10;stanford-crypto-aes;91;85;82;88;82;81;82;82;81;82 15:36:50 INFO - PROCESS | 18252 | |11;stanford-crypto-ccm;133;132;135;133;146;137;131;131;172;135 15:36:50 INFO - PROCESS | 18252 | |12;stanford-crypto-pbkdf2;174;170;175;170;176;174;171;174;171;172 15:36:50 INFO - PROCESS | 18252 | |13;stanford-crypto-sha256-iterative;69;69;69;68;69;68;75;70;67;67 15:36:50 INFO - PROCESS | 18252 | __end_tp_report 15:36:50 INFO - PROCESS | 18252 | __start_cc_report 15:36:50 INFO - PROCESS | 18252 | _x_x_mozilla_cycle_collect,1984 15:36:50 INFO - PROCESS | 18252 | __end_cc_report 15:36:50 INFO - PROCESS | 18252 | __startTimestamp1474065410764__endTimestamp 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | ------- Summary: start ------- 15:36:50 INFO - PROCESS | 18252 | Number of tests: 14 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:1.34 (1.2%) stddev-sans-first:1.27 15:36:50 INFO - PROCESS | 18252 | Values: 113.0 116.0 115.0 114.0 114.0 117.0 116.0 113.0 114.0 115.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#1] audio-beat-detection Cycles:10 Average:136.20 Median:128.00 stddev:17.81 (13.9%) stddev-sans-first:16.04 15:36:50 INFO - PROCESS | 18252 | Values: 163.0 128.0 125.0 156.0 124.0 128.0 123.0 124.0 166.0 125.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.70 (0.4%) stddev-sans-first:0.53 15:36:50 INFO - PROCESS | 18252 | Values: 163.0 162.0 162.0 162.0 161.0 161.0 161.0 161.0 162.0 161.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#3] audio-fft Cycles:10 Average:83.20 Median:76.00 stddev:15.46 (20.3%) stddev-sans-first:16.18 15:36:50 INFO - PROCESS | 18252 | Values: 76.0 75.0 76.0 120.0 76.0 77.0 77.0 77.0 103.0 75.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:84.00 stddev:16.47 (19.6%) stddev-sans-first:17.07 15:36:50 INFO - PROCESS | 18252 | Values: 84.0 84.0 127.0 84.0 84.0 85.0 111.0 84.0 83.0 113.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#5] imaging-gaussian-blur Cycles:10 Average:118.30 Median:118.00 stddev:2.75 (2.3%) stddev-sans-first:2.88 15:36:50 INFO - PROCESS | 18252 | Values: 117.0 117.0 118.0 117.0 117.0 117.0 118.0 118.0 126.0 118.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 15:36:50 INFO - PROCESS | 18252 | Values: 146.0 146.0 149.0 147.0 147.0 147.0 147.0 146.0 147.0 146.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 15:36:50 INFO - PROCESS | 18252 | Values: 95.0 95.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 15:36:50 INFO - PROCESS | 18252 | Values: 63.0 60.0 63.0 62.0 63.0 63.0 63.0 63.0 63.0 62.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#9] json-stringify-tinderbox Cycles:10 Average:66.50 Median:67.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 15:36:50 INFO - PROCESS | 18252 | Values: 66.0 67.0 66.0 67.0 67.0 66.0 66.0 67.0 67.0 66.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:82.00 stddev:3.37 (4.1%) stddev-sans-first:2.28 15:36:50 INFO - PROCESS | 18252 | Values: 91.0 85.0 82.0 88.0 82.0 81.0 82.0 82.0 81.0 82.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#11] stanford-crypto-ccm Cycles:10 Average:138.50 Median:135.00 stddev:12.56 (9.3%) stddev-sans-first:13.17 15:36:50 INFO - PROCESS | 18252 | Values: 133.0 132.0 135.0 133.0 146.0 137.0 131.0 131.0 172.0 135.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:174.00 stddev:2.16 (1.2%) stddev-sans-first:2.24 15:36:50 INFO - PROCESS | 18252 | Values: 174.0 170.0 175.0 170.0 176.0 174.0 171.0 174.0 171.0 172.0 15:36:50 INFO - PROCESS | 18252 | 15:36:50 INFO - PROCESS | 18252 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:2.28 (3.3%) stddev-sans-first:2.42 15:36:50 INFO - PROCESS | 18252 | Values: 69.0 69.0 69.0 68.0 69.0 68.0 75.0 70.0 67.0 67.0 15:36:50 INFO - PROCESS | 18252 | -------- Summary: end -------- 15:36:50 INFO - PROCESS | 18252 | 15:36:52 INFO - TEST-INFO | 18252: exit 0 15:36:52 INFO - TEST-OK | kraken | took 64890ms 15:36:52 INFO - SUITE-END | took 1186s 15:36:52 INFO - Completed test suite (00:19:46) 15:36:52 INFO - javascript benchmark 15:36:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2602.310387747247, 2637.8812953417096, 2661.2282438092952, 2663.0, 2671.719469455707, 4619.491855895849, 4704.364910737049, 4707.105649926516, 4780.4741478437345, 4835.226363781791, 4775.0927323808455, 4776.498467660889, 4777.187878060729, 4815.133276010317, 4843.66094373394, 4337.154254920276, 4375.737455752649, 4385.057212699269, 4394.252976993903, 4486.170058539181, 4447.288433850585, 4465.977670111645, 4470.418845550074, 4478.57453541913, 4494.797734101957, 4205.053862880858, 4263.893402664927, 4389.166058448916, 4442.777861106948, 4445.555444455561, 4377.1683380157665, 4394.582514661102, 4403.471583409979, 4405.405270288534, 4408.20652282589, 4339.240632889232, 4369.169857727022, 4370.950971766785, 4375.256206444912, 4376.715513491645, 4760.690555113906, 4825.879353016167, 4862.416510018785, 4872.098661747553, 4875.219964805614, 5151.5106064923775, 5155.793768249266, 5161.071007218699, 5165.762567311588, 5165.819196328111, 5161.225816127611, 5172.9741351293005, 5173.1722924331925, 5174.3790745110455, 5225.0, 4685.297205419214, 4742.07529531738, 4768.666193366465, 4816.614670826326, 4838.274258861128, 5210.843674689765, 5213.7393130343335, 5213.817516386908, 5214.139666954986, 5215.0, 5009.12340340439, 5024.0956627807345, 5030.37120359955, 5032.622553308516, 5051.595872330205, 4998.825041123616, 5024.371953505812, 5044.319016932741, 5108.106081435734, 5113.130767769489, 5014.272930425141, 5088.567471764942, 5126.512981266774, 5132.178851383761, 5148.407933087743, 5030.4215015272985, 5033.421156566968, 5036.043151801146, 5048.1418158913075, 5114.974425127851, 4174.478190226222, 4202.096549241866, 4281.100968796525, 4319.3952846601505, 4363.847265345697, 3721.0325315417645, 3730.347189241872, 3739.2334571412794, 3769.9434508482395, 3786.73492855503, 4463.152001119777, 4466.3523789050405, 4469.0, 4514.706544074625, 4520.841770538082, 5108.846734597968, 5167.638265321391, 5172.172452407597, 5178.326817513699, 5236.554892834151, 4296.398504209413, 4338.197433474817, 4370.650347972155, 4376.431063961664, 4454.933176002362, 4574.611158051536, 4578.496365399803, 4627.097715945359, 4636.605888499448, 4640.651951103613, 4854.320395144753, 4856.368672072608, 4860.173770459101, 4860.295257187759, 4918.754062296799, 5022.221555658879, 5059.721715305694, 5069.062223488519, 5078.593712503065, 5119.334486516729, 4829.7585120744525, 4848.442429120696, 4860.465348811557, 4889.90220195587, 4907.60739140875, 5022.648414611088, 5024.799008039638, 5028.371453568304, 5051.87370315745, 5092.719900405513, 4974.452810190804, 4996.825111121093, 5115.079285728604, 5124.871878203075, 5189.325387699575, 4914.5822605078965, 4943.975280123576, 4966.329545511454, 4968.577670898014, 5025.723585202849, 4955.776990035529, 4962.702237865739, 4964.702117872939, 4968.882001549622, 5023.995200959749, 4801.615870730263, 4893.926591101207, 4900.044491324157, 4907.582855457326, 4971.6022718183185, 4926.926096108489, 4960.50394960501, 5012.072766538202, 5030.874228144326, 5112.948870511247, 4902.779374928065, 4919.926201107058, 4921.384826896638, 4921.704697718148, 4952.034353301216], "value": 4692.028880921142, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.1785298831237, 558.9329280486343, 565.2228186243915, 567.9006173919563, 568.2357229526291, 27994.44011119779, 39520.0, 40689.593104068954, 40888.38667419991, 43591.56408435915, 40021.39967900484, 40354.192916141736, 40363.0, 40367.39448908262, 40396.59603403965, 7429.962850185741, 7573.924260757404, 7580.431467639922, 7585.962070189641, 7586.3172314491685, 7314.963425182893, 7395.075615548057, 7400.888986665204, 7401.5189012714, 7403.51877127985, 7363.5581865088, 7377.114746230433, 7398.519096258726, 7402.518836275625, 7403.296686814744, 7359.558426494401, 7376.520526165809, 7389.1502477214735, 7391.778246652609, 7396.741114061036, 7150.356467917913, 7210.819729506752, 7213.026241457389, 7216.8917466238045, 7217.206107328189, 37298.91320649068, 37701.0, 37779.0, 37800.43299350512, 37816.62183378159, 2500.849949003066, 2559.372953626355, 2608.6347911292432, 2775.8473283969374, 2785.261905595019, 27500.0, 27600.309992250353, 27974.300642483897, 28075.85962070197, 28615.57076643852, 3025.7276845084048, 3148.071318960889, 3155.368926214743, 3181.9522707159413, 3207.342494788563, 238739.0, 238962.0, 239120.0, 239462.0, 239908.99999999825, 51514.74242628763, 51902.0, 51938.623989440566, 51998.0, 52052.47947520476, 32711.345773084882, 33081.3383732324, 33168.0, 35143.4728479073, 47304.763476183085, 12415.875841241652, 12424.875751242553, 12429.316387598768, 12446.31545265001, 12561.937190313989, 9942.204623630092, 10017.29878908484, 10028.29801913859, 10031.047050530187, 10032.046955539212, 17931.551711207325, 17987.83079099854, 18009.819901801075, 18031.819681803016, 18101.547461313574, 6848.486363522656, 6853.828654283583, 6863.176418829673, 6906.136732908387, 6909.723611055501, 707.752286699651, 785.8074771680974, 787.8818177273455, 862.6499528238287, 874.7594411536878, 27788.861055694593, 27941.0, 28053.298667533476, 28071.298217544318, 28409.857950710113, 845.9365547583956, 851.2594043182471, 855.6064210463256, 874.0116235564143, 971.1065819446127, 12990.610281691565, 13453.529126480526, 13540.187588744708, 13723.519676811264, 13742.587722368346, 370067.0, 371247.0, 371817.0, 373067.0, 373384.0, 48595.5140448598, 49094.26358604624, 49226.50773492291, 49267.75366123218, 49384.75307623439, 1039.8492218628257, 1093.5516438260274, 1109.1182509904645, 1111.5053801058452, 1151.0388825330942, 27304.453910921275, 28626.284342891595, 29090.418191636472, 29174.854125730086, 29594.704052960056, 40860.59139408568, 47654.0, 48410.27384589305, 48448.5155148444, 53010.0, 363742.0, 371360.0, 371786.0, 372226.0, 372367.0, 31688.207794805316, 32134.0, 35092.64907350894, 36934.261314773015, 37997.24005519929, 41433.0, 41620.791896040326, 41666.0, 42309.57690423056, 42325.57674423217, 35424.46863297104, 35490.8225458871, 35610.10974725652, 35771.46342804808, 36466.453003205505, 35023.47464788081, 35286.0, 35702.64297357097, 35759.28481430409, 35902.64097358993, 34833.30333393368, 34851.65148348484, 35289.82355088208, 35792.0, 36021.81989090038, 30501.389972199988, 32897.177570560925, 33025.66974330226, 33229.16927076746, 33559.66440335565, 24532.877335613208, 24765.62851557264, 25225.87387063053, 25406.106893002787, 25705.742942571083], "value": 16933.643580132164, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.8538628389791, 361.84079005237703, 364.5406787447816, 365.6836836136739, 389.10311731458995, 15363.3086511107, 15964.840351596495, 16457.423990160347, 16718.41485548006, 16741.497755067336, 15608.297626606802, 16305.021698698087, 16469.91765041173, 16540.669186616262, 16720.665586688257, 4724.787384567694, 4920.655554111214, 4940.654154209206, 4948.158813001798, 4950.727709975949, 3677.2277821657485, 3851.903702407434, 4948.356713627241, 5023.346964894558, 5025.1708468102715, 3807.333716599584, 3853.7687738735626, 3893.922121557567, 5871.324797648261, 5993.340732519441, 5963.910541341883, 5973.970130149365, 5981.58128930975, 5982.0, 5984.0126379147605, 4682.648801339879, 4941.629377796644, 4975.477574854661, 5035.62232832539, 5038.874028149289, 12268.386580670931, 12302.507899684002, 12379.504819807196, 12482.063845211645, 12485.56300529477, 1711.6918954588293, 1800.1449311577028, 1888.3107665702057, 2027.3816485971772, 2236.418531181904, 8849.823003539957, 9182.219511341544, 9723.65967191145, 9884.703458896245, 13457.125286856322, 1625.1874062968516, 1680.6806706725806, 1686.8313168683155, 1701.3789966662202, 1745.293156271712, 63450.682746585975, 64413.0, 73005.63497182533, 73068.2693173072, 75305.0, 9709.174720148867, 9727.416355018722, 9897.851532227023, 10190.286679932482, 10271.794564108826, 13636.590902272948, 13700.314984250947, 13740.862591374158, 13744.587662369944, 13754.174749515061, 6758.959636018531, 6833.658317084126, 7061.576305421691, 7108.68010939509, 7153.248908864598, 6353.5870168438905, 6518.120053792677, 6574.243961944342, 6588.275289718223, 6618.768343107968, 4209.34755112958, 4291.592298731615, 4382.145481631115, 4413.360062790942, 4438.755868427267, 1754.438519761163, 1819.5360183153211, 1834.2296235581089, 1883.4537983984492, 1885.5474686075243, 750.3696894608555, 761.4022991951402, 767.2902565127235, 803.0724513187278, 853.3599800149888, 15607.063576185465, 15900.602484937968, 16372.263248153862, 16484.42304519336, 16983.830161698224, 810.5379933437883, 815.2662603656756, 818.639798488675, 824.3240542754884, 826.8677011678103, 9078.636854525877, 9610.135087842127, 9634.373765705206, 9699.854502182472, 9756.756081097887, 390985.0, 395766.0, 397230.0, 397876.0, 398189.0, 75260.0, 77858.0, 77865.6106719474, 78578.0, 79167.60416197882, 878.1416489327072, 886.5567216391804, 898.380117718772, 901.8692289617969, 903.9728808135767, 14306.0, 14686.045407048508, 14859.405623774928, 15024.399024039354, 15522.5343239703, 35562.46656300209, 35996.64003359933, 36515.0, 36648.0, 37038.0, 50329.49670503248, 50641.74679126581, 50759.746201268754, 50916.49083509117, 51747.482525174266, 6948.374646281859, 7224.963875180591, 7375.8241318810005, 7475.36459400957, 7625.466217364953, 9707.466089365153, 10994.945025274823, 11086.556537738401, 11180.440977951233, 11509.251898626562, 9493.0, 9539.189168920719, 9627.950261365559, 9953.502324883582, 10200.438975856103, 9327.300452465957, 9641.517924103908, 9902.554385052834, 9943.105120539187, 9947.701568952943, 9460.621575137193, 9940.155086817413, 10226.437545935045, 10231.232657550863, 10340.479192938354, 7524.209957954555, 7695.268949449794, 7763.689452422066, 7805.8829117562145, 7907.130215676386, 4794.256890182028, 6522.673866306571, 6777.525573209828, 9320.6271749132, 10692.83960740574], "value": 7823.351400389833, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1149.7757937202248, 1339.3169483563383, 1382.3019375215517, 1433.2618701368797, 1660.369059757292, 6657.267700552941, 7529.32236098751, 7676.731314403997, 7761.6507257173425, 8012.038555373363, 7839.412044096687, 7900.565468899208, 7911.643976021079, 7911.762647120596, 7913.762587122395, 2141.8714877107386, 2171.8479706420558, 2174.206414658654, 2177.68423578581, 2178.4227179797367, 2164.512984578473, 2192.166976548909, 2219.1456289328644, 2221.577900198957, 2222.3333000099988, 2154.9353519394444, 2159.147136880931, 2168.8373371997163, 2169.8481106322565, 2203.4050806282294, 2118.555103428282, 2123.4479035450895, 2124.904379302935, 2125.8724476531456, 2127.925522606701, 2086.123827992247, 2152.3758110148037, 2155.9029843656917, 2159.1147629471843, 2162.1891790578534, 7751.961240193819, 7818.921810781876, 7829.334506566948, 7852.057753069611, 7871.330936870373, 4218.78906054696, 4382.693211475198, 4418.028033832552, 4447.444069491314, 4460.174867649495, 2774.6531683539556, 2860.8855645774142, 2899.289674029855, 2916.533354663265, 2920.2261400728826, 984.6553706202843, 987.8123156600223, 991.8710567626163, 1000.6697789729371, 1009.9798004039951, 10711.303765255312, 10798.136149108053, 10800.189985751022, 10806.891931080667, 10810.135189184846, 4048.5344185419053, 4094.774787386663, 4105.261053010487, 4149.170165966819, 4155.58444155582, 8580.184882436159, 8583.270422014073, 8583.785405364915, 8596.527191004554, 8601.5699215039, 4455.042165934275, 4461.866144015685, 4462.0, 4463.888402789957, 4466.330050492452, 3282.163048422685, 3524.2951409718157, 3648.434492653643, 4178.603032711888, 4182.079942412664, 3070.4473194825146, 3403.3533628610485, 3427.108951672583, 3442.896713098611, 3483.4600636901323, 975.9072888075622, 1028.1980055556678, 1030.4281123976116, 1033.788073444942, 1035.4253389368973, 596.4006173795308, 662.3806740697463, 679.2494293805274, 690.4441924250925, 691.4952084977995, 4716.198246298138, 4859.586935110485, 4917.385326834145, 4922.72924989129, 5055.191169412877, 829.2205326992607, 849.6558893648082, 853.0701535326524, 857.185673610073, 874.9606267717968, 3140.0, 3328.750343724182, 3436.9484457733156, 3443.1736383268335, 3551.183227857607, 9076.773080673036, 9581.904180958241, 9585.993470685617, 9593.0, 9595.28035397348, 9383.343165978453, 9463.769337982616, 9503.239740820718, 9539.90460095404, 9561.235101191887, 7206.56760594366, 7210.819729506699, 7347.742829000959, 7350.77947661571, 7354.77935661931, 4785.186518291968, 4887.535684110004, 4929.531694489017, 4971.9254211186135, 5101.285819985131, 8589.269912057545, 8790.604422800861, 8916.108389161032, 8989.325800564853, 9069.365144439826, 8002.5598592077995, 8957.776055598662, 9031.548422578977, 9071.818563628822, 9120.726378208665, 5717.1995920570325, 5940.435658612425, 6102.267727872683, 6153.353897840662, 6262.0, 6863.519553631198, 6917.23910369869, 6918.792436226921, 6925.411340036153, 6935.306469353024, 6249.9062514063235, 6295.150154729053, 6316.684165791784, 6429.32492088324, 6494.6752662367635, 6183.81448556544, 6216.564840461125, 6222.222222222223, 6237.438630523093, 6251.78118765841, 5718.170865224436, 5722.942770572241, 5760.539156867524, 5771.365149833431, 5775.6823374714795, 4191.622753952037, 4214.936775948425, 4225.133847561304, 4229.598188172119, 4311.288637374798, 4298.76356800379, 4309.892252693708, 4343.956560434482, 4354.455693038371, 4377.562243775597], "value": 3967.1016106253915, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.7314590014887, 310.0373340776888, 312.0529193896523, 315.5708236797954, 316.05657113516133, 4785.61715062795, 4808.687435316702, 4810.494898035704, 4810.869445680267, 4845.757712114398, 4782.187028205205, 4787.473377928425, 4791.808327666898, 4795.304680821278, 4806.43790768001, 3581.283743251354, 3712.9603710960973, 3734.7012239020814, 3805.2769973705044, 3825.3114439400897, 3813.3326667833157, 3816.370298900678, 3821.216650586637, 3834.7123965702544, 3840.2703486337646, 3788.715846311524, 3789.829457674411, 3791.184895247521, 3791.317562838688, 3793.753406028613, 3594.155373487228, 3671.76133551321, 3698.704103671727, 3792.7534710243744, 3799.25914446683, 3359.89123589215, 3366.562346894888, 3372.5447064646205, 3374.96625033749, 3383.5770528683915, 4667.199680191891, 4687.531246875319, 4689.812407503696, 4702.247640377557, 4703.6942598730975, 4085.9387109193385, 4301.591348821857, 4359.193549193377, 4368.990264478344, 4383.890402739925, 4974.42794078682, 4976.62675299354, 4979.975100124476, 4981.850544483671, 4992.87517812054, 3661.9816900915594, 3666.1018050577513, 3682.085315635881, 3703.8888833335045, 3715.5912849586525, 5068.974655126738, 5190.4550022247495, 5204.34945631796, 5236.895262094775, 5269.104252277122, 3707.94438083429, 3729.8321575529167, 3735.7198210134347, 3744.045268456526, 3765.755225910307, 3783.754055986352, 3784.7669874773464, 3788.507494025814, 3790.677792387623, 3792.3173828711097, 4245.193413251534, 4245.766482843474, 4247.129338485603, 4310.913781724348, 4311.676624253131, 4801.375821143229, 4957.231629097496, 4978.427480839678, 4989.002199560103, 4993.350864387607, 1301.8177455156285, 1311.9278439685909, 1321.1412581821892, 1321.2402868350737, 1325.8806707396382, 806.246159840551, 830.969597698849, 844.2148801614558, 847.9406441549032, 848.3594885861136, 4234.449521562177, 4378.540253273424, 4387.846425375096, 4401.471823381215, 4421.557844215553, 5026.974865125651, 5129.204973229155, 5169.06956747789, 5197.29836472079, 5204.375474943031, 4508.706934049342, 4509.7970591323465, 4510.594046535762, 4513.390692256569, 4518.164139634178, 2652.5490666586725, 2682.3637581075527, 2715.2668779429837, 2732.180345896223, 2759.889604415841, 4863.076015557032, 4876.414830220397, 4885.218365061644, 4885.316055752198, 4891.1685013547785, 5002.474740152305, 5235.3717553893775, 5244.318238628954, 5269.209618557171, 5293.364796224478, 4837.830675926326, 4904.705717656952, 4905.533974272438, 4923.138450771101, 4948.233023881304, 5148.76830542619, 5155.304033955368, 5158.871028224325, 5168.896622067613, 5170.939957308818, 4835.879103022453, 5052.520010598987, 5060.367454068241, 5072.0, 5077.238414237894, 4844.346013288302, 4888.804447822047, 4936.445019818746, 4943.901121977612, 4944.4066711993355, 3679.3377192105677, 3688.944665829961, 3691.630836916287, 3707.4068149096015, 3709.6104908984166, 5052.949470505395, 5215.800365915937, 5218.425973142876, 5220.399654039682, 5223.962654564799, 4967.40391153064, 4973.701577905337, 4976.328195693535, 4990.775415106402, 5053.570446511941, 4958.933829226734, 4976.328195693535, 5022.945181511777, 5064.44291127983, 5070.290159377764, 4749.620030397629, 4965.801367945386, 4967.155583550877, 4969.776360063734, 4970.204767237225, 4856.95143048565, 4986.50134986513, 5081.364829396325, 5091.6181286402925, 5091.821786237464, 4841.782119804688, 4975.850724478271, 4984.302197692398, 4989.501049894981, 4994.550490455877], "value": 3806.161370993242, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [530.0, 539.5440852479653, 541.7670401727256, 552.1524459953971, 560.5907687388207, 11745.823812642817, 12808.551700690477, 12879.227246365224, 12908.0, 13019.934900325508, 11934.820977685342, 12191.512339506431, 12214.572489962853, 12305.323207223599, 12353.19704219225, 738.4055835052794, 748.9438292128085, 750.2085300008489, 750.7710148404734, 751.8609057324385, 606.278528551025, 621.4996927473383, 742.3578604507117, 764.9158592554815, 771.2711487644178, 807.0436532708729, 810.4650930385947, 810.748667912946, 810.7810891059411, 810.8256724804166, 805.5166899860095, 805.8589746794288, 806.31060443321, 806.592670701298, 806.6894245715383, 468.98358557450325, 470.9152352576535, 476.11442716547197, 480.2723873331905, 557.4926816596918, 11829.763404731857, 12418.627441176779, 12442.75114497705, 12531.31077790721, 12557.05822063349, 11008.944955275172, 11063.446827658665, 11186.776264474665, 11206.663800086011, 11780.0, 10879.728006799893, 10886.67339979802, 10963.50664220104, 11191.44042797865, 11226.101911847032, 3965.8810235692977, 3987.6809855211513, 4083.4691490106097, 4172.061286210609, 4175.164967006611, 69468.0, 70330.0, 70671.0, 70770.64614676945, 71458.64270678614, 8947.776305592412, 9168.816623667622, 9236.8614470783, 9358.812823743487, 9503.0, 16476.83523164753, 16608.916955415145, 16612.83387166113, 16639.667206656042, 17426.302947881944, 6820.454363650896, 6903.723851045902, 7031.226565077837, 7069.6111713856235, 7294.744683936036, 3678.5401824771902, 3693.686036686858, 3702.7593206441493, 3778.981105094512, 3795.127120762185, 2803.5794630805544, 2891.08930686833, 2892.75411590013, 2901.3907079513115, 2902.2309088091674, 504.09011733820074, 510.8237658008056, 517.4256575201598, 520.885405210853, 526.1975487381696, 532.4914706455326, 552.3896094815252, 565.4967079299427, 566.9829905102854, 571.9799807006734, 7432.554046757212, 7464.738734144279, 7521.608502427069, 7811.828225766067, 8000.399970002273, 439.34318194299726, 462.6576333513176, 531.4632221456357, 537.3363895588944, 537.5806870640906, 4105.199486100241, 4202.957970420318, 4387.583179597933, 4390.692651514363, 4664.673472856867, 61787.0, 91848.0, 101770.0, 107720.46139769407, 108340.45829770801, 72625.63687181531, 90144.54927725319, 90647.0, 92487.07512924919, 92856.53571732098, 70467.0, 73721.6313918427, 74010.62994684992, 74429.0, 75035.0, 8280.0, 8283.627236774239, 8467.576621168793, 8522.764199190959, 8620.870686939576, 30790.38419231558, 32868.0, 42402.575974239866, 42965.78517107519, 48722.459914431485, 61692.0, 63703.6814815923, 63788.362116378245, 64087.18256348656, 66590.0, 10556.84164737545, 10636.14910807118, 10798.352098873778, 10951.233413660968, 11044.447777610927, 12282.447289872156, 12379.62861114132, 12533.74932501363, 13550.0, 13703.588892333246, 10997.835032474679, 11159.832602511131, 11238.269512481662, 11778.46996885125, 11820.0543956485, 9742.0, 10870.510827012646, 11138.33170009802, 11980.221285616408, 12424.0, 10178.236632252463, 10564.366138031743, 10922.29005114665, 11151.498182581965, 11831.0, 9689.660861869801, 9784.80430391374, 9871.358361706467, 10046.447445390573, 10151.086402223835, 9370.39092458988, 10236.897631023594, 10298.639547615798, 10326.173906087344, 10427.0], "value": 6371.988831082655, "unit": "score"}], "name": "dromaeo_css", "value": 6253.618286471309, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 115.0, 114.0, 114.0, 117.0, 116.0, 113.0, 114.0, 115.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 128.0, 125.0, 156.0, 124.0, 128.0, 123.0, 124.0, 166.0, 125.0], "value": 136.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 162.0, 161.0, 161.0, 161.0, 161.0, 162.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 120.0, 76.0, 77.0, 77.0, 77.0, 103.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 127.0, 84.0, 84.0, 85.0, 111.0, 84.0, 83.0, 113.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0, 126.0, 118.0], "value": 118.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 149.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 146.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 63.0, 62.0, 63.0, 63.0, 63.0, 63.0, 63.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 67.0, 66.0, 66.0, 67.0, 67.0, 66.0], "value": 66.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 82.0, 88.0, 82.0, 81.0, 82.0, 82.0, 81.0, 82.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 132.0, 135.0, 133.0, 146.0, 137.0, 131.0, 131.0, 172.0, 135.0], "value": 138.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 170.0, 175.0, 170.0, 176.0, 174.0, 171.0, 174.0, 171.0, 172.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 68.0, 69.0, 68.0, 75.0, 70.0, 67.0, 67.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1543.2, "alertThreshold": 2.0}]} 15:36:52 INFO - Return code: 0 15:36:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:36:52 INFO - # TBPL SUCCESS # 15:36:52 INFO - Running post-action listener: _resource_record_post_action 15:36:52 INFO - [mozharness: 2016-09-16 22:36:52.580428Z] Finished run-tests step (success) 15:36:52 INFO - Running post-run listener: _resource_record_post_run 15:36:52 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 0; Write bytes: 392294400; Read time: 0; Write time: 344756 15:36:52 INFO - TinderboxPrint: CPU usage
13.1% 15:36:52 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:36:52 INFO - TinderboxPrint: I/O write bytes / time
392,294,400 / 344,756 15:36:52 INFO - TinderboxPrint: CPU idle
8,342.8 (86.9%) 15:36:52 INFO - TinderboxPrint: CPU user
1,201.8 (12.5%) 15:36:52 INFO - TinderboxPrint: Swap in / out
0 / 0 15:36:52 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99803136; Read time: 0; Write time: 115252 15:36:52 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 292491264; Read time: 0; Write time: 229504 15:36:53 INFO - Running post-run listener: _upload_blobber_files 15:36:53 INFO - Blob upload gear active. 15:36:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:36:53 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 15:36:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:36:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:36:53 INFO - (blobuploader) - INFO - Open directory for files ... 15:36:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:36:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:57 INFO - (blobuploader) - INFO - Done attempting. 15:36:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:36:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:36:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:58 INFO - (blobuploader) - INFO - Done attempting. 15:36:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:36:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:36:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:59 INFO - (blobuploader) - INFO - Done attempting. 15:36:59 INFO - (blobuploader) - INFO - Iteration through files over. 15:36:59 INFO - Return code: 0 15:36:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:36:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:36:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46fd0b4f48b04e8ff7f2a171d3e7c6a18528d358abd1bea6cf0b94e06e387c1881b91ebad78a8c44de24e760c616e82adabbe68218cc98e173223671cfd96688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0ea699eb922010d826ad1308758853e1a664d5d2b2233f6c017a83095063bcd61281651ba930a32ac093b442572728d8e7e6310e3fcc721b0a1959a293904f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13514c3b59ccd1a1bc885005594c2b6e39b7b878af5f4592ff57c28b2317fb36efdcf4ae298c39a0beed644cfbfcae44f30e5cafea8bb20075bbd6efaed36144"} 15:36:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:36:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:36:59 INFO - Contents: 15:36:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46fd0b4f48b04e8ff7f2a171d3e7c6a18528d358abd1bea6cf0b94e06e387c1881b91ebad78a8c44de24e760c616e82adabbe68218cc98e173223671cfd96688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0ea699eb922010d826ad1308758853e1a664d5d2b2233f6c017a83095063bcd61281651ba930a32ac093b442572728d8e7e6310e3fcc721b0a1959a293904f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13514c3b59ccd1a1bc885005594c2b6e39b7b878af5f4592ff57c28b2317fb36efdcf4ae298c39a0beed644cfbfcae44f30e5cafea8bb20075bbd6efaed36144"} 15:36:59 INFO - Running post-run listener: copy_logs_to_upload_dir 15:36:59 INFO - Copying logs to upload dir... 15:36:59 INFO - mkdir: /builds/slave/test/build/upload/logs 15:36:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.142790 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-09-16 15:36:58.860526) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 15:36:58.864075) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557 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-Non-PGO/sha512/46fd0b4f48b04e8ff7f2a171d3e7c6a18528d358abd1bea6cf0b94e06e387c1881b91ebad78a8c44de24e760c616e82adabbe68218cc98e173223671cfd96688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0ea699eb922010d826ad1308758853e1a664d5d2b2233f6c017a83095063bcd61281651ba930a32ac093b442572728d8e7e6310e3fcc721b0a1959a293904f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13514c3b59ccd1a1bc885005594c2b6e39b7b878af5f4592ff57c28b2317fb36efdcf4ae298c39a0beed644cfbfcae44f30e5cafea8bb20075bbd6efaed36144"} build_url:https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011473 build_url: 'https://queue.taskcluster.net/v1/task/DD8O--mMS5us_quMCcbEWA/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-Non-PGO/sha512/46fd0b4f48b04e8ff7f2a171d3e7c6a18528d358abd1bea6cf0b94e06e387c1881b91ebad78a8c44de24e760c616e82adabbe68218cc98e173223671cfd96688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d0ea699eb922010d826ad1308758853e1a664d5d2b2233f6c017a83095063bcd61281651ba930a32ac093b442572728d8e7e6310e3fcc721b0a1959a293904f3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13514c3b59ccd1a1bc885005594c2b6e39b7b878af5f4592ff57c28b2317fb36efdcf4ae298c39a0beed644cfbfcae44f30e5cafea8bb20075bbd6efaed36144"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 15:36:58.897387) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:36:58.897736) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1474063880.77600-2074793557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004435 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:36:58.982543) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 15:36:58.983264) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 15:36:58.983958) ========= ========= Total master_lag: 0.20 =========