builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1481951513.46 results: success (0) buildid: 20161216193202 builduid: b1fb3d2707934b42acfc0f6bf63a292b revision: 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:53.462190) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:53.462618) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:53.462891) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:53.477952) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:53.478400) ========= 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=62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-16 21:11:53-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 51.2K=0.2s 2016-12-16 21:11:53 (51.2 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.366292 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:53.859569) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:53.860069) ========= 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=62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013745 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:53.910056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-16 21:11:53.910657) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 --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 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 --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=62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-16 21:11:53,911 truncating revision to first 12 chars 2016-12-16 21:11:53,911 Setting DEBUG logging. 2016-12-16 21:11:53,911 attempt 1/10 2016-12-16 21:11:53,912 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/34a1ab064cb5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 21:11:54,637 unpacking tar archive at: mozilla-central-34a1ab064cb5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.100520 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-16 21:11:55.024283) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:55.024604) ========= script_repo_revision: 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:55.025032) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:55.025318) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 21:11:55.033768) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-16 21:11:55.034077) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:11:55 INFO - MultiFileLogger online at 20161216 21:11:55 in /builds/slave/test 21:11:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:11:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:11:55 INFO - {'append_to_log': False, 21:11:55 INFO - 'base_work_dir': '/builds/slave/test', 21:11:55 INFO - 'blob_upload_branch': 'Firefox', 21:11:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:11:55 INFO - 'branch': 'Firefox', 21:11:55 INFO - 'buildbot_json_path': 'buildprops.json', 21:11:55 INFO - 'config_files': ('talos/linux_config.py',), 21:11:55 INFO - 'default_actions': ('clobber', 21:11:55 INFO - 'read-buildbot-config', 21:11:55 INFO - 'download-and-extract', 21:11:55 INFO - 'populate-webroot', 21:11:55 INFO - 'create-virtualenv', 21:11:55 INFO - 'install', 21:11:55 INFO - 'run-tests'), 21:11:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:11:55 INFO - 'download_minidump_stackwalk': True, 21:11:55 INFO - 'download_symbols': 'ondemand', 21:11:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:11:55 INFO - 'tooltool.py': '/tools/tooltool.py', 21:11:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:11:55 INFO - '/tools/misc-python/virtualenv.py')}, 21:11:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:11:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:11:55 INFO - 'installer_path': 'installer.exe', 21:11:55 INFO - 'log_level': 'info', 21:11:55 INFO - 'log_name': 'talos', 21:11:55 INFO - 'log_to_console': True, 21:11:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:11:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:11:55 INFO - 'opt_config_files': (), 21:11:55 INFO - 'pip_index': False, 21:11:55 INFO - 'sps_profile': False, 21:11:55 INFO - 'sps_profile_interval': 0, 21:11:55 INFO - 'suite': 'dromaeojs', 21:11:55 INFO - 'system_bits': '32', 21:11:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:11:55 INFO - 'title': 'talos-linux64-ix-018', 21:11:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:11:55 INFO - 'use_talos_json': True, 21:11:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:11:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:11:55 INFO - 'work_dir': 'build'} 21:11:55 INFO - [mozharness: 2016-12-17 05:11:55.129732Z] Running clobber step. 21:11:55 INFO - Running pre-action listener: _resource_record_pre_action 21:11:55 INFO - Running main action method: clobber 21:11:55 INFO - rmtree: /builds/slave/test/build 21:11:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:11:55 INFO - Running post-action listener: _resource_record_post_action 21:11:55 INFO - [mozharness: 2016-12-17 05:11:55.580180Z] Finished clobber step (success) 21:11:55 INFO - [mozharness: 2016-12-17 05:11:55.580279Z] Running read-buildbot-config step. 21:11:55 INFO - Running pre-action listener: _resource_record_pre_action 21:11:55 INFO - Running main action method: read_buildbot_config 21:11:55 INFO - Using buildbot properties: 21:11:55 INFO - { 21:11:55 INFO - "project": "", 21:11:55 INFO - "product": "firefox", 21:11:55 INFO - "script_repo_revision": "production", 21:11:55 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:11:55 INFO - "repository": "", 21:11:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:11:55 INFO - "buildid": "20161216193202", 21:11:55 INFO - "pgo_build": "True", 21:11:55 INFO - "basedir": "/builds/slave/test", 21:11:55 INFO - "buildnumber": 118, 21:11:55 INFO - "slavename": "talos-linux64-ix-018", 21:11:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:11:55 INFO - "platform": "ubuntu64_hw", 21:11:55 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:11:55 INFO - "branch": "mozilla-central", 21:11:55 INFO - "repo_path": "mozilla-central", 21:11:55 INFO - "revision": "34a1ab064cb5b868fa75cb74d052e978eb34d6c1", 21:11:55 INFO - "stage_platform": "linux64-pgo", 21:11:55 INFO - "builduid": "b1fb3d2707934b42acfc0f6bf63a292b", 21:11:55 INFO - "slavebuilddir": "test" 21:11:55 INFO - } 21:11:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:11:55 INFO - Running post-action listener: _resource_record_post_action 21:11:55 INFO - [mozharness: 2016-12-17 05:11:55.582707Z] Finished read-buildbot-config step (success) 21:11:55 INFO - [mozharness: 2016-12-17 05:11:55.582794Z] Running download-and-extract step. 21:11:55 INFO - Running pre-action listener: _resource_record_pre_action 21:11:55 INFO - Running main action method: download_and_extract 21:11:55 INFO - mkdir: /builds/slave/test/build/tests 21:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:11:55 INFO - trying https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:11:55 INFO - Downloading https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:11:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:11:56 INFO - Downloaded 1361 bytes. 21:11:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:11:56 INFO - Using the following test package requirements: 21:11:56 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:11:56 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:11:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:11:56 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:11:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:11:56 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:11:56 INFO - u'jsshell-linux-x86_64.zip'], 21:11:56 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:11:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:11:56 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:11:56 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:11:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:11:56 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:11:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:11:56 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:11:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:11:56 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:11:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:11:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:11:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:11:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:11:56 INFO - Fetch https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:11:56 INFO - Expected file size: 21776718 21:11:56 INFO - Obtained file size: 21776718 21:12:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:12:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:12:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:12:01 INFO - Fetch https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:12:01 INFO - Expected file size: 21776718 21:12:01 INFO - Obtained file size: 21776718 21:12:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:12:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:12:06 INFO - Fetch https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:12:07 INFO - Expected file size: 14409734 21:12:07 INFO - Obtained file size: 14409734 21:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:07 INFO - trying https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:12:07 INFO - Downloading https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:12:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:12:09 INFO - Downloaded 59769600 bytes. 21:12:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:12:09 INFO - mkdir: /builds/slave/test/properties 21:12:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:12:09 INFO - Writing to file /builds/slave/test/properties/build_url 21:12:09 INFO - Contents: 21:12:09 INFO - build_url:https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:12:09 INFO - Running post-action listener: _resource_record_post_action 21:12:09 INFO - Running post-action listener: set_extra_try_arguments 21:12:09 INFO - [mozharness: 2016-12-17 05:12:09.728811Z] Finished download-and-extract step (success) 21:12:09 INFO - [mozharness: 2016-12-17 05:12:09.729035Z] Running populate-webroot step. 21:12:09 INFO - Running pre-action listener: _resource_record_pre_action 21:12:09 INFO - Running main action method: populate_webroot 21:12:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:12:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:12:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:12:09 INFO - '--tppagecycles', 21:12:09 INFO - '7'], 21:12:09 INFO - 'tests': ['tsvgm']}}, 21:12:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:12:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:12:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:12:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:12:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:12:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:12:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:12:09 INFO - 'talos_options': ['--disable-e10s'], 21:12:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:12:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:12:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:12:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:12:09 INFO - 'talos_options': ['--disable-e10s'], 21:12:09 INFO - 'tests': ['damp', 'tps']}, 21:12:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:12:09 INFO - 'tests': ['damp', 'tps']}, 21:12:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:12:09 INFO - 'tests': ['dromaeo_dom']}, 21:12:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:12:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:12:09 INFO - 'tests': ['basic_compositor_video']}, 21:12:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:12:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:12:09 INFO - 'tests': ['a11yr', 21:12:09 INFO - 'ts_paint', 21:12:09 INFO - 'tpaint', 21:12:09 INFO - 'sessionrestore', 21:12:09 INFO - 'sessionrestore_no_auto_restore', 21:12:09 INFO - 'tabpaint']}, 21:12:09 INFO - 'other-e10s': {'tests': ['a11yr', 21:12:09 INFO - 'ts_paint', 21:12:09 INFO - 'tpaint', 21:12:09 INFO - 'sessionrestore', 21:12:09 INFO - 'sessionrestore_no_auto_restore', 21:12:09 INFO - 'tabpaint']}, 21:12:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:12:09 INFO - 'ts_paint', 21:12:09 INFO - 'tpaint', 21:12:09 INFO - 'sessionrestore', 21:12:09 INFO - 'sessionrestore_no_auto_restore', 21:12:09 INFO - 'tabpaint']}, 21:12:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:12:09 INFO - 'ts_paint', 21:12:09 INFO - 'tpaint', 21:12:09 INFO - 'sessionrestore', 21:12:09 INFO - 'sessionrestore_no_auto_restore', 21:12:09 INFO - 'tabpaint']}, 21:12:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:12:09 INFO - 'tests': ['a11yr', 21:12:09 INFO - 'ts_paint', 21:12:09 INFO - 'tpaint', 21:12:09 INFO - 'sessionrestore', 21:12:09 INFO - 'sessionrestore_no_auto_restore', 21:12:09 INFO - 'tabpaint']}, 21:12:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:12:09 INFO - 'tests': ['a11yr', 21:12:09 INFO - 'ts_paint', 21:12:09 INFO - 'tpaint', 21:12:09 INFO - 'sessionrestore', 21:12:09 INFO - 'sessionrestore_no_auto_restore', 21:12:09 INFO - 'tabpaint']}, 21:12:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:12:09 INFO - 'tests': ['tsvgx', 21:12:09 INFO - 'tsvgr_opacity', 21:12:09 INFO - 'tart', 21:12:09 INFO - 'tscrollx', 21:12:09 INFO - 'cart', 21:12:09 INFO - 'tsvg_static']}, 21:12:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:12:09 INFO - 'tsvgr_opacity', 21:12:09 INFO - 'tart', 21:12:09 INFO - 'tscrollx', 21:12:09 INFO - 'cart', 21:12:09 INFO - 'tsvg_static']}, 21:12:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:12:09 INFO - 'talos_options': ['--disable-e10s'], 21:12:09 INFO - 'tests': ['tp5o']}, 21:12:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:12:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:12:09 INFO - 'talos_options': ['--disable-e10s', 21:12:09 INFO - '--xperf_path', 21:12:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:12:09 INFO - 'tests': ['tp5n']}, 21:12:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:12:09 INFO - 'talos_options': ['--xperf_path', 21:12:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:12:09 INFO - 'tests': ['tp5n']}}, 21:12:09 INFO - 'talos.zip': {'path': '', 21:12:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:12:09 INFO - Running post-action listener: _resource_record_post_action 21:12:09 INFO - [mozharness: 2016-12-17 05:12:09.749786Z] Finished populate-webroot step (success) 21:12:09 INFO - [mozharness: 2016-12-17 05:12:09.749873Z] Running create-virtualenv step. 21:12:09 INFO - Running pre-action listener: _resource_record_pre_action 21:12:09 INFO - Running main action method: create_virtualenv 21:12:09 INFO - Creating virtualenv /builds/slave/test/build/venv 21:12:09 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 21:12:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:12:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:12:09 INFO - Using real prefix '/usr' 21:12:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:12:10 INFO - Installing distribute.............................................................................................................................................................................................done. 21:12:12 INFO - Installing pip.................done. 21:12:12 INFO - Return code: 0 21:12:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:12:12 INFO - Reading from file tmpfile_stdout 21:12:12 INFO - Output received: 21:12:12 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:12:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:12 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 0x7f207ea18e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2541a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26df120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276ff20>, '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 0x26dcc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dcf90>, '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': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', '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 21:12:12 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 21:12:12 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 21:12:12 INFO - Using env: {'DISPLAY': ':0', 21:12:12 INFO - 'HOME': '/home/cltbld', 21:12:12 INFO - 'LANG': 'en_US.UTF-8', 21:12:12 INFO - 'LANGUAGE': 'en_US:en', 21:12:12 INFO - 'LOGNAME': 'cltbld', 21:12:12 INFO - 'MAIL': '/var/mail/cltbld', 21:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:12 INFO - 'MOZ_NO_REMOTE': '1', 21:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:12 INFO - 'NO_EM_RESTART': '1', 21:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:12 INFO - 'PWD': '/builds/slave/test', 21:12:12 INFO - 'SHELL': '/bin/bash', 21:12:12 INFO - 'SHLVL': '1', 21:12:12 INFO - 'TERM': 'linux', 21:12:12 INFO - 'TMOUT': '86400', 21:12:12 INFO - 'USER': 'cltbld', 21:12:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', 21:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:12 INFO - '_': '/tools/buildbot/bin/python'} 21:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:12 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 21:12:12 INFO - Cleaning up... 21:12:12 INFO - Return code: 0 21:12:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:12 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 0x7f207ea18e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2541a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26df120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276ff20>, '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 0x26dcc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dcf90>, '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': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', '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 21:12:12 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 21:12:12 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 21:12:12 INFO - Using env: {'DISPLAY': ':0', 21:12:12 INFO - 'HOME': '/home/cltbld', 21:12:12 INFO - 'LANG': 'en_US.UTF-8', 21:12:12 INFO - 'LANGUAGE': 'en_US:en', 21:12:12 INFO - 'LOGNAME': 'cltbld', 21:12:12 INFO - 'MAIL': '/var/mail/cltbld', 21:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:12 INFO - 'MOZ_NO_REMOTE': '1', 21:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:12 INFO - 'NO_EM_RESTART': '1', 21:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:12 INFO - 'PWD': '/builds/slave/test', 21:12:12 INFO - 'SHELL': '/bin/bash', 21:12:12 INFO - 'SHLVL': '1', 21:12:12 INFO - 'TERM': 'linux', 21:12:12 INFO - 'TMOUT': '86400', 21:12:12 INFO - 'USER': 'cltbld', 21:12:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', 21:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:12 INFO - '_': '/tools/buildbot/bin/python'} 21:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:12 INFO - Downloading/unpacking psutil>=3.1.1 21:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:12:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:12:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:12:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:12:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:12:15 INFO - Installing collected packages: psutil 21:12:15 INFO - Running setup.py install for psutil 21:12:15 INFO - building 'psutil._psutil_linux' extension 21:12:15 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 21:12:15 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 21:12:15 INFO - building 'psutil._psutil_posix' extension 21:12:15 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 21:12:15 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 21:12:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:12:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:12:15 INFO - Successfully installed psutil 21:12:15 INFO - Cleaning up... 21:12:15 INFO - Return code: 0 21:12:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:12:15 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')]} 21:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:15 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')]} 21:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:15 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 0x7f207ea18e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2541a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26df120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276ff20>, '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 0x26dcc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dcf90>, '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': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', '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 21:12:15 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 21:12:15 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 21:12:15 INFO - Using env: {'DISPLAY': ':0', 21:12:15 INFO - 'HOME': '/home/cltbld', 21:12:15 INFO - 'LANG': 'en_US.UTF-8', 21:12:15 INFO - 'LANGUAGE': 'en_US:en', 21:12:15 INFO - 'LOGNAME': 'cltbld', 21:12:15 INFO - 'MAIL': '/var/mail/cltbld', 21:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:15 INFO - 'MOZ_NO_REMOTE': '1', 21:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:15 INFO - 'NO_EM_RESTART': '1', 21:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:15 INFO - 'PWD': '/builds/slave/test', 21:12:15 INFO - 'SHELL': '/bin/bash', 21:12:15 INFO - 'SHLVL': '1', 21:12:15 INFO - 'TERM': 'linux', 21:12:15 INFO - 'TMOUT': '86400', 21:12:15 INFO - 'USER': 'cltbld', 21:12:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', 21:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:15 INFO - '_': '/tools/buildbot/bin/python'} 21:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:12:15 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 21:12:15 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 21:12:15 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 21:12:15 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 21:12:15 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 21:12:15 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 21:12:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:12:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:12:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:12:17 INFO - Installing collected packages: mozsystemmonitor 21:12:17 INFO - Running setup.py install for mozsystemmonitor 21:12:17 INFO - Successfully installed mozsystemmonitor 21:12:17 INFO - Cleaning up... 21:12:17 INFO - Return code: 0 21:12:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f207ea18e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2541a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26df120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276ff20>, '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 0x26dcc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dcf90>, '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': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', '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 21:12:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:12:17 INFO - Using env: {'DISPLAY': ':0', 21:12:17 INFO - 'HOME': '/home/cltbld', 21:12:17 INFO - 'LANG': 'en_US.UTF-8', 21:12:17 INFO - 'LANGUAGE': 'en_US:en', 21:12:17 INFO - 'LOGNAME': 'cltbld', 21:12:17 INFO - 'MAIL': '/var/mail/cltbld', 21:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:17 INFO - 'MOZ_NO_REMOTE': '1', 21:12:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:17 INFO - 'NO_EM_RESTART': '1', 21:12:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:17 INFO - 'PWD': '/builds/slave/test', 21:12:17 INFO - 'SHELL': '/bin/bash', 21:12:17 INFO - 'SHLVL': '1', 21:12:17 INFO - 'TERM': 'linux', 21:12:17 INFO - 'TMOUT': '86400', 21:12:17 INFO - 'USER': 'cltbld', 21:12:17 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', 21:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:17 INFO - '_': '/tools/buildbot/bin/python'} 21:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:18 INFO - Downloading/unpacking jsonschema==2.5.1 21:12:18 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 21:12:18 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 21:12:18 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 21:12:18 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 21:12:18 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 21:12:18 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 21:12:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:12:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:12:20 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 21:12:20 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 21:12:20 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 21:12:20 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 21:12:20 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 21:12:20 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 21:12:20 INFO - Downloading functools32-3.2.3-2.tar.gz 21:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:12:20 INFO - warning: no files found matching '*.txt' 21:12:20 INFO - no previously-included directories found matching 'build' 21:12:20 INFO - no previously-included directories found matching 'dist' 21:12:20 INFO - no previously-included directories found matching '.git*' 21:12:20 INFO - Installing collected packages: jsonschema, functools32 21:12:20 INFO - Running setup.py install for functools32 21:12:20 INFO - warning: no files found matching '*.txt' 21:12:20 INFO - no previously-included directories found matching 'build' 21:12:20 INFO - no previously-included directories found matching 'dist' 21:12:20 INFO - no previously-included directories found matching '.git*' 21:12:20 INFO - Successfully installed jsonschema functools32 21:12:20 INFO - Cleaning up... 21:12:20 INFO - Return code: 0 21:12:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:20 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 0x7f207ea18e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2541a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26df120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276ff20>, '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 0x26dcc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dcf90>, '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': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', '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 21:12:20 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 21:12:20 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 21:12:20 INFO - Using env: {'DISPLAY': ':0', 21:12:20 INFO - 'HOME': '/home/cltbld', 21:12:20 INFO - 'LANG': 'en_US.UTF-8', 21:12:20 INFO - 'LANGUAGE': 'en_US:en', 21:12:20 INFO - 'LOGNAME': 'cltbld', 21:12:20 INFO - 'MAIL': '/var/mail/cltbld', 21:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:20 INFO - 'MOZ_NO_REMOTE': '1', 21:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:20 INFO - 'NO_EM_RESTART': '1', 21:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:20 INFO - 'PWD': '/builds/slave/test', 21:12:20 INFO - 'SHELL': '/bin/bash', 21:12:20 INFO - 'SHLVL': '1', 21:12:20 INFO - 'TERM': 'linux', 21:12:20 INFO - 'TMOUT': '86400', 21:12:20 INFO - 'USER': 'cltbld', 21:12:20 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', 21:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:20 INFO - '_': '/tools/buildbot/bin/python'} 21:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:12:20 INFO - Cleaning up... 21:12:20 INFO - Return code: 0 21:12:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:20 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 0x7f207ea18e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2541a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26df120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276ff20>, '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 0x26dcc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dcf90>, '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': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', '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 21:12:20 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 21:12:20 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 21:12:20 INFO - Using env: {'DISPLAY': ':0', 21:12:20 INFO - 'HOME': '/home/cltbld', 21:12:20 INFO - 'LANG': 'en_US.UTF-8', 21:12:20 INFO - 'LANGUAGE': 'en_US:en', 21:12:20 INFO - 'LOGNAME': 'cltbld', 21:12:20 INFO - 'MAIL': '/var/mail/cltbld', 21:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:20 INFO - 'MOZ_NO_REMOTE': '1', 21:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:20 INFO - 'NO_EM_RESTART': '1', 21:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:20 INFO - 'PWD': '/builds/slave/test', 21:12:20 INFO - 'SHELL': '/bin/bash', 21:12:20 INFO - 'SHLVL': '1', 21:12:20 INFO - 'TERM': 'linux', 21:12:20 INFO - 'TMOUT': '86400', 21:12:20 INFO - 'USER': 'cltbld', 21:12:20 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', 21:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:20 INFO - '_': '/tools/buildbot/bin/python'} 21:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:21 INFO - Downloading/unpacking blobuploader==1.2.4 21:12:21 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 21:12:21 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 21:12:21 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 21:12:21 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 21:12:21 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 21:12:21 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 21:12:23 INFO - Downloading blobuploader-1.2.4.tar.gz 21:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:12:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:12:23 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 21:12:23 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 21:12:23 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 21:12:23 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 21:12:23 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 21:12:23 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 21:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:12:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:12:23 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 21:12:23 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 21:12:23 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 21:12:23 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 21:12:23 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 21:12:23 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 21:12:23 INFO - Downloading docopt-0.6.1.tar.gz 21:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:12:23 INFO - Installing collected packages: blobuploader, requests, docopt 21:12:23 INFO - Running setup.py install for blobuploader 21:12:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:12:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:12:23 INFO - Running setup.py install for requests 21:12:24 INFO - Running setup.py install for docopt 21:12:24 INFO - Successfully installed blobuploader requests docopt 21:12:24 INFO - Cleaning up... 21:12:24 INFO - Return code: 0 21:12:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:12:24 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')]} 21:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:24 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')]} 21:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:24 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 0x7f207ea18e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2541a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26df120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276ff20>, '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 0x26dcc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dcf90>, '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': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', '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 21:12:24 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 21:12:24 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 21:12:24 INFO - Using env: {'DISPLAY': ':0', 21:12:24 INFO - 'HOME': '/home/cltbld', 21:12:24 INFO - 'LANG': 'en_US.UTF-8', 21:12:24 INFO - 'LANGUAGE': 'en_US:en', 21:12:24 INFO - 'LOGNAME': 'cltbld', 21:12:24 INFO - 'MAIL': '/var/mail/cltbld', 21:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:24 INFO - 'MOZ_NO_REMOTE': '1', 21:12:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:24 INFO - 'NO_EM_RESTART': '1', 21:12:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:24 INFO - 'PWD': '/builds/slave/test', 21:12:24 INFO - 'SHELL': '/bin/bash', 21:12:24 INFO - 'SHLVL': '1', 21:12:24 INFO - 'TERM': 'linux', 21:12:24 INFO - 'TMOUT': '86400', 21:12:24 INFO - 'USER': 'cltbld', 21:12:24 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', 21:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:24 INFO - '_': '/tools/buildbot/bin/python'} 21:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:12:24 INFO - Running setup.py (path:/tmp/pip-jvSXL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:12:24 INFO - Running setup.py (path:/tmp/pip-t8MMHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:12:24 INFO - Running setup.py (path:/tmp/pip-Y2bxaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:12:24 INFO - Running setup.py (path:/tmp/pip-iWTpKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:12:24 INFO - Running setup.py (path:/tmp/pip-IwBQjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:12:25 INFO - Running setup.py (path:/tmp/pip-KHmE8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:12:25 INFO - Running setup.py (path:/tmp/pip-_t2GUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:12:25 INFO - Running setup.py (path:/tmp/pip-1c4tic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:12:25 INFO - Running setup.py (path:/tmp/pip-rN6PCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:12:25 INFO - Running setup.py (path:/tmp/pip-PHXHZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:12:25 INFO - Running setup.py (path:/tmp/pip-H4dUAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:12:25 INFO - Running setup.py (path:/tmp/pip-JmcmxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:12:25 INFO - Running setup.py (path:/tmp/pip-Jprk0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:12:25 INFO - Running setup.py (path:/tmp/pip-oXlfVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:12:25 INFO - Running setup.py (path:/tmp/pip-KWISCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:12:25 INFO - Running setup.py (path:/tmp/pip-0Ie1oA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:12:25 INFO - Running setup.py (path:/tmp/pip-x7COkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:12:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:12:25 INFO - Running setup.py install for manifestparser 21:12:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:12:25 INFO - Running setup.py install for mozcrash 21:12:26 INFO - Running setup.py install for mozdebug 21:12:26 INFO - Running setup.py install for mozdevice 21:12:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:12:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:12:26 INFO - Running setup.py install for mozfile 21:12:26 INFO - Running setup.py install for mozhttpd 21:12:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:12:26 INFO - Running setup.py install for mozinfo 21:12:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:12:26 INFO - Running setup.py install for mozInstall 21:12:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:12:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:12:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:12:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:12:26 INFO - Running setup.py install for mozleak 21:12:26 INFO - Running setup.py install for mozlog 21:12:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:12:26 INFO - Running setup.py install for moznetwork 21:12:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:12:27 INFO - Running setup.py install for mozprocess 21:12:27 INFO - Running setup.py install for mozprofile 21:12:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:12:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:12:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:12:27 INFO - Running setup.py install for mozrunner 21:12:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:12:27 INFO - Running setup.py install for mozscreenshot 21:12:27 INFO - Running setup.py install for moztest 21:12:27 INFO - Running setup.py install for mozversion 21:12:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:12:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:12:27 INFO - Cleaning up... 21:12:27 INFO - Return code: 0 21:12:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:12:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:27 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 0x7f207ea18e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2541a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26df120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276ff20>, '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 0x26dcc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dcf90>, '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': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', '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 21:12:27 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 21:12:27 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 21:12:27 INFO - Using env: {'DISPLAY': ':0', 21:12:27 INFO - 'HOME': '/home/cltbld', 21:12:27 INFO - 'LANG': 'en_US.UTF-8', 21:12:27 INFO - 'LANGUAGE': 'en_US:en', 21:12:27 INFO - 'LOGNAME': 'cltbld', 21:12:27 INFO - 'MAIL': '/var/mail/cltbld', 21:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:27 INFO - 'MOZ_NO_REMOTE': '1', 21:12:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:27 INFO - 'NO_EM_RESTART': '1', 21:12:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:27 INFO - 'PWD': '/builds/slave/test', 21:12:27 INFO - 'SHELL': '/bin/bash', 21:12:27 INFO - 'SHLVL': '1', 21:12:27 INFO - 'TERM': 'linux', 21:12:27 INFO - 'TMOUT': '86400', 21:12:27 INFO - 'USER': 'cltbld', 21:12:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', 21:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:27 INFO - '_': '/tools/buildbot/bin/python'} 21:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:12:27 INFO - Running setup.py (path:/tmp/pip-Vv9d_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:12:27 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)) 21:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:12:27 INFO - Running setup.py (path:/tmp/pip-EHkuEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:12:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:12:28 INFO - Running setup.py (path:/tmp/pip-gBx5XD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:12:28 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)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:12:28 INFO - Running setup.py (path:/tmp/pip-vaGYQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:12:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:12:28 INFO - Running setup.py (path:/tmp/pip-9zFR7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:12:28 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)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:12:28 INFO - Running setup.py (path:/tmp/pip-moqvWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:12:28 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)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:12:28 INFO - Running setup.py (path:/tmp/pip-tAu6Zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:12:28 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)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:12:28 INFO - Running setup.py (path:/tmp/pip-pY2uLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:12:28 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)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:12:28 INFO - Running setup.py (path:/tmp/pip-RcEcBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:12:28 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)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:12:28 INFO - Running setup.py (path:/tmp/pip-OFBHCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:12:28 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)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:12:28 INFO - Running setup.py (path:/tmp/pip-exxpkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:12:28 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)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:12:28 INFO - Running setup.py (path:/tmp/pip-JtvS0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:12:28 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)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:12:28 INFO - Running setup.py (path:/tmp/pip-exJEhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:12:28 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)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:12:28 INFO - Running setup.py (path:/tmp/pip-Frq2W6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:12:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:12:28 INFO - Running setup.py (path:/tmp/pip-33xqdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:12:29 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)) 21:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:12:29 INFO - Running setup.py (path:/tmp/pip-l58sWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:12:29 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)) 21:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:12:29 INFO - Running setup.py (path:/tmp/pip-NRP1Zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:12:29 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)) 21:12:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:12:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:12:29 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)) 21:12:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:12:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:12:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:12: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 21:12: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 21:12: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 21:12: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 21:12: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 21:12: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 21:12:31 INFO - Downloading blessings-1.6.tar.gz 21:12:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:12:31 INFO - Installing collected packages: blessings 21:12:31 INFO - Running setup.py install for blessings 21:12:31 INFO - Successfully installed blessings 21:12:31 INFO - Cleaning up... 21:12:31 INFO - Return code: 0 21:12:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:12:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:12:31 INFO - Reading from file tmpfile_stdout 21:12:31 INFO - Current package versions: 21:12:31 INFO - argparse == 1.2.1 21:12:31 INFO - blessings == 1.6 21:12:31 INFO - blobuploader == 1.2.4 21:12:31 INFO - docopt == 0.6.1 21:12:31 INFO - functools32 == 3.2.3-2 21:12:31 INFO - jsonschema == 2.5.1 21:12:31 INFO - manifestparser == 1.1 21:12:31 INFO - mozInstall == 1.12 21:12:31 INFO - mozcrash == 1.0 21:12:31 INFO - mozdebug == 0.1 21:12:31 INFO - mozdevice == 0.49 21:12:31 INFO - mozfile == 1.2 21:12:31 INFO - mozhttpd == 0.7 21:12:31 INFO - mozinfo == 0.9 21:12:31 INFO - mozleak == 0.1 21:12:31 INFO - mozlog == 3.4 21:12:31 INFO - moznetwork == 0.27 21:12:31 INFO - mozprocess == 0.23 21:12:31 INFO - mozprofile == 0.28 21:12:31 INFO - mozrunner == 6.13 21:12:31 INFO - mozscreenshot == 0.1 21:12:31 INFO - mozsystemmonitor == 0.3 21:12:31 INFO - moztest == 0.7 21:12:31 INFO - mozversion == 1.4 21:12:31 INFO - psutil == 3.1.1 21:12:31 INFO - requests == 1.2.3 21:12:31 INFO - wsgiref == 0.1.2 21:12:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:12: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')]} 21:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12: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')]} 21:12:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12:31 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 0x7f207ea18e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2541a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26df120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276ff20>, '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 0x26dcc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dcf90>, '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': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', '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 21:12:31 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 21:12:31 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 21:12:31 INFO - Using env: {'DISPLAY': ':0', 21:12:31 INFO - 'HOME': '/home/cltbld', 21:12:31 INFO - 'LANG': 'en_US.UTF-8', 21:12:31 INFO - 'LANGUAGE': 'en_US:en', 21:12:31 INFO - 'LOGNAME': 'cltbld', 21:12:31 INFO - 'MAIL': '/var/mail/cltbld', 21:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:31 INFO - 'MOZ_NO_REMOTE': '1', 21:12:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:31 INFO - 'NO_EM_RESTART': '1', 21:12:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:31 INFO - 'PWD': '/builds/slave/test', 21:12:31 INFO - 'SHELL': '/bin/bash', 21:12:31 INFO - 'SHLVL': '1', 21:12:31 INFO - 'TERM': 'linux', 21:12:31 INFO - 'TMOUT': '86400', 21:12:31 INFO - 'USER': 'cltbld', 21:12:31 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', 21:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:31 INFO - '_': '/tools/buildbot/bin/python'} 21:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:31 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)) 21:12:31 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)) 21:12:31 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)) 21:12:31 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)) 21:12:31 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)) 21:12:31 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)) 21:12:31 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)) 21:12:31 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)) 21:12:31 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)) 21:12:31 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)) 21:12:31 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)) 21:12:31 INFO - Cleaning up... 21:12:31 INFO - Return code: 0 21:12:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:12: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')]} 21:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:12:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12: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')]} 21:12:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:12:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:12: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f207ea18e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2541a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26df120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x276ff20>, '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 0x26dcc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dcf90>, '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': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', '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 21:12: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', 'jsonschema'] in /builds/slave/test/build 21:12: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 jsonschema 21:12:31 INFO - Using env: {'DISPLAY': ':0', 21:12:31 INFO - 'HOME': '/home/cltbld', 21:12:31 INFO - 'LANG': 'en_US.UTF-8', 21:12:31 INFO - 'LANGUAGE': 'en_US:en', 21:12:31 INFO - 'LOGNAME': 'cltbld', 21:12:31 INFO - 'MAIL': '/var/mail/cltbld', 21:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:31 INFO - 'MOZ_NO_REMOTE': '1', 21:12:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:31 INFO - 'NO_EM_RESTART': '1', 21:12:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:31 INFO - 'PWD': '/builds/slave/test', 21:12:31 INFO - 'SHELL': '/bin/bash', 21:12:31 INFO - 'SHLVL': '1', 21:12:31 INFO - 'TERM': 'linux', 21:12:31 INFO - 'TMOUT': '86400', 21:12:31 INFO - 'USER': 'cltbld', 21:12:31 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', 21:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:31 INFO - '_': '/tools/buildbot/bin/python'} 21:12:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:12:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:12:32 INFO - Cleaning up... 21:12:32 INFO - Return code: 0 21:12:32 INFO - Running post-action listener: _resource_record_post_action 21:12:32 INFO - Running post-action listener: _start_resource_monitoring 21:12:32 INFO - Starting resource monitoring. 21:12:32 INFO - [mozharness: 2016-12-17 05:12:32.074815Z] Finished create-virtualenv step (success) 21:12:32 INFO - [mozharness: 2016-12-17 05:12:32.075283Z] Running install step. 21:12:32 INFO - Running pre-action listener: _resource_record_pre_action 21:12:32 INFO - Running main action method: install 21:12:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:12:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:12:32 INFO - Reading from file tmpfile_stdout 21:12:32 INFO - Detecting whether we're running mozinstall >=1.0... 21:12:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:12:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:12:32 INFO - Reading from file tmpfile_stdout 21:12:32 INFO - Output received: 21:12:32 INFO - Usage: mozinstall [options] installer 21:12:32 INFO - Options: 21:12:32 INFO - -h, --help show this help message and exit 21:12:32 INFO - -d DEST, --destination=DEST 21:12:32 INFO - Directory to install application into. [default: 21:12:32 INFO - "/builds/slave/test"] 21:12:32 INFO - --app=APP Application being installed. [default: firefox] 21:12:32 INFO - mkdir: /builds/slave/test/build/application 21:12:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:12:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:12:49 INFO - Reading from file tmpfile_stdout 21:12:49 INFO - Output received: 21:12:49 INFO - /builds/slave/test/build/application/firefox/firefox 21:12:49 INFO - Running post-action listener: _resource_record_post_action 21:12:49 INFO - [mozharness: 2016-12-17 05:12:49.391920Z] Finished install step (success) 21:12:49 INFO - [mozharness: 2016-12-17 05:12:49.392158Z] Running run-tests step. 21:12:49 INFO - Running pre-action listener: _resource_record_pre_action 21:12:49 INFO - Running main action method: run_tests 21:12:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:12:49 INFO - Python 2.7.3 21:12:49 INFO - Return code: 0 21:12:49 INFO - grabbing minidump binary from tooltool 21:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:12:49 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 0x276ff20>, '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 0x26dcc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dcf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:12:49 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 21:12:49 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 21:12:49 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 21:12:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:12:49 INFO - Return code: 0 21:12:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:12:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:12:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:12:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:12:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:12:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 21:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 21:12:49 INFO - Using env: {'DISPLAY': ':0', 21:12:49 INFO - 'HOME': '/home/cltbld', 21:12:49 INFO - 'LANG': 'en_US.UTF-8', 21:12:49 INFO - 'LANGUAGE': 'en_US:en', 21:12:49 INFO - 'LOGNAME': 'cltbld', 21:12:49 INFO - 'MAIL': '/var/mail/cltbld', 21:12:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:12:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:12:49 INFO - 'MOZ_NO_REMOTE': '1', 21:12:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:12:49 INFO - 'NO_EM_RESTART': '1', 21:12:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:12:49 INFO - 'PWD': '/builds/slave/test', 21:12:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:12:49 INFO - 'SHELL': '/bin/bash', 21:12:49 INFO - 'SHLVL': '1', 21:12:49 INFO - 'TERM': 'linux', 21:12:49 INFO - 'TMOUT': '86400', 21:12:49 INFO - 'USER': 'cltbld', 21:12:49 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327', 21:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:12:49 INFO - '_': '/tools/buildbot/bin/python'} 21:12:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 21:12:49 INFO - mozversion application_buildid: 20161216193202 21:12:49 INFO - mozversion application_changeset: 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 21:12:49 INFO - mozversion application_display_name: Nightly 21:12:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:12:49 INFO - mozversion application_name: Firefox 21:12:49 INFO - mozversion application_remotingname: firefox 21:12:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:12:49 INFO - mozversion application_vendor: Mozilla 21:12:49 INFO - mozversion application_version: 53.0a1 21:12:49 INFO - mozversion platform_buildid: 20161216193202 21:12:49 INFO - mozversion platform_changeset: 34a1ab064cb5b868fa75cb74d052e978eb34d6c1 21:12:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:12:49 INFO - mozversion platform_version: 53.0a1 21:12:49 INFO - using testdate: 1481951569 21:12:49 INFO - actual date: 1481951569 21:12:49 INFO - starting webserver on 'localhost:34570' 21:12:49 INFO - SUITE-START | Running 2 tests 21:12:49 INFO - TEST-START | dromaeo_css 21:12:49 INFO - operating with platform_type : linux_ 21:12:49 INFO - Initialising browser for dromaeo_css test... 21:12:49 INFO - TEST-INFO | started process 6343 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK4lisc/profile http://localhost:34570/getInfo.html) 21:12:53 INFO - PROCESS | 6343 | __metrics Screen width/height:1600/1200 21:12:53 INFO - PROCESS | 6343 | colorDepth:24 21:12:53 INFO - PROCESS | 6343 | Browser inner width/height: 1024/697 21:12:53 INFO - PROCESS | 6343 | __metrics 21:12:55 INFO - PROCESS | 6343 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:12:55 INFO - PROCESS | 6343 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:12:55 INFO - PROCESS | 6343 | console.error: PushService: 21:12:55 INFO - PROCESS | 6343 | stateChangeProcessEnqueue: Error transitioning state 21:12:55 INFO - PROCESS | 6343 | UnknownError 21:12:56 INFO - TEST-INFO | 6343: exit 0 21:12:56 INFO - Browser initialized. 21:12:56 INFO - Running cycle 1/1 for dromaeo_css test... 21:12:56 INFO - TEST-INFO | started process 6427 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK4lisc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:13:08 INFO - PROCESS | 6427 | RSS: Main: 154066944 21:13:08 INFO - PROCESS | 6427 | 21:13:59 INFO - PROCESS | 6427 | 1481951639454 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] 21:16:10 INFO - PROCESS | 6427 | Cycle 1(1): loaded http://localhost:34570/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34570/tests/dromaeo/cssquery-ext.html) 21:16:11 INFO - PROCESS | 6427 | RSS: Main: 258768896 21:16:11 INFO - PROCESS | 6427 | 21:19:14 INFO - PROCESS | 6427 | Cycle 1(1): loaded http://localhost:34570/tests/dromaeo/cssquery-ext.html (next: http://localhost:34570/tests/dromaeo/cssquery-jquery.html) 21:19:14 INFO - PROCESS | 6427 | RSS: Main: 254214144 21:19:14 INFO - PROCESS | 6427 | 21:22:16 INFO - PROCESS | 6427 | Cycle 1(1): loaded http://localhost:34570/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34570/tests/dromaeo/cssquery-mootools.html) 21:22:17 INFO - PROCESS | 6427 | RSS: Main: 228655104 21:22:17 INFO - PROCESS | 6427 | 21:25:19 INFO - PROCESS | 6427 | Cycle 1(1): loaded http://localhost:34570/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34570/tests/dromaeo/cssquery-prototype.html) 21:25:20 INFO - PROCESS | 6427 | RSS: Main: 249061376 21:25:20 INFO - PROCESS | 6427 | 21:28:23 INFO - PROCESS | 6427 | Cycle 1(1): loaded http://localhost:34570/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34570/tests/dromaeo/cssquery-yui.html) 21:28:23 INFO - PROCESS | 6427 | RSS: Main: 190083072 21:28:23 INFO - PROCESS | 6427 | 21:31:26 INFO - PROCESS | 6427 | Cycle 1(1): loaded http://localhost:34570/tests/dromaeo/cssquery-yui.html (next: http://localhost:34570/tests/dromaeo/cssquery-dojo.html) 21:31:26 INFO - PROCESS | 6427 | RSS: Main: 282034176 21:31:26 INFO - PROCESS | 6427 | 21:31:26 INFO - PROCESS | 6427 | __start_tp_report 21:31:26 INFO - PROCESS | 6427 | _x_x_mozilla_page_load 21:31:26 INFO - PROCESS | 6427 | _x_x_mozilla_page_load_details 21:31:26 INFO - PROCESS | 6427 | |i|pagename|runs| 21:31:26 INFO - PROCESS | 6427 | |0;dojo.html;2951.985240073799;2988.417258634567;2991.0877182459353;3002.129382479081;3029.1366960416285;5028.472010438906;5044.974775126119;5162.993216322819;5279.41926388097;5298.12580924148;5106.5148810863;5116.309298244736;5159.251908473269;5168.586513078954;5247.658902171365;4675.649326300524;4711.151992641323;4726.26742854858;4742.644301677371;4758.833440829571;4718.57532822046;4730.290456431546;4755.048990201956;4809.759512024402;4812.157872372339;4813.350197723333;4871.488493708162;4875.926861097087;4895.388076490439;4904.607631389498;4544.636429085665;4547.363369128325;4561.70348927322;4624.491305956342;4775.9283610745515;4590.380298659672;4596.4254468191475;4599.2871104978785;4599.31010348447;4606.216943119644;4958.7024778513405;4979.8008079676765;5000.499950004971;5005.2492126181;5009.724465154415;5331.946680533222;5480.534154596864;5528.917066244009;5541.390447050821;5617.606767526275;5594.412586678422;5599.7480113394995;5611.3546942101775;5611.719414029323;5614.859628509279;5224.9738751306;5239.21411788231;5250.18622113573;5255.001549705582;5298.125809241432;5346.946530534645;5392.5416339611575;5613.635113717595;5639.943600564024;5658.462446067617;5481.972590137024;5491.862703432446;5495.890082198333;5498.642588231752;5500.037493438632;5575.749091290902;5575.888482230333;5577.191307260425;5578.63738856973;5579.358373787067;5390.164524498709;5442.020436321421;5474.616776825584;5512.889742205133;5573.5819813514145;5558.888822223533;5560.833175004756;5563.304586926634;5581.581381396411;5602;4279.144171165779;4288.442502474658;4291.892702682396;4293.4203882476095;4333.090051089243;3734.8132593370224;3752.2307926874923;3755.3428150074174;3826.1964987352967;3847.942280865789;5226.503482169264;5255.132903071027;5280.683159010395;5297.258383826267;5348.5186333230195;5570.749316280777;5571.609987300931;5573.331200255995;5578.24693666358;5588.972055139699;5025.522575355335;5156.92264616031;5193.221016847428;5212.244224587414;5264.92102618461;4605;4668.50980647034;4718.05638872227;4720.787564559603;4721.622270218374;5521.641093328921;5548.5838562108;5560.026995275811;5563.165525171256;5564.666120032811;5397.325334333208;5402.216678581663;5406.107992181246;5408.432114627907;5409.0804563223555;5470.070088085115;5512.476314750093;5517.806876759294;5518.365387980273;5564.749586268546;5391.7034563099405;5402.648827826179;5403.297571315861;5409.432009639088;5410.323709536308;5480.451954804487;5485.4788795064405;5488.945110548843;5491.176323551499;5496.422875598005;5346.866328341823;5348.786048558014;5355.866103347447;5427.538659213853;5427.5657947363325;5388.110961691277;5397.109476936262;5400.513953744182;5406.459354064719;5443.891122177455;5128.564072053768;5158.871028224325;5304.469553044664;5321.840344789663;5371.865703357447;5451.236826844166;5466.234727138124;5471.042567550742;5479.863003424946;5511.862203444946;5358.812441564526;5365.543928766098;5366.409694933476;5367.543758780547;5415.160650099241 21:31:26 INFO - PROCESS | 6427 | |1;ext.html;547.4388751529682;573.538301667158;579.8202557207263;592.2951687491882;592.6207227374479;30749.538756918635;39745;41948.79025604868;42041.789791051044;45338.7733061335;43178.56821431785;43470.78264608672;43631;43631.563684363144;43631.78184109075;8604.225619694242;8775;8779.604917778683;8781.56092195391;8784.341174411913;7673.271039251262;7900.881486777675;10021.649242276522;10260.281780275383;10270.794584108313;10484.161267098652;10557.472126393688;10558.894411055906;10559.894401056006;10560.683179504627;10400.895991040068;10439.791204175912;10510.894891051068;10511.106555942753;10513.579456821719;9926.45404502752;9995.550200240934;9999.35004224723;10002.49987500622;10008.199344052458;39364.803175983936;39520.40719389211;39631;39696.80151599253;39805.402918956235;3246.9350612987846;3309.8014119152926;3323.3851737428413;3588.6770190682964;3833.674137698271;30093;30133.849330753204;30413.543796843063;31545.52681709776;31587.36825263505;4081.7346872453195;4091.5908409158847;4104.4664193654935;4231.936520952188;4320.978395108035;320862;322792;324485;325355;325658;55490.44509554934;56262.156067659016;56364.43635563674;56371.15443268354;56422.435775641716;58315;58392.416075839545;58414;58485.70757146272;58517;16205.541501264943;16427.260773265232;16429.753553696704;16440.095794731405;16444.177791110396;10659.573617055232;11117.166212534092;12127.939360303319;12142.421485206867;12159.696007599881;18122.037503757216;19098.2360705573;20100.597988040157;20256.29102981418;20347.69478457824;7150.499465037397;7473.476856620093;7519.473636845369;7522.774316770506;7770.689172433153;857.9099194584603;966.9516524173763;976.9218462522981;1001.529281237817;1009.9040591143829;29623.703762962094;31301.217469562987;31395.215119622193;31413.37173256522;32017;1037.0666400239845;1102.7188067043014;1150.3385553306714;1158.1371877950812;1198.1972078707113;15316.310766015557;16327.265273062741;16453.259603317878;16454.177291135395;16542.586435339213;504838;509341;509690;510199;510833;55325.488157249994;55799.72100139473;55831.72084139553;55836.72081639647;55851.720741396035;1185.9229150105216;1255.1465003797505;1259.6661884600587;1310.102579732886;1330.7138965122545;25892;30716.846415767777;31026.68973310238;33269.667303326656;33526.83236583802;51816;52471;52543;52566.211506826665;52572.73713631407;468044;476623;476657;477229;477338;34480.31039379148;35326.11684707903;35803.82098089493;40273.39589906095;40968.38547421851;36655.633443665225;44397.77801111103;44697.55302446934;45377;45589.316160258284;34907.47638785471;37756;38146.809265953496;38285.6171438282;39195.80402098085;37393.81303093576;37682.8115859419;37822.81088594539;37903;38364;37906.81046594749;38076.23847522979;38496.6150338493;38843.80578097091;38890.22219555536;31801;33671;34442.13894652654;34541.65458345485;34575.30849382948;26259.868700657138;26332;26542.469150616493;26576.601350979363;26967.730322696523 21:31:26 INFO - PROCESS | 6427 | |2;jquery.html;372.92914346274216;373.68610367291467;382.87939299120774;383.4038070799908;397.4832717467294;16885.57030420545;17928.91035544822;18353.816461835377;18825.529361765944;18900;17929;18332.991685457295;18365.908170459163;18399.35602253918;18729.719054214165;5388.164834450667;5586.301712285965;5586.832395028156;5588.776448942038;5590.608657393984;5371.57027437804;5438;5597.692126933017;5628.32460104786;5705.058665320217;5678.545716342683;5681.3466451358;5688.829335119953;5690.430956904297;5691.345495268035;5705.600607957424;6147.784827531037;6178.907316390258;6186.690665466709;6186.814395568118;5337.146056630959;5455.990641731253;5494.615376923617;5505.421930697259;5510.641808282448;13066.738665226641;13271.535496257682;13455.192688438725;13455.932720336434;13469.595912122651;2152.9138834446603;2210.6462965925534;2217.667349897512;2221.0893533651124;2302.6661134135456;9469.479178645171;9803.509824508747;9826.4104153751;10319.948400258027;10385.221108416823;1836.4398858348197;1841.9815801841944;1888.9244430222775;1896.9905150474297;1930.1410872161894;73325;75168;76442;77986.61006694987;78020;10364.378137311785;10468.47657617116;10676.786464270672;10796.082333001626;10862.45687715611;14610.977231593899;14627.122372657675;14678.926605366905;14683.77974330386;14718.852811471963;7552.546847189186;7715.614219289013;7727.884081738778;7737.613119344011;7933.444658873939;7346.963265183746;7467.365273951775;7563.886541701879;7627.466077374531;7686.269804368576;4363.934540981824;4453.487848897418;4467.553244675506;4485.775711214492;4584.426946631671;1887.1224880430666;1900.1639278717596;1913.1964574878584;1918.2998205654835;1926.5183704073982;806.6773290683774;897.3135551303258;920.6363486422825;947.9810403792023;980.9754756131153;17604.559886002953;18816;18831;19210.423687289403;19429.22283108688;911.886014248219;932.7295084425441;943.5942544705842;946.1673727120091;968.8498282766183;7361;10215.795684086277;10462;10605.575776968835;10606.946965265124;427334;432053;433205;435432;436614;79414.602926985;81435.59282203551;82802.17197828065;94643;95333.52333238428;957.7270477913622;990.1830989433746;1026.338011982282;1027.7173777211326;1029.0789743179812;16351;16610.39167394945;17041.31834726597;17454.563635909202;17572.73640895362;33409.83295083509;39699;39953.80023099982;40606.59393406028;41374.79312603418;55069.724651376484;55172.17241741297;55236.72381638066;55359;56834.71582642226;7992.240737129963;8060.1133875274845;8180.83638327242;8247.917520824714;8303.169683031649;10988.450577470934;12635.3682315882;12850.614481565568;12905.290209038592;12965.22208667483;11126.944365278121;11177.776444470903;11205.215634905804;11499;11519.193656444304;10985.670429887115;11245.156613253876;11322.32066076038;11324.150688698546;11592.420378981185;10869.891301086887;10976.725581860197;11302.773944521228;11373.54505819758;11436.083798631296;7116.644167791694;7415.555066696015;7434.825393495303;8094.190580941859;8182.631781569725;8258.42191046621;10176;11248.437578120898;11326.773464530828;11662.12534059966 21:31:26 INFO - PROCESS | 6427 | |3;mootools.html;1344.1196016609124;1437.1951707044057;1464.7290251303514;1626.4388785868878;1782.0376996421924;7104.11198600175;8017;8401.201885820838;8412.705555305572;8497.405181637288;8358.122397148289;8440.32477401808;8444;8445.831083378329;8446.662133514668;1883.3973128598855;1946.5814849807289;2184.8470607057475;2219.844610877239;2220.977790222093;2111.3032699209225;2173.8369622278315;2175.8912054397215;2182.4216582605623;2198.703175071369;2159.7516285627116;2165.8700477971292;2172.9891350543226;2175.59751445983;2201.273579718697;2159.201095594632;2179;2181.6618424144285;2183.1049269799305;2183.2031308572336;2127.819135373495;2163.621366260914;2165.404513758713;2165.837562182827;2165.935021949344;8004.119546849842;8095.433319667625;8097.635606397726;8281.54451505167;8441.366897482714;4826.420829500447;4956.529129732669;4959.6528243023;4962.0324036812835;4964.255361695738;3023.3046399328277;3140.733037691776;3141.9057428277188;3144.685531446837;3164.27221738999;955.8518429643416;975.9219262458986;1028.1569113327075;1034.13132968307;1036.922230832683;11506.07951363889;11516.309021458655;11532.365719885403;11547.307161570332;11563.884361156364;4177.352510360838;4285.507166675872;4355.956440435619;4378.671599629977;4409.779511024436;9034.14175653312;9165.633374665073;9185.540722963826;9191.770205744777;9213.447193168297;4964.553190212826;4965.0566392385335;4968.950310496921;4970.950290497121;4972.577330926912;4023.6721881778944;4432.468103827497;4599.264117741145;4666.556677115668;4667;3421.4525675891737;3481.3385456763135;3491.965080349164;3537.2218112015316;3552.5559305086863;1003.9247056470793;1018.3787889387468;1019.2712210769336;1030.0420608833706;1036.4921188617523;715.2704241673464;823.4441751817499;888.1296329597031;918.9954050229707;938.0759951447818;5334.59990500714;5590.720463976785;5701.372848986608;5790.276215473066;5963.543471588258;913.2465715784372;924.5931790012373;954.9283803714749;960.3661583354952;961.5528779117744;3654.8903532894055;3744.64425879541;3850.5186851643543;3865.690744740414;3970.5433875104154;10155.644552440777;10424.322419042737;10472.895271047344;10499.370037797757;10512.57949682004;10309.123724483354;10313.587456501806;10358.772863834312;10369.118624916966;10379.11777498906;7452.254774522613;7549.50928189666;7631.656575454118;7700.884486732703;7716.2669546393;5453.26380938584;5461.836144915659;5629.240052592848;5635.436456354331;5789.1605717171615;8844.601992910462;8910.643574257216;8945.46327220369;8990.280777537911;9110.681126160553;8999.820003600022;9032.232260257952;9247.213986811197;9331.813363732552;9461.763455913382;6600.207975043026;6810.5913645181445;6815.011823285695;7373.29953654402;7496.437767167376;7553.433492487976;7643.159252482112;7672;7951.602419879098;8003.159668234752;7152.85694286101;7235.782926512213;7310.6710198040155;7368.447366447431;7441.255874412732;6982.79051628452;7177.856442871218;7218.458615603956;7255.310745479171;7442.330190282901;6257.68711564429;6425.4217120457515;6437.742490300336;6439.098526206241;6439.195100612424;4645.721256724607;4647.65142614312;4758.809647614056;4998.350214472095;5032.345795046621;4757.429108507002;4876.731779752147;4896.608271338356;5012.448630650552;5026.045051440215 21:31:26 INFO - PROCESS | 6427 | |4;prototype.html;344.5331575714906;354.58159371941105;361.6925613228755;372.69625255416815;373.8784894909155;5105.285260063594;5297.284866543016;5299.5230429261355;5312.601554883385;5332.280142180804;5197.558207552353;5209.192575150848;5214.713190774506;5223.294855194547;5227.973860130694;3772.4529943158227;3860.3823388257883;4058.736182148166;4068.1456894052285;4084.9991752020796;3987.7806720630356;4036.677065834741;4048.67610591152;4056.127932494513;4066;3968.1865217630316;3974.787689754624;3995.7003224758114;4006.8397264109544;4010.811404933085;3839.9616003839883;3927.528696556403;4007;4012.859549915768;4031.6573091287128;3481.704055155315;3531.9293614127573;3532.858685652571;3535.6287589803214;3569.821508924543;4989.925151122699;5014.674046186986;5021.748912554394;5028.220625803006;5043.42000669924;4466.397036400077;4577.610903073243;4582.839600613963;4583.381243532114;4595.379623750818;5295.947040529584;5310.60170487215;5355.223492593552;5396.514313711785;5403.405625381205;3807.333716599584;3841.8293664079706;3859.2281543691092;3862.4592557042038;3864.632859878307;5383.300170977787;5544.695041772701;5598.720063996825;5600.519584332558;5621.409751976065;3927.9607203928167;3968.8214030368126;3974.463447434617;3978.622030907059;3981.5819338969573;4014.474499690099;4017.276890159741;4023.758574485482;4027.677785777131;4032.6572241359227;4589.196890544142;4603.332516785047;4617.399738034034;4618.683675152565;4638.5593368629925;5300.628955973122;5365.490278423544;5406.269833733939;5468.28912241406;5513.2005859151;1334.5195729537359;1342.4697244588529;1346.9865301347056;1353.7901625248103;1363.8158848555518;872.5506364222431;929.5305870535336;930.3115694386106;931.3434029009532;943.7357539888842;5049.444561098277;5083.974580127076;5110.565601923878;5154.458781827929;5237.135872581058;5528.308961379827;5544.390117087117;5555.972220138954;5569.749361278752;5574.9721251393485;5248.816291429782;5252.632315737862;5263;5280.1815718563685;5317.867053323698;2789.149309460634;2840.786940979435;2859.799814012999;2865.5271880139962;2867.3835125448127;5500.697461639568;5509.421510741315;5616.213730077792;5622.409646987089;5660.660360378391;5266.278971261677;5456.727163641802;5529.612927095065;5540.279763630783;5544.556435485232;5541.5289700375915;5582.469665381782;5609.523190528851;5610.663360198401;5616.831495055155;5382.381026182039;5406.026800217971;5422.8373148805595;5435.510804027656;5449.509544140888;5284.81503147388;5313.591898147071;5374.3013408256675;5396.9460305398015;5407.810726624549;5565.749541270571;5579.721013949205;5606.327240731139;5650.09020354526;5661.688607126647;3855.5758866524097;3865.627702165803;3867.61323867611;3874.748141370802;3886.572477027581;5226.764795584132;5417.566594672496;5440.110321120418;5497.560195184456;5504.697241651747;5299.49654782795;5302.1781623848365;5307.4692530746615;5312.070387682217;5342.198670199501;5222.764975576032;5238.004779091961;5249.2126181073145;5310.442403547572;5331.840044798662;5110.977804439052;5418.729063546728;5423.159410291411;5433.945660543344;5441.945580544144;5326.733663316896;5427.3215848019;5476.288082549243;5476.835694929158;5535.225068490334;5224.451432599674;5349.769553002909;5354.518093371616;5363.19552067193;5364.6512976656395 21:31:26 INFO - PROCESS | 6427 | |5;yui.html;695.6417445015818;715.9105111861018;732.0739264829991;770.7995921060524;780.7774784186508;14363.42546298148;15408.922955385207;15456.304466299016;15514.612134696625;15532.223388830542;13939.372728227228;14079.436822527112;14081.084729492575;14090.506832260848;14107.717845643107;661.8180000499852;684.3054299885595;753.7098217186368;772.6870617399948;790.3124281874748;765.720512013114;768.2701433638039;776.4370831147429;778.6379333609871;779.5829231361223;845.7589586967715;849.7068511363569;851.7870532366908;852.0031563071221;852.7313896122702;697.2713514377436;720.2941117704627;842.3555979675554;844.2275318083946;846.5132548784474;588.7321268822675;606.0878378041064;609.7591451376682;611.7124951272895;613.2763339259672;13849.86150138506;13873.930630346784;13888.93055534726;13892.583222503239;13901.513447029305;13571.321433928362;13587.660308492366;13634.86365136346;13724.93137534316;13854.445822167101;11775.597664591123;11933.284002959763;12135.211211271238;12186.329751863645;12357.196782209128;3963.3064213762477;4021.8994525136814;4089.9795501022604;4090.6522945549664;4221.197972385237;72645;74624.6268768658;75352;75803.24196758016;76107;10559.683209503728;10637.46812659364;10688.198385121146;10821.513031913582;10957.342559446459;19492.902535487232;19751.802481974995;20439.693404598944;20480.180792768122;20530;7641.538415800291;7905.56519391439;7909.960450197712;8079.353651707849;8203.876941845878;4359.106383191438;4379.846705365297;4470.865874023784;4493.483249426358;4518.977405112953;3217.501287300472;3287.997160865959;3297.1757060734817;3318.601767787881;3389.9661003390142;549.6564647095565;559.5635404384586;567.7955935863087;573.0201355681747;582.2459914410848;522.55321699946;634.4163369699887;634.5621521150391;652.3476523476523;659.66686823154;8019.198080192051;8078.23256790604;8182.83634327322;8335.541545215072;8348.081711011784;585.5110982329802;587.9441453061952;623.688155922039;632.9145317374871;658.2298710508717;3859.1895701902354;3874.321993651156;3918.921621567553;3959.5248570171766;4020.5175378954127;79480.60259698665;79784;80611.59694201492;81508;82381.58809205916;70281.64859175774;70854;70872;72370.3351913413;74280;50896.49103509066;63685.36314636794;63980.360196398375;64145.35854641487;64540.354596453435;8771.824563508566;9233.492157931378;9487.193588545051;9623.470709111067;9897.604095836374;35155.47266790949;39876.401853971634;40502.114640511776;42089.36865946952;42607.786961064994;52322.21516677329;54090.18864716954;55048.44951550433;55273;56600.433995659514;11994.580189693319;12015.519379224734;12101.81847272274;12416.441260143134;12478.563250286197;12797.424115914993;13234.801477977646;13312.201267923956;13682.384292706653;14092.22492762867;12666.838290625474;12786.74426511446;12793.680157996125;12811.743765124458;12978.415971281504;10865.456727163768;12017.579384721494;12206.328651923874;12314.815277770662;12446.937765311115;10344.069033786694;10534.57861685524;10709.571617135227;10827.621033263798;10980.341179529254;9893.50532473359;10147.949260253652;10184.490775461345;10319.845202321821;10421.791564168825;9491.715248542554;10925.726856828644;11095.5561777528;11161;11221.438928053727 21:31:26 INFO - PROCESS | 6427 | __end_tp_report 21:31:26 INFO - PROCESS | 6427 | __start_cc_report 21:31:26 INFO - PROCESS | 6427 | _x_x_mozilla_cycle_collect,1197 21:31:26 INFO - PROCESS | 6427 | __end_cc_report 21:31:26 INFO - PROCESS | 6427 | __startTimestamp1481952686805__endTimestamp 21:31:26 INFO - PROCESS | 6427 | 21:31:26 INFO - PROCESS | 6427 | ------- Summary: start ------- 21:31:26 INFO - PROCESS | 6427 | Number of tests: 6 21:31:26 INFO - PROCESS | 6427 | 21:31:26 INFO - PROCESS | 6427 | [#0] dojo.html Cycles:165 Average:5119.57 Median:5348.79 stddev:569.76 (10.7%) stddev-sans-first:545.54 21:31:26 INFO - PROCESS | 6427 | Values: 2952.0 2988.4 2991.1 3002.1 3029.1 5028.5 5045.0 5163.0 5279.4 5298.1 5106.5 5116.3 5159.3 5168.6 5247.7 4675.6 4711.2 4726.3 4742.6 4758.8 4718.6 4730.3 4755.0 4809.8 4812.2 4813.4 4871.5 4875.9 4895.4 4904.6 4544.6 4547.4 4561.7 4624.5 4775.9 4590.4 4596.4 4599.3 4599.3 4606.2 4958.7 4979.8 5000.5 5005.2 5009.7 5331.9 5480.5 5528.9 5541.4 5617.6 5594.4 5599.7 5611.4 5611.7 5614.9 5225.0 5239.2 5250.2 5255.0 5298.1 5346.9 5392.5 5613.6 5639.9 5658.5 5482.0 5491.9 5495.9 5498.6 5500.0 5575.7 5575.9 5577.2 5578.6 5579.4 5390.2 5442.0 5474.6 5512.9 5573.6 5558.9 5560.8 5563.3 5581.6 5602.0 4279.1 4288.4 4291.9 4293.4 4333.1 3734.8 3752.2 3755.3 3826.2 3847.9 5226.5 5255.1 5280.7 5297.3 5348.5 5570.7 5571.6 5573.3 5578.2 5589.0 5025.5 5156.9 5193.2 5212.2 5264.9 4605.0 4668.5 4718.1 4720.8 4721.6 5521.6 5548.6 5560.0 5563.2 5564.7 5397.3 5402.2 5406.1 5408.4 5409.1 5470.1 5512.5 5517.8 5518.4 5564.7 5391.7 5402.6 5403.3 5409.4 5410.3 5480.5 5485.5 5488.9 5491.2 5496.4 5346.9 5348.8 5355.9 5427.5 5427.6 5388.1 5397.1 5400.5 5406.5 5443.9 5128.6 5158.9 5304.5 5321.8 5371.9 5451.2 5466.2 5471.0 5479.9 5511.9 5358.8 5365.5 5366.4 5367.5 5415.2 21:31:26 INFO - PROCESS | 6427 | 21:31:26 INFO - PROCESS | 6427 | [#1] ext.html Cycles:180 Average:59262.20 Median:37862.91 stddev:117562.48 (310.5%) stddev-sans-first:117809.62 21:31:26 INFO - PROCESS | 6427 | Values: 547.4 573.5 579.8 592.3 592.6 30749.5 39745.0 41948.8 42041.8 45338.8 43178.6 43470.8 43631.0 43631.6 43631.8 8604.2 8775.0 8779.6 8781.6 8784.3 7673.3 7900.9 10021.6 10260.3 10270.8 10484.2 10557.5 10558.9 10559.9 10560.7 10400.9 10439.8 10510.9 10511.1 10513.6 9926.5 9995.6 9999.4 10002.5 10008.2 39364.8 39520.4 39631.0 39696.8 39805.4 3246.9 3309.8 3323.4 3588.7 3833.7 30093.0 30133.8 30413.5 31545.5 31587.4 4081.7 4091.6 4104.5 4231.9 4321.0 320862.0 322792.0 324485.0 325355.0 325658.0 55490.4 56262.2 56364.4 56371.2 56422.4 58315.0 58392.4 58414.0 58485.7 58517.0 16205.5 16427.3 16429.8 16440.1 16444.2 10659.6 11117.2 12127.9 12142.4 12159.7 18122.0 19098.2 20100.6 20256.3 20347.7 7150.5 7473.5 7519.5 7522.8 7770.7 857.9 967.0 976.9 1001.5 1009.9 29623.7 31301.2 31395.2 31413.4 32017.0 1037.1 1102.7 1150.3 1158.1 1198.2 15316.3 16327.3 16453.3 16454.2 16542.6 504838.0 509341.0 509690.0 510199.0 510833.0 55325.5 55799.7 55831.7 55836.7 55851.7 1185.9 1255.1 1259.7 1310.1 1330.7 25892.0 30716.8 31026.7 33269.7 33526.8 51816.0 52471.0 52543.0 52566.2 52572.7 468044.0 476623.0 476657.0 477229.0 477338.0 34480.3 35326.1 35803.8 40273.4 40968.4 36655.6 44397.8 44697.6 45377.0 45589.3 34907.5 37756.0 38146.8 38285.6 39195.8 37393.8 37682.8 37822.8 37903.0 38364.0 37906.8 38076.2 38496.6 38843.8 38890.2 31801.0 33671.0 34442.1 34541.7 34575.3 26259.9 26332.0 26542.5 26576.6 26967.7 21:31:26 INFO - PROCESS | 6427 | 21:31:26 INFO - PROCESS | 6427 | [#2] jquery.html Cycles:180 Average:26418.56 Median:40280.20 stddev:71574.97 (177.7%) stddev-sans-first:71749.04 21:31:26 INFO - PROCESS | 6427 | Values: 372.9 373.7 382.9 383.4 397.5 16885.6 17928.9 18353.8 18825.5 18900.0 17929.0 18333.0 18365.9 18399.4 18729.7 5388.2 5586.3 5586.8 5588.8 5590.6 5371.6 5438.0 5597.7 5628.3 5705.1 5678.5 5681.3 5688.8 5690.4 5691.3 5705.6 6147.8 6178.9 6186.7 6186.8 5337.1 5456.0 5494.6 5505.4 5510.6 13066.7 13271.5 13455.2 13455.9 13469.6 2152.9 2210.6 2217.7 2221.1 2302.7 9469.5 9803.5 9826.4 10319.9 10385.2 1836.4 1842.0 1888.9 1897.0 1930.1 73325.0 75168.0 76442.0 77986.6 78020.0 10364.4 10468.5 10676.8 10796.1 10862.5 14611.0 14627.1 14678.9 14683.8 14718.9 7552.5 7715.6 7727.9 7737.6 7933.4 7347.0 7467.4 7563.9 7627.5 7686.3 4363.9 4453.5 4467.6 4485.8 4584.4 1887.1 1900.2 1913.2 1918.3 1926.5 806.7 897.3 920.6 948.0 981.0 17604.6 18816.0 18831.0 19210.4 19429.2 911.9 932.7 943.6 946.2 968.8 7361.0 10215.8 10462.0 10605.6 10606.9 427334.0 432053.0 433205.0 435432.0 436614.0 79414.6 81435.6 82802.2 94643.0 95333.5 957.7 990.2 1026.3 1027.7 1029.1 16351.0 16610.4 17041.3 17454.6 17572.7 33409.8 39699.0 39953.8 40606.6 41374.8 55069.7 55172.2 55236.7 55359.0 56834.7 7992.2 8060.1 8180.8 8247.9 8303.2 10988.5 12635.4 12850.6 12905.3 12965.2 11126.9 11177.8 11205.2 11499.0 11519.2 10985.7 11245.2 11322.3 11324.2 11592.4 10869.9 10976.7 11302.8 11373.5 11436.1 7116.6 7415.6 7434.8 8094.2 8182.6 8258.4 10176.0 11248.4 11326.8 11662.1 21:31:26 INFO - PROCESS | 6427 | 21:31:26 INFO - PROCESS | 6427 | [#3] mootools.html Cycles:180 Average:5386.12 Median:4967.00 stddev:3061.51 (61.6%) stddev-sans-first:3055.03 21:31:26 INFO - PROCESS | 6427 | Values: 1344.1 1437.2 1464.7 1626.4 1782.0 7104.1 8017.0 8401.2 8412.7 8497.4 8358.1 8440.3 8444.0 8445.8 8446.7 1883.4 1946.6 2184.8 2219.8 2221.0 2111.3 2173.8 2175.9 2182.4 2198.7 2159.8 2165.9 2173.0 2175.6 2201.3 2159.2 2179.0 2181.7 2183.1 2183.2 2127.8 2163.6 2165.4 2165.8 2165.9 8004.1 8095.4 8097.6 8281.5 8441.4 4826.4 4956.5 4959.7 4962.0 4964.3 3023.3 3140.7 3141.9 3144.7 3164.3 955.9 975.9 1028.2 1034.1 1036.9 11506.1 11516.3 11532.4 11547.3 11563.9 4177.4 4285.5 4356.0 4378.7 4409.8 9034.1 9165.6 9185.5 9191.8 9213.4 4964.6 4965.1 4969.0 4971.0 4972.6 4023.7 4432.5 4599.3 4666.6 4667.0 3421.5 3481.3 3492.0 3537.2 3552.6 1003.9 1018.4 1019.3 1030.0 1036.5 715.3 823.4 888.1 919.0 938.1 5334.6 5590.7 5701.4 5790.3 5963.5 913.2 924.6 954.9 960.4 961.6 3654.9 3744.6 3850.5 3865.7 3970.5 10155.6 10424.3 10472.9 10499.4 10512.6 10309.1 10313.6 10358.8 10369.1 10379.1 7452.3 7549.5 7631.7 7700.9 7716.3 5453.3 5461.8 5629.2 5635.4 5789.2 8844.6 8910.6 8945.5 8990.3 9110.7 8999.8 9032.2 9247.2 9331.8 9461.8 6600.2 6810.6 6815.0 7373.3 7496.4 7553.4 7643.2 7672.0 7951.6 8003.2 7152.9 7235.8 7310.7 7368.4 7441.3 6982.8 7177.9 7218.5 7255.3 7442.3 6257.7 6425.4 6437.7 6439.1 6439.2 4645.7 4647.7 4758.8 4998.4 5032.3 4757.4 4876.7 4896.6 5012.4 5026.0 21:31:26 INFO - PROCESS | 6427 | 21:31:26 INFO - PROCESS | 6427 | [#4] prototype.html Cycles:180 Average:4522.12 Median:5243.41 stddev:1323.59 (25.2%) stddev-sans-first:1289.63 21:31:26 INFO - PROCESS | 6427 | Values: 344.5 354.6 361.7 372.7 373.9 5105.3 5297.3 5299.5 5312.6 5332.3 5197.6 5209.2 5214.7 5223.3 5228.0 3772.5 3860.4 4058.7 4068.1 4085.0 3987.8 4036.7 4048.7 4056.1 4066.0 3968.2 3974.8 3995.7 4006.8 4010.8 3840.0 3927.5 4007.0 4012.9 4031.7 3481.7 3531.9 3532.9 3535.6 3569.8 4989.9 5014.7 5021.7 5028.2 5043.4 4466.4 4577.6 4582.8 4583.4 4595.4 5295.9 5310.6 5355.2 5396.5 5403.4 3807.3 3841.8 3859.2 3862.5 3864.6 5383.3 5544.7 5598.7 5600.5 5621.4 3928.0 3968.8 3974.5 3978.6 3981.6 4014.5 4017.3 4023.8 4027.7 4032.7 4589.2 4603.3 4617.4 4618.7 4638.6 5300.6 5365.5 5406.3 5468.3 5513.2 1334.5 1342.5 1347.0 1353.8 1363.8 872.6 929.5 930.3 931.3 943.7 5049.4 5084.0 5110.6 5154.5 5237.1 5528.3 5544.4 5556.0 5569.7 5575.0 5248.8 5252.6 5263.0 5280.2 5317.9 2789.1 2840.8 2859.8 2865.5 2867.4 5500.7 5509.4 5616.2 5622.4 5660.7 5266.3 5456.7 5529.6 5540.3 5544.6 5541.5 5582.5 5609.5 5610.7 5616.8 5382.4 5406.0 5422.8 5435.5 5449.5 5284.8 5313.6 5374.3 5396.9 5407.8 5565.7 5579.7 5606.3 5650.1 5661.7 3855.6 3865.6 3867.6 3874.7 3886.6 5226.8 5417.6 5440.1 5497.6 5504.7 5299.5 5302.2 5307.5 5312.1 5342.2 5222.8 5238.0 5249.2 5310.4 5331.8 5111.0 5418.7 5423.2 5433.9 5441.9 5326.7 5427.3 5476.3 5476.8 5535.2 5224.5 5349.8 5354.5 5363.2 5364.7 21:31:26 INFO - PROCESS | 6427 | 21:31:26 INFO - PROCESS | 6427 | [#5] yui.html Cycles:180 Average:17028.31 Median:4482.17 stddev:22412.83 (500.0%) stddev-sans-first:22442.15 21:31:26 INFO - PROCESS | 6427 | Values: 695.6 715.9 732.1 770.8 780.8 14363.4 15408.9 15456.3 15514.6 15532.2 13939.4 14079.4 14081.1 14090.5 14107.7 661.8 684.3 753.7 772.7 790.3 765.7 768.3 776.4 778.6 779.6 845.8 849.7 851.8 852.0 852.7 697.3 720.3 842.4 844.2 846.5 588.7 606.1 609.8 611.7 613.3 13849.9 13873.9 13888.9 13892.6 13901.5 13571.3 13587.7 13634.9 13724.9 13854.4 11775.6 11933.3 12135.2 12186.3 12357.2 3963.3 4021.9 4090.0 4090.7 4221.2 72645.0 74624.6 75352.0 75803.2 76107.0 10559.7 10637.5 10688.2 10821.5 10957.3 19492.9 19751.8 20439.7 20480.2 20530.0 7641.5 7905.6 7910.0 8079.4 8203.9 4359.1 4379.8 4470.9 4493.5 4519.0 3217.5 3288.0 3297.2 3318.6 3390.0 549.7 559.6 567.8 573.0 582.2 522.6 634.4 634.6 652.3 659.7 8019.2 8078.2 8182.8 8335.5 8348.1 585.5 587.9 623.7 632.9 658.2 3859.2 3874.3 3918.9 3959.5 4020.5 79480.6 79784.0 80611.6 81508.0 82381.6 70281.6 70854.0 70872.0 72370.3 74280.0 50896.5 63685.4 63980.4 64145.4 64540.4 8771.8 9233.5 9487.2 9623.5 9897.6 35155.5 39876.4 40502.1 42089.4 42607.8 52322.2 54090.2 55048.4 55273.0 56600.4 11994.6 12015.5 12101.8 12416.4 12478.6 12797.4 13234.8 13312.2 13682.4 14092.2 12666.8 12786.7 12793.7 12811.7 12978.4 10865.5 12017.6 12206.3 12314.8 12446.9 10344.1 10534.6 10709.6 10827.6 10980.3 9893.5 10147.9 10184.5 10319.8 10421.8 9491.7 10925.7 11095.6 11161.0 11221.4 21:31:26 INFO - PROCESS | 6427 | -------- Summary: end -------- 21:31:26 INFO - PROCESS | 6427 | 21:31:27 INFO - TEST-INFO | 6427: exit 0 21:31:27 INFO - TEST-OK | dromaeo_css | took 1117975ms 21:31:27 INFO - TEST-START | kraken 21:31:27 INFO - operating with platform_type : linux_ 21:31:27 INFO - Initialising browser for kraken test... 21:31:27 INFO - TEST-INFO | started process 6730 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdVYS2X/profile http://localhost:34570/getInfo.html) 21:31:31 INFO - PROCESS | 6730 | __metrics Screen width/height:1600/1200 21:31:31 INFO - PROCESS | 6730 | colorDepth:24 21:31:31 INFO - PROCESS | 6730 | Browser inner width/height: 1024/697 21:31:31 INFO - PROCESS | 6730 | __metrics 21:31:31 INFO - PROCESS | 6730 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:31:31 INFO - PROCESS | 6730 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:31:32 INFO - PROCESS | 6730 | console.error: PushService: 21:31:32 INFO - PROCESS | 6730 | stateChangeProcessEnqueue: Error transitioning state 21:31:32 INFO - PROCESS | 6730 | UnknownError 21:31:32 INFO - TEST-INFO | 6730: exit 0 21:31:32 INFO - Browser initialized. 21:31:32 INFO - Running cycle 1/1 for kraken test... 21:31:32 INFO - TEST-INFO | started process 6812 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdVYS2X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:31:44 INFO - PROCESS | 6812 | RSS: Main: 155074560 21:31:44 INFO - PROCESS | 6812 | 21:31:47 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34570/tests/kraken/driver.html?testName=audio-beat-detection) 21:31:48 INFO - PROCESS | 6812 | RSS: Main: 208228352 21:31:48 INFO - PROCESS | 6812 | 21:31:50 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34570/tests/kraken/driver.html?testName=audio-dft) 21:31:51 INFO - PROCESS | 6812 | RSS: Main: 186724352 21:31:51 INFO - PROCESS | 6812 | 21:31:54 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34570/tests/kraken/driver.html?testName=audio-fft) 21:31:54 INFO - PROCESS | 6812 | RSS: Main: 267939840 21:31:54 INFO - PROCESS | 6812 | 21:31:56 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34570/tests/kraken/driver.html?testName=audio-oscillator) 21:31:57 INFO - PROCESS | 6812 | RSS: Main: 178946048 21:31:57 INFO - PROCESS | 6812 | 21:31:59 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34570/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:32:00 INFO - PROCESS | 6812 | RSS: Main: 182804480 21:32:00 INFO - PROCESS | 6812 | 21:32:03 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34570/tests/kraken/driver.html?testName=imaging-darkroom) 21:32:03 INFO - PROCESS | 6812 | RSS: Main: 223047680 21:32:03 INFO - PROCESS | 6812 | 21:32:07 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34570/tests/kraken/driver.html?testName=imaging-desaturate) 21:32:07 INFO - PROCESS | 6812 | RSS: Main: 222015488 21:32:07 INFO - PROCESS | 6812 | 21:32:10 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34570/tests/kraken/driver.html?testName=json-parse-financial) 21:32:11 INFO - PROCESS | 6812 | RSS: Main: 221691904 21:32:11 INFO - PROCESS | 6812 | 21:32:12 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34570/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:32:13 INFO - PROCESS | 6812 | RSS: Main: 183635968 21:32:13 INFO - PROCESS | 6812 | 21:32:15 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34570/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:32:15 INFO - PROCESS | 6812 | RSS: Main: 190689280 21:32:15 INFO - PROCESS | 6812 | 21:32:17 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34570/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:32:18 INFO - PROCESS | 6812 | RSS: Main: 198684672 21:32:18 INFO - PROCESS | 6812 | 21:32:20 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34570/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:32:21 INFO - PROCESS | 6812 | RSS: Main: 208424960 21:32:21 INFO - PROCESS | 6812 | 21:32:24 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34570/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:32:24 INFO - PROCESS | 6812 | RSS: Main: 204619776 21:32:24 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | Cycle 1(1): loaded http://localhost:34570/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34570/tests/kraken/driver.html?testName=ai-astar) 21:32:26 INFO - PROCESS | 6812 | RSS: Main: 256737280 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | __start_tp_report 21:32:26 INFO - PROCESS | 6812 | _x_x_mozilla_page_load 21:32:26 INFO - PROCESS | 6812 | _x_x_mozilla_page_load_details 21:32:26 INFO - PROCESS | 6812 | |i|pagename|runs| 21:32:26 INFO - PROCESS | 6812 | |0;ai-astar;112;111;112;112;112;110;110;110;111;110 21:32:26 INFO - PROCESS | 6812 | |1;audio-beat-detection;156;117;120;158;122;127;131;119;145;119 21:32:26 INFO - PROCESS | 6812 | |2;audio-dft;160;160;161;161;159;159;160;160;161;162 21:32:26 INFO - PROCESS | 6812 | |3;audio-fft;73;75;76;118;75;76;77;76;90;75 21:32:26 INFO - PROCESS | 6812 | |4;audio-oscillator;84;84;108;84;83;83;96;83;83;112 21:32:26 INFO - PROCESS | 6812 | |5;imaging-gaussian-blur;127;127;127;127;127;130;126;127;127;126 21:32:26 INFO - PROCESS | 6812 | |6;imaging-darkroom;146;146;145;146;146;146;147;146;145;146 21:32:26 INFO - PROCESS | 6812 | |7;imaging-desaturate;95;96;96;95;95;95;96;96;96;95 21:32:26 INFO - PROCESS | 6812 | |8;json-parse-financial;52;53;54;52;53;55;53;53;55;52 21:32:26 INFO - PROCESS | 6812 | |9;json-stringify-tinderbox;63;64;63;63;63;63;63;63;63;64 21:32:26 INFO - PROCESS | 6812 | |10;stanford-crypto-aes;83;86;80;80;79;89;79;88;79;85 21:32:26 INFO - PROCESS | 6812 | |11;stanford-crypto-ccm;126;128;134;126;137;130;127;131;164;138 21:32:26 INFO - PROCESS | 6812 | |12;stanford-crypto-pbkdf2;167;162;182;164;169;167;165;170;168;169 21:32:26 INFO - PROCESS | 6812 | |13;stanford-crypto-sha256-iterative;63;64;61;64;63;73;63;68;70;65 21:32:26 INFO - PROCESS | 6812 | __end_tp_report 21:32:26 INFO - PROCESS | 6812 | __start_cc_report 21:32:26 INFO - PROCESS | 6812 | _x_x_mozilla_cycle_collect,1662 21:32:26 INFO - PROCESS | 6812 | __end_cc_report 21:32:26 INFO - PROCESS | 6812 | __startTimestamp1481952746923__endTimestamp 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | ------- Summary: start ------- 21:32:26 INFO - PROCESS | 6812 | Number of tests: 14 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.50 stddev:0.94 (0.8%) stddev-sans-first:0.93 21:32:26 INFO - PROCESS | 6812 | Values: 112.0 111.0 112.0 112.0 112.0 110.0 110.0 110.0 111.0 110.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:129.00 stddev:15.81 (12.3%) stddev-sans-first:14.04 21:32:26 INFO - PROCESS | 6812 | Values: 156.0 117.0 120.0 158.0 122.0 127.0 131.0 119.0 145.0 119.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 21:32:26 INFO - PROCESS | 6812 | Values: 160.0 160.0 161.0 161.0 159.0 159.0 160.0 160.0 161.0 162.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#3] audio-fft Cycles:10 Average:81.10 Median:76.00 stddev:13.80 (18.2%) stddev-sans-first:14.32 21:32:26 INFO - PROCESS | 6812 | Values: 73.0 75.0 76.0 118.0 75.0 76.0 77.0 76.0 90.0 75.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:83.50 stddev:11.29 (13.5%) stddev-sans-first:11.77 21:32:26 INFO - PROCESS | 6812 | Values: 84.0 84.0 108.0 84.0 83.0 83.0 96.0 83.0 83.0 112.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#5] imaging-gaussian-blur Cycles:10 Average:127.10 Median:127.00 stddev:1.10 (0.9%) stddev-sans-first:1.17 21:32:26 INFO - PROCESS | 6812 | Values: 127.0 127.0 127.0 127.0 127.0 130.0 126.0 127.0 127.0 126.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 21:32:26 INFO - PROCESS | 6812 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 147.0 146.0 145.0 146.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 21:32:26 INFO - PROCESS | 6812 | Values: 95.0 96.0 96.0 95.0 95.0 95.0 96.0 96.0 96.0 95.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:1.14 (2.1%) stddev-sans-first:1.12 21:32:26 INFO - PROCESS | 6812 | Values: 52.0 53.0 54.0 52.0 53.0 55.0 53.0 53.0 55.0 52.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 21:32:26 INFO - PROCESS | 6812 | Values: 63.0 64.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 64.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:84.00 stddev:3.94 (4.7%) stddev-sans-first:4.18 21:32:26 INFO - PROCESS | 6812 | Values: 83.0 86.0 80.0 80.0 79.0 89.0 79.0 88.0 79.0 85.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#11] stanford-crypto-ccm Cycles:10 Average:134.10 Median:132.50 stddev:11.37 (8.6%) stddev-sans-first:11.67 21:32:26 INFO - PROCESS | 6812 | Values: 126.0 128.0 134.0 126.0 137.0 130.0 127.0 131.0 164.0 138.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:168.50 stddev:5.42 (3.2%) stddev-sans-first:5.73 21:32:26 INFO - PROCESS | 6812 | Values: 167.0 162.0 182.0 164.0 169.0 167.0 165.0 170.0 168.0 169.0 21:32:26 INFO - PROCESS | 6812 | 21:32:26 INFO - PROCESS | 6812 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:64.50 stddev:3.75 (5.8%) stddev-sans-first:3.87 21:32:26 INFO - PROCESS | 6812 | Values: 63.0 64.0 61.0 64.0 63.0 73.0 63.0 68.0 70.0 65.0 21:32:26 INFO - PROCESS | 6812 | -------- Summary: end -------- 21:32:26 INFO - PROCESS | 6812 | 21:32:27 INFO - TEST-INFO | 6812: exit 0 21:32:27 INFO - TEST-OK | kraken | took 60344ms 21:32:27 INFO - SUITE-END | took 1178s 21:32:28 INFO - Completed test suite (00:19:38) 21:32:28 INFO - javascript benchmark 21:32:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2951.985240073799, 2988.417258634567, 2991.0877182459353, 3002.129382479081, 3029.1366960416285, 5028.472010438906, 5044.974775126119, 5162.993216322819, 5279.41926388097, 5298.12580924148, 5106.5148810863, 5116.309298244736, 5159.251908473269, 5168.586513078954, 5247.658902171365, 4675.649326300524, 4711.151992641323, 4726.26742854858, 4742.644301677371, 4758.833440829571, 4718.57532822046, 4730.290456431546, 4755.048990201956, 4809.759512024402, 4812.157872372339, 4813.350197723333, 4871.488493708162, 4875.926861097087, 4895.388076490439, 4904.607631389498, 4544.636429085665, 4547.363369128325, 4561.70348927322, 4624.491305956342, 4775.9283610745515, 4590.380298659672, 4596.4254468191475, 4599.2871104978785, 4599.31010348447, 4606.216943119644, 4958.7024778513405, 4979.8008079676765, 5000.499950004971, 5005.2492126181, 5009.724465154415, 5331.946680533222, 5480.534154596864, 5528.917066244009, 5541.390447050821, 5617.606767526275, 5594.412586678422, 5599.7480113394995, 5611.3546942101775, 5611.719414029323, 5614.859628509279, 5224.9738751306, 5239.21411788231, 5250.18622113573, 5255.001549705582, 5298.125809241432, 5346.946530534645, 5392.5416339611575, 5613.635113717595, 5639.943600564024, 5658.462446067617, 5481.972590137024, 5491.862703432446, 5495.890082198333, 5498.642588231752, 5500.037493438632, 5575.749091290902, 5575.888482230333, 5577.191307260425, 5578.63738856973, 5579.358373787067, 5390.164524498709, 5442.020436321421, 5474.616776825584, 5512.889742205133, 5573.5819813514145, 5558.888822223533, 5560.833175004756, 5563.304586926634, 5581.581381396411, 5602.0, 4279.144171165779, 4288.442502474658, 4291.892702682396, 4293.4203882476095, 4333.090051089243, 3734.8132593370224, 3752.2307926874923, 3755.3428150074174, 3826.1964987352967, 3847.942280865789, 5226.503482169264, 5255.132903071027, 5280.683159010395, 5297.258383826267, 5348.5186333230195, 5570.749316280777, 5571.609987300931, 5573.331200255995, 5578.24693666358, 5588.972055139699, 5025.522575355335, 5156.92264616031, 5193.221016847428, 5212.244224587414, 5264.92102618461, 4605.0, 4668.50980647034, 4718.05638872227, 4720.787564559603, 4721.622270218374, 5521.641093328921, 5548.5838562108, 5560.026995275811, 5563.165525171256, 5564.666120032811, 5397.325334333208, 5402.216678581663, 5406.107992181246, 5408.432114627907, 5409.0804563223555, 5470.070088085115, 5512.476314750093, 5517.806876759294, 5518.365387980273, 5564.749586268546, 5391.7034563099405, 5402.648827826179, 5403.297571315861, 5409.432009639088, 5410.323709536308, 5480.451954804487, 5485.4788795064405, 5488.945110548843, 5491.176323551499, 5496.422875598005, 5346.866328341823, 5348.786048558014, 5355.866103347447, 5427.538659213853, 5427.5657947363325, 5388.110961691277, 5397.109476936262, 5400.513953744182, 5406.459354064719, 5443.891122177455, 5128.564072053768, 5158.871028224325, 5304.469553044664, 5321.840344789663, 5371.865703357447, 5451.236826844166, 5466.234727138124, 5471.042567550742, 5479.863003424946, 5511.862203444946, 5358.812441564526, 5365.543928766098, 5366.409694933476, 5367.543758780547, 5415.160650099241], "value": 5081.7692047632245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.4388751529682, 573.538301667158, 579.8202557207263, 592.2951687491882, 592.6207227374479, 30749.538756918635, 39745.0, 41948.79025604868, 42041.789791051044, 45338.7733061335, 43178.56821431785, 43470.78264608672, 43631.0, 43631.563684363144, 43631.78184109075, 8604.225619694242, 8775.0, 8779.604917778683, 8781.56092195391, 8784.341174411913, 7673.271039251262, 7900.881486777675, 10021.649242276522, 10260.281780275383, 10270.794584108313, 10484.161267098652, 10557.472126393688, 10558.894411055906, 10559.894401056006, 10560.683179504627, 10400.895991040068, 10439.791204175912, 10510.894891051068, 10511.106555942753, 10513.579456821719, 9926.45404502752, 9995.550200240934, 9999.35004224723, 10002.49987500622, 10008.199344052458, 39364.803175983936, 39520.40719389211, 39631.0, 39696.80151599253, 39805.402918956235, 3246.9350612987846, 3309.8014119152926, 3323.3851737428413, 3588.6770190682964, 3833.674137698271, 30093.0, 30133.849330753204, 30413.543796843063, 31545.52681709776, 31587.36825263505, 4081.7346872453195, 4091.5908409158847, 4104.4664193654935, 4231.936520952188, 4320.978395108035, 320862.0, 322792.0, 324485.0, 325355.0, 325658.0, 55490.44509554934, 56262.156067659016, 56364.43635563674, 56371.15443268354, 56422.435775641716, 58315.0, 58392.416075839545, 58414.0, 58485.70757146272, 58517.0, 16205.541501264943, 16427.260773265232, 16429.753553696704, 16440.095794731405, 16444.177791110396, 10659.573617055232, 11117.166212534092, 12127.939360303319, 12142.421485206867, 12159.696007599881, 18122.037503757216, 19098.2360705573, 20100.597988040157, 20256.29102981418, 20347.69478457824, 7150.499465037397, 7473.476856620093, 7519.473636845369, 7522.774316770506, 7770.689172433153, 857.9099194584603, 966.9516524173763, 976.9218462522981, 1001.529281237817, 1009.9040591143829, 29623.703762962094, 31301.217469562987, 31395.215119622193, 31413.37173256522, 32017.0, 1037.0666400239845, 1102.7188067043014, 1150.3385553306714, 1158.1371877950812, 1198.1972078707113, 15316.310766015557, 16327.265273062741, 16453.259603317878, 16454.177291135395, 16542.586435339213, 504838.0, 509341.0, 509690.0, 510199.0, 510833.0, 55325.488157249994, 55799.72100139473, 55831.72084139553, 55836.72081639647, 55851.720741396035, 1185.9229150105216, 1255.1465003797505, 1259.6661884600587, 1310.102579732886, 1330.7138965122545, 25892.0, 30716.846415767777, 31026.68973310238, 33269.667303326656, 33526.83236583802, 51816.0, 52471.0, 52543.0, 52566.211506826665, 52572.73713631407, 468044.0, 476623.0, 476657.0, 477229.0, 477338.0, 34480.31039379148, 35326.11684707903, 35803.82098089493, 40273.39589906095, 40968.38547421851, 36655.633443665225, 44397.77801111103, 44697.55302446934, 45377.0, 45589.316160258284, 34907.47638785471, 37756.0, 38146.809265953496, 38285.6171438282, 39195.80402098085, 37393.81303093576, 37682.8115859419, 37822.81088594539, 37903.0, 38364.0, 37906.81046594749, 38076.23847522979, 38496.6150338493, 38843.80578097091, 38890.22219555536, 31801.0, 33671.0, 34442.13894652654, 34541.65458345485, 34575.30849382948, 26259.868700657138, 26332.0, 26542.469150616493, 26576.601350979363, 26967.730322696523], "value": 19892.943767876364, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.92914346274216, 373.68610367291467, 382.87939299120774, 383.4038070799908, 397.4832717467294, 16885.57030420545, 17928.91035544822, 18353.816461835377, 18825.529361765944, 18900.0, 17929.0, 18332.991685457295, 18365.908170459163, 18399.35602253918, 18729.719054214165, 5388.164834450667, 5586.301712285965, 5586.832395028156, 5588.776448942038, 5590.608657393984, 5371.57027437804, 5438.0, 5597.692126933017, 5628.32460104786, 5705.058665320217, 5678.545716342683, 5681.3466451358, 5688.829335119953, 5690.430956904297, 5691.345495268035, 5705.600607957424, 6147.784827531037, 6178.907316390258, 6186.690665466709, 6186.814395568118, 5337.146056630959, 5455.990641731253, 5494.615376923617, 5505.421930697259, 5510.641808282448, 13066.738665226641, 13271.535496257682, 13455.192688438725, 13455.932720336434, 13469.595912122651, 2152.9138834446603, 2210.6462965925534, 2217.667349897512, 2221.0893533651124, 2302.6661134135456, 9469.479178645171, 9803.509824508747, 9826.4104153751, 10319.948400258027, 10385.221108416823, 1836.4398858348197, 1841.9815801841944, 1888.9244430222775, 1896.9905150474297, 1930.1410872161894, 73325.0, 75168.0, 76442.0, 77986.61006694987, 78020.0, 10364.378137311785, 10468.47657617116, 10676.786464270672, 10796.082333001626, 10862.45687715611, 14610.977231593899, 14627.122372657675, 14678.926605366905, 14683.77974330386, 14718.852811471963, 7552.546847189186, 7715.614219289013, 7727.884081738778, 7737.613119344011, 7933.444658873939, 7346.963265183746, 7467.365273951775, 7563.886541701879, 7627.466077374531, 7686.269804368576, 4363.934540981824, 4453.487848897418, 4467.553244675506, 4485.775711214492, 4584.426946631671, 1887.1224880430666, 1900.1639278717596, 1913.1964574878584, 1918.2998205654835, 1926.5183704073982, 806.6773290683774, 897.3135551303258, 920.6363486422825, 947.9810403792023, 980.9754756131153, 17604.559886002953, 18816.0, 18831.0, 19210.423687289403, 19429.22283108688, 911.886014248219, 932.7295084425441, 943.5942544705842, 946.1673727120091, 968.8498282766183, 7361.0, 10215.795684086277, 10462.0, 10605.575776968835, 10606.946965265124, 427334.0, 432053.0, 433205.0, 435432.0, 436614.0, 79414.602926985, 81435.59282203551, 82802.17197828065, 94643.0, 95333.52333238428, 957.7270477913622, 990.1830989433746, 1026.338011982282, 1027.7173777211326, 1029.0789743179812, 16351.0, 16610.39167394945, 17041.31834726597, 17454.563635909202, 17572.73640895362, 33409.83295083509, 39699.0, 39953.80023099982, 40606.59393406028, 41374.79312603418, 55069.724651376484, 55172.17241741297, 55236.72381638066, 55359.0, 56834.71582642226, 7992.240737129963, 8060.1133875274845, 8180.83638327242, 8247.917520824714, 8303.169683031649, 10988.450577470934, 12635.3682315882, 12850.614481565568, 12905.290209038592, 12965.22208667483, 11126.944365278121, 11177.776444470903, 11205.215634905804, 11499.0, 11519.193656444304, 10985.670429887115, 11245.156613253876, 11322.32066076038, 11324.150688698546, 11592.420378981185, 10869.891301086887, 10976.725581860197, 11302.773944521228, 11373.54505819758, 11436.083798631296, 7116.644167791694, 7415.555066696015, 7434.825393495303, 8094.190580941859, 8182.631781569725, 8258.42191046621, 10176.0, 11248.437578120898, 11326.773464530828, 11662.12534059966], "value": 8687.44661406678, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.1196016609124, 1437.1951707044057, 1464.7290251303514, 1626.4388785868878, 1782.0376996421924, 7104.11198600175, 8017.0, 8401.201885820838, 8412.705555305572, 8497.405181637288, 8358.122397148289, 8440.32477401808, 8444.0, 8445.831083378329, 8446.662133514668, 1883.3973128598855, 1946.5814849807289, 2184.8470607057475, 2219.844610877239, 2220.977790222093, 2111.3032699209225, 2173.8369622278315, 2175.8912054397215, 2182.4216582605623, 2198.703175071369, 2159.7516285627116, 2165.8700477971292, 2172.9891350543226, 2175.59751445983, 2201.273579718697, 2159.201095594632, 2179.0, 2181.6618424144285, 2183.1049269799305, 2183.2031308572336, 2127.819135373495, 2163.621366260914, 2165.404513758713, 2165.837562182827, 2165.935021949344, 8004.119546849842, 8095.433319667625, 8097.635606397726, 8281.54451505167, 8441.366897482714, 4826.420829500447, 4956.529129732669, 4959.6528243023, 4962.0324036812835, 4964.255361695738, 3023.3046399328277, 3140.733037691776, 3141.9057428277188, 3144.685531446837, 3164.27221738999, 955.8518429643416, 975.9219262458986, 1028.1569113327075, 1034.13132968307, 1036.922230832683, 11506.07951363889, 11516.309021458655, 11532.365719885403, 11547.307161570332, 11563.884361156364, 4177.352510360838, 4285.507166675872, 4355.956440435619, 4378.671599629977, 4409.779511024436, 9034.14175653312, 9165.633374665073, 9185.540722963826, 9191.770205744777, 9213.447193168297, 4964.553190212826, 4965.0566392385335, 4968.950310496921, 4970.950290497121, 4972.577330926912, 4023.6721881778944, 4432.468103827497, 4599.264117741145, 4666.556677115668, 4667.0, 3421.4525675891737, 3481.3385456763135, 3491.965080349164, 3537.2218112015316, 3552.5559305086863, 1003.9247056470793, 1018.3787889387468, 1019.2712210769336, 1030.0420608833706, 1036.4921188617523, 715.2704241673464, 823.4441751817499, 888.1296329597031, 918.9954050229707, 938.0759951447818, 5334.59990500714, 5590.720463976785, 5701.372848986608, 5790.276215473066, 5963.543471588258, 913.2465715784372, 924.5931790012373, 954.9283803714749, 960.3661583354952, 961.5528779117744, 3654.8903532894055, 3744.64425879541, 3850.5186851643543, 3865.690744740414, 3970.5433875104154, 10155.644552440777, 10424.322419042737, 10472.895271047344, 10499.370037797757, 10512.57949682004, 10309.123724483354, 10313.587456501806, 10358.772863834312, 10369.118624916966, 10379.11777498906, 7452.254774522613, 7549.50928189666, 7631.656575454118, 7700.884486732703, 7716.2669546393, 5453.26380938584, 5461.836144915659, 5629.240052592848, 5635.436456354331, 5789.1605717171615, 8844.601992910462, 8910.643574257216, 8945.46327220369, 8990.280777537911, 9110.681126160553, 8999.820003600022, 9032.232260257952, 9247.213986811197, 9331.813363732552, 9461.763455913382, 6600.207975043026, 6810.5913645181445, 6815.011823285695, 7373.29953654402, 7496.437767167376, 7553.433492487976, 7643.159252482112, 7672.0, 7951.602419879098, 8003.159668234752, 7152.85694286101, 7235.782926512213, 7310.6710198040155, 7368.447366447431, 7441.255874412732, 6982.79051628452, 7177.856442871218, 7218.458615603956, 7255.310745479171, 7442.330190282901, 6257.68711564429, 6425.4217120457515, 6437.742490300336, 6439.098526206241, 6439.195100612424, 4645.721256724607, 4647.65142614312, 4758.809647614056, 4998.350214472095, 5032.345795046621, 4757.429108507002, 4876.731779752147, 4896.608271338356, 5012.448630650552, 5026.045051440215], "value": 4300.997643896692, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.5331575714906, 354.58159371941105, 361.6925613228755, 372.69625255416815, 373.8784894909155, 5105.285260063594, 5297.284866543016, 5299.5230429261355, 5312.601554883385, 5332.280142180804, 5197.558207552353, 5209.192575150848, 5214.713190774506, 5223.294855194547, 5227.973860130694, 3772.4529943158227, 3860.3823388257883, 4058.736182148166, 4068.1456894052285, 4084.9991752020796, 3987.7806720630356, 4036.677065834741, 4048.67610591152, 4056.127932494513, 4066.0, 3968.1865217630316, 3974.787689754624, 3995.7003224758114, 4006.8397264109544, 4010.811404933085, 3839.9616003839883, 3927.528696556403, 4007.0, 4012.859549915768, 4031.6573091287128, 3481.704055155315, 3531.9293614127573, 3532.858685652571, 3535.6287589803214, 3569.821508924543, 4989.925151122699, 5014.674046186986, 5021.748912554394, 5028.220625803006, 5043.42000669924, 4466.397036400077, 4577.610903073243, 4582.839600613963, 4583.381243532114, 4595.379623750818, 5295.947040529584, 5310.60170487215, 5355.223492593552, 5396.514313711785, 5403.405625381205, 3807.333716599584, 3841.8293664079706, 3859.2281543691092, 3862.4592557042038, 3864.632859878307, 5383.300170977787, 5544.695041772701, 5598.720063996825, 5600.519584332558, 5621.409751976065, 3927.9607203928167, 3968.8214030368126, 3974.463447434617, 3978.622030907059, 3981.5819338969573, 4014.474499690099, 4017.276890159741, 4023.758574485482, 4027.677785777131, 4032.6572241359227, 4589.196890544142, 4603.332516785047, 4617.399738034034, 4618.683675152565, 4638.5593368629925, 5300.628955973122, 5365.490278423544, 5406.269833733939, 5468.28912241406, 5513.2005859151, 1334.5195729537359, 1342.4697244588529, 1346.9865301347056, 1353.7901625248103, 1363.8158848555518, 872.5506364222431, 929.5305870535336, 930.3115694386106, 931.3434029009532, 943.7357539888842, 5049.444561098277, 5083.974580127076, 5110.565601923878, 5154.458781827929, 5237.135872581058, 5528.308961379827, 5544.390117087117, 5555.972220138954, 5569.749361278752, 5574.9721251393485, 5248.816291429782, 5252.632315737862, 5263.0, 5280.1815718563685, 5317.867053323698, 2789.149309460634, 2840.786940979435, 2859.799814012999, 2865.5271880139962, 2867.3835125448127, 5500.697461639568, 5509.421510741315, 5616.213730077792, 5622.409646987089, 5660.660360378391, 5266.278971261677, 5456.727163641802, 5529.612927095065, 5540.279763630783, 5544.556435485232, 5541.5289700375915, 5582.469665381782, 5609.523190528851, 5610.663360198401, 5616.831495055155, 5382.381026182039, 5406.026800217971, 5422.8373148805595, 5435.510804027656, 5449.509544140888, 5284.81503147388, 5313.591898147071, 5374.3013408256675, 5396.9460305398015, 5407.810726624549, 5565.749541270571, 5579.721013949205, 5606.327240731139, 5650.09020354526, 5661.688607126647, 3855.5758866524097, 3865.627702165803, 3867.61323867611, 3874.748141370802, 3886.572477027581, 5226.764795584132, 5417.566594672496, 5440.110321120418, 5497.560195184456, 5504.697241651747, 5299.49654782795, 5302.1781623848365, 5307.4692530746615, 5312.070387682217, 5342.198670199501, 5222.764975576032, 5238.004779091961, 5249.2126181073145, 5310.442403547572, 5331.840044798662, 5110.977804439052, 5418.729063546728, 5423.159410291411, 5433.945660543344, 5441.945580544144, 5326.733663316896, 5427.3215848019, 5476.288082549243, 5476.835694929158, 5535.225068490334, 5224.451432599674, 5349.769553002909, 5354.518093371616, 5363.19552067193, 5364.6512976656395], "value": 4110.056532178535, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.6417445015818, 715.9105111861018, 732.0739264829991, 770.7995921060524, 780.7774784186508, 14363.42546298148, 15408.922955385207, 15456.304466299016, 15514.612134696625, 15532.223388830542, 13939.372728227228, 14079.436822527112, 14081.084729492575, 14090.506832260848, 14107.717845643107, 661.8180000499852, 684.3054299885595, 753.7098217186368, 772.6870617399948, 790.3124281874748, 765.720512013114, 768.2701433638039, 776.4370831147429, 778.6379333609871, 779.5829231361223, 845.7589586967715, 849.7068511363569, 851.7870532366908, 852.0031563071221, 852.7313896122702, 697.2713514377436, 720.2941117704627, 842.3555979675554, 844.2275318083946, 846.5132548784474, 588.7321268822675, 606.0878378041064, 609.7591451376682, 611.7124951272895, 613.2763339259672, 13849.86150138506, 13873.930630346784, 13888.93055534726, 13892.583222503239, 13901.513447029305, 13571.321433928362, 13587.660308492366, 13634.86365136346, 13724.93137534316, 13854.445822167101, 11775.597664591123, 11933.284002959763, 12135.211211271238, 12186.329751863645, 12357.196782209128, 3963.3064213762477, 4021.8994525136814, 4089.9795501022604, 4090.6522945549664, 4221.197972385237, 72645.0, 74624.6268768658, 75352.0, 75803.24196758016, 76107.0, 10559.683209503728, 10637.46812659364, 10688.198385121146, 10821.513031913582, 10957.342559446459, 19492.902535487232, 19751.802481974995, 20439.693404598944, 20480.180792768122, 20530.0, 7641.538415800291, 7905.56519391439, 7909.960450197712, 8079.353651707849, 8203.876941845878, 4359.106383191438, 4379.846705365297, 4470.865874023784, 4493.483249426358, 4518.977405112953, 3217.501287300472, 3287.997160865959, 3297.1757060734817, 3318.601767787881, 3389.9661003390142, 549.6564647095565, 559.5635404384586, 567.7955935863087, 573.0201355681747, 582.2459914410848, 522.55321699946, 634.4163369699887, 634.5621521150391, 652.3476523476523, 659.66686823154, 8019.198080192051, 8078.23256790604, 8182.83634327322, 8335.541545215072, 8348.081711011784, 585.5110982329802, 587.9441453061952, 623.688155922039, 632.9145317374871, 658.2298710508717, 3859.1895701902354, 3874.321993651156, 3918.921621567553, 3959.5248570171766, 4020.5175378954127, 79480.60259698665, 79784.0, 80611.59694201492, 81508.0, 82381.58809205916, 70281.64859175774, 70854.0, 70872.0, 72370.3351913413, 74280.0, 50896.49103509066, 63685.36314636794, 63980.360196398375, 64145.35854641487, 64540.354596453435, 8771.824563508566, 9233.492157931378, 9487.193588545051, 9623.470709111067, 9897.604095836374, 35155.47266790949, 39876.401853971634, 40502.114640511776, 42089.36865946952, 42607.786961064994, 52322.21516677329, 54090.18864716954, 55048.44951550433, 55273.0, 56600.433995659514, 11994.580189693319, 12015.519379224734, 12101.81847272274, 12416.441260143134, 12478.563250286197, 12797.424115914993, 13234.801477977646, 13312.201267923956, 13682.384292706653, 14092.22492762867, 12666.838290625474, 12786.74426511446, 12793.680157996125, 12811.743765124458, 12978.415971281504, 10865.456727163768, 12017.579384721494, 12206.328651923874, 12314.815277770662, 12446.937765311115, 10344.069033786694, 10534.57861685524, 10709.571617135227, 10827.621033263798, 10980.341179529254, 9893.50532473359, 10147.949260253652, 10184.490775461345, 10319.845202321821, 10421.791564168825, 9491.715248542554, 10925.726856828644, 11095.5561777528, 11161.0, 11221.438928053727], "value": 6809.90271590658, "unit": "score"}], "name": "dromaeo_css", "value": 6876.521412316134, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 112.0, 112.0, 112.0, 110.0, 110.0, 110.0, 111.0, 110.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 117.0, 120.0, 158.0, 122.0, 127.0, 131.0, 119.0, 145.0, 119.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 161.0, 159.0, 159.0, 160.0, 160.0, 161.0, 162.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 118.0, 75.0, 76.0, 77.0, 76.0, 90.0, 75.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 108.0, 84.0, 83.0, 83.0, 96.0, 83.0, 83.0, 112.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 127.0, 127.0, 127.0, 130.0, 126.0, 127.0, 127.0, 126.0], "value": 127.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 147.0, 146.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 54.0, 52.0, 53.0, 55.0, 53.0, 53.0, 55.0, 52.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 64.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 80.0, 80.0, 79.0, 89.0, 79.0, 88.0, 79.0, 85.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 128.0, 134.0, 126.0, 137.0, 130.0, 127.0, 131.0, 164.0, 138.0], "value": 134.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 162.0, 182.0, 164.0, 169.0, 167.0, 165.0, 170.0, 168.0, 169.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 61.0, 64.0, 63.0, 73.0, 63.0, 68.0, 70.0, 65.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1509.3, "alertThreshold": 2.0}]} 21:32:28 INFO - Return code: 0 21:32:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:32:28 INFO - # TBPL SUCCESS # 21:32:28 INFO - Running post-action listener: _resource_record_post_action 21:32:28 INFO - [mozharness: 2016-12-17 05:32:28.227264Z] Finished run-tests step (success) 21:32:28 INFO - Running post-run listener: _resource_record_post_run 21:32:28 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 536576; Write bytes: 409186304; Read time: 80; Write time: 346300 21:32:28 INFO - TinderboxPrint: CPU usage
13.2% 21:32:28 INFO - TinderboxPrint: I/O read bytes / time
536,576 / 80 21:32:28 INFO - TinderboxPrint: I/O write bytes / time
409,186,304 / 346,300 21:32:28 INFO - TinderboxPrint: CPU idle
8,289.7 (86.8%) 21:32:28 INFO - TinderboxPrint: CPU user
1,210.5 (12.7%) 21:32:28 INFO - TinderboxPrint: Swap in / out
0 / 0 21:32:28 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115290112; Read time: 0; Write time: 96080 21:32:28 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 532480; Write bytes: 293896192; Read time: 80; Write time: 250220 21:32:28 INFO - Running post-run listener: _upload_blobber_files 21:32:28 INFO - Blob upload gear active. 21:32:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:32:28 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 21:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:32:29 INFO - (blobuploader) - INFO - Open directory for files ... 21:32:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:32:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:32:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:32:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:32:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:32:29 INFO - (blobuploader) - INFO - Done attempting. 21:32:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:32:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:32:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:32:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:32:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:32:30 INFO - (blobuploader) - INFO - Done attempting. 21:32:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:32:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:32:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:32:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:32:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:32:34 INFO - (blobuploader) - INFO - Done attempting. 21:32:34 INFO - (blobuploader) - INFO - Iteration through files over. 21:32:34 INFO - Return code: 0 21:32:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:32:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:32:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f415c7c209b5612dc8db9d095b8284173f045b1b575d2ceb849ce1f6e3fa5c276df2a84333c4b44ea79663d023fea2f28179fd1af53fcfe714c1de71b764e32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/975c21dc890084c356fdf113a33499f1ec74050f13fc6975d0ea725fe01f5d7f6afaffcbebf40690f4f042c3dad0f00f7992c0d149699057a64a93d511c65440", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50870bb9b6f17be2b4f8952e55c34e7803a0e7076e4beef35850f944fabe544e58912bdf06f1e6e20adee9d85dfb8077aa952daa8729baa7831a2d17d95bf89"} 21:32:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:32:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:32:34 INFO - Contents: 21:32:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f415c7c209b5612dc8db9d095b8284173f045b1b575d2ceb849ce1f6e3fa5c276df2a84333c4b44ea79663d023fea2f28179fd1af53fcfe714c1de71b764e32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/975c21dc890084c356fdf113a33499f1ec74050f13fc6975d0ea725fe01f5d7f6afaffcbebf40690f4f042c3dad0f00f7992c0d149699057a64a93d511c65440", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50870bb9b6f17be2b4f8952e55c34e7803a0e7076e4beef35850f944fabe544e58912bdf06f1e6e20adee9d85dfb8077aa952daa8729baa7831a2d17d95bf89"} 21:32:34 INFO - Running post-run listener: copy_logs_to_upload_dir 21:32:34 INFO - Copying logs to upload dir... 21:32:34 INFO - mkdir: /builds/slave/test/build/upload/logs 21:32:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.240926 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-12-16 21:32:34.291995) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 21:32:34.295941) ========= 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=62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f415c7c209b5612dc8db9d095b8284173f045b1b575d2ceb849ce1f6e3fa5c276df2a84333c4b44ea79663d023fea2f28179fd1af53fcfe714c1de71b764e32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/975c21dc890084c356fdf113a33499f1ec74050f13fc6975d0ea725fe01f5d7f6afaffcbebf40690f4f042c3dad0f00f7992c0d149699057a64a93d511c65440", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50870bb9b6f17be2b4f8952e55c34e7803a0e7076e4beef35850f944fabe544e58912bdf06f1e6e20adee9d85dfb8077aa952daa8729baa7831a2d17d95bf89"} build_url:https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011748 build_url: 'https://queue.taskcluster.net/v1/task/RfDwi_phRBmFScRvrn_Ykw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f415c7c209b5612dc8db9d095b8284173f045b1b575d2ceb849ce1f6e3fa5c276df2a84333c4b44ea79663d023fea2f28179fd1af53fcfe714c1de71b764e32", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/975c21dc890084c356fdf113a33499f1ec74050f13fc6975d0ea725fe01f5d7f6afaffcbebf40690f4f042c3dad0f00f7992c0d149699057a64a93d511c65440", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50870bb9b6f17be2b4f8952e55c34e7803a0e7076e4beef35850f944fabe544e58912bdf06f1e6e20adee9d85dfb8077aa952daa8729baa7831a2d17d95bf89"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 21:32:34.321491) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 21:32:34.321824) ========= 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=62c568580acbfdaac3eec7be000001dc-1481951392.188618-847585327 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004425 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 21:32:34.407595) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 21:32:34.407910) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 21:32:34.408208) ========= ========= Total master_lag: 0.18 =========