builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-042 starttime: 1482343080.19 results: success (0) buildid: 20161221075354 builduid: 9ccf4e2c071042749c4908a6ff1b0832 revision: 8a362f5c65fbe14a6976beb15905e20fca53d3ac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:00.188634) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:00.189143) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:00.189436) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:00.204509) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:00.204907) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-21 09:58:01-- 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% 45.6M=0s 2016-12-21 09:58:01 (45.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093071 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:00.313169) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:00.313635) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018451 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:00.363541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 09:58:00.363897) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 8a362f5c65fbe14a6976beb15905e20fca53d3ac --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 8a362f5c65fbe14a6976beb15905e20fca53d3ac --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=74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-21 09:58:01,307 truncating revision to first 12 chars 2016-12-21 09:58:01,308 Setting DEBUG logging. 2016-12-21 09:58:01,308 attempt 1/10 2016-12-21 09:58:01,308 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/8a362f5c65fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 09:58:02,430 unpacking tar archive at: graphics-8a362f5c65fb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.417438 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 09:58:01.887752) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:01.888221) ========= script_repo_revision: 8a362f5c65fbe14a6976beb15905e20fca53d3ac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:01.888865) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:01.889256) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 09:58:01.901034) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-12-21 09:58:01.901427) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:58:02 INFO - MultiFileLogger online at 20161221 09:58:02 in /builds/slave/test 09:58:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 09:58:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:58:02 INFO - {'append_to_log': False, 09:58:02 INFO - 'base_work_dir': '/builds/slave/test', 09:58:02 INFO - 'blob_upload_branch': 'Graphics', 09:58:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:58:02 INFO - 'branch': 'Graphics', 09:58:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:58:02 INFO - 'config_files': ('talos/linux_config.py',), 09:58:02 INFO - 'default_actions': ('clobber', 09:58:02 INFO - 'read-buildbot-config', 09:58:02 INFO - 'download-and-extract', 09:58:02 INFO - 'populate-webroot', 09:58:02 INFO - 'create-virtualenv', 09:58:02 INFO - 'install', 09:58:02 INFO - 'run-tests'), 09:58:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:58:02 INFO - 'download_minidump_stackwalk': True, 09:58:02 INFO - 'download_symbols': 'ondemand', 09:58:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:58:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:58:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:58:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:58:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:58:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:58:02 INFO - 'installer_path': 'installer.exe', 09:58:02 INFO - 'log_level': 'info', 09:58:02 INFO - 'log_name': 'talos', 09:58:02 INFO - 'log_to_console': True, 09:58:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:58:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:58:02 INFO - 'opt_config_files': (), 09:58:02 INFO - 'pip_index': False, 09:58:02 INFO - 'sps_profile': False, 09:58:02 INFO - 'sps_profile_interval': 0, 09:58:02 INFO - 'suite': 'dromaeojs', 09:58:02 INFO - 'system_bits': '32', 09:58:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:58:02 INFO - 'title': 'talos-linux64-ix-042', 09:58:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:58:02 INFO - 'use_talos_json': True, 09:58:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:58:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:58:02 INFO - 'work_dir': 'build'} 09:58:02 INFO - [mozharness: 2016-12-21 17:58:02.942975Z] Running clobber step. 09:58:02 INFO - Running pre-action listener: _resource_record_pre_action 09:58:02 INFO - Running main action method: clobber 09:58:02 INFO - rmtree: /builds/slave/test/build 09:58:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:58:03 INFO - Running post-action listener: _resource_record_post_action 09:58:03 INFO - [mozharness: 2016-12-21 17:58:03.466600Z] Finished clobber step (success) 09:58:03 INFO - [mozharness: 2016-12-21 17:58:03.466703Z] Running read-buildbot-config step. 09:58:03 INFO - Running pre-action listener: _resource_record_pre_action 09:58:03 INFO - Running main action method: read_buildbot_config 09:58:03 INFO - Using buildbot properties: 09:58:03 INFO - { 09:58:03 INFO - "project": "", 09:58:03 INFO - "product": "firefox", 09:58:03 INFO - "script_repo_revision": "default", 09:58:03 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 09:58:03 INFO - "repository": "", 09:58:03 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 09:58:03 INFO - "buildid": "20161221075354", 09:58:03 INFO - "pgo_build": "False", 09:58:03 INFO - "basedir": "/builds/slave/test", 09:58:03 INFO - "buildnumber": 29, 09:58:03 INFO - "slavename": "talos-linux64-ix-042", 09:58:03 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:58:03 INFO - "platform": "ubuntu64_hw", 09:58:03 INFO - "scheduler": "tests-graphics-linux64-talos", 09:58:03 INFO - "branch": "graphics", 09:58:03 INFO - "repo_path": "projects/graphics", 09:58:03 INFO - "revision": "8a362f5c65fbe14a6976beb15905e20fca53d3ac", 09:58:03 INFO - "stage_platform": "linux64", 09:58:03 INFO - "builduid": "9ccf4e2c071042749c4908a6ff1b0832", 09:58:03 INFO - "slavebuilddir": "test" 09:58:03 INFO - } 09:58:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:58:03 INFO - Running post-action listener: _resource_record_post_action 09:58:03 INFO - [mozharness: 2016-12-21 17:58:03.469266Z] Finished read-buildbot-config step (success) 09:58:03 INFO - [mozharness: 2016-12-21 17:58:03.469355Z] Running download-and-extract step. 09:58:03 INFO - Running pre-action listener: _resource_record_pre_action 09:58:03 INFO - Running main action method: download_and_extract 09:58:03 INFO - mkdir: /builds/slave/test/build/tests 09:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:03 INFO - trying https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:58:03 INFO - Downloading https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/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 09:58:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/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 09:58:04 INFO - Downloaded 1361 bytes. 09:58:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:58:04 INFO - Using the following test package requirements: 09:58:04 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:58:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:58:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:58:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:04 INFO - u'jsshell-linux-x86_64.zip'], 09:58:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:58:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:58:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:58:04 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:58:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:58:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:58:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:58:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:58:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:58:04 INFO - Fetch https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:58:04 INFO - Expected file size: 20260924 09:58:04 INFO - Obtained file size: 20260924 09:58:08 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 09:58:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:58:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:58:08 INFO - Fetch https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:58:09 INFO - Expected file size: 20260924 09:58:09 INFO - Obtained file size: 20260924 09:58:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:58:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:58:13 INFO - Fetch https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:58:14 INFO - Expected file size: 14409734 09:58:14 INFO - Obtained file size: 14409734 09:58:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:14 INFO - trying https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:58:14 INFO - Downloading https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/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 09:58:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/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 09:58:16 INFO - Downloaded 55654429 bytes. 09:58:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:58:16 INFO - mkdir: /builds/slave/test/properties 09:58:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:58:16 INFO - Writing to file /builds/slave/test/properties/build_url 09:58:16 INFO - Contents: 09:58:16 INFO - build_url:https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:58:16 INFO - Running post-action listener: _resource_record_post_action 09:58:16 INFO - Running post-action listener: set_extra_try_arguments 09:58:16 INFO - [mozharness: 2016-12-21 17:58:16.903537Z] Finished download-and-extract step (success) 09:58:16 INFO - [mozharness: 2016-12-21 17:58:16.903760Z] Running populate-webroot step. 09:58:16 INFO - Running pre-action listener: _resource_record_pre_action 09:58:16 INFO - Running main action method: populate_webroot 09:58:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:58:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:58:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:58:16 INFO - '--tppagecycles', 09:58:16 INFO - '7'], 09:58:16 INFO - 'tests': ['tsvgm']}}, 09:58:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:58:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:58:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:58:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:58:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:58:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:58:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:58:16 INFO - 'talos_options': ['--disable-e10s'], 09:58:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:58:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:58:16 INFO - 'talos_options': ['--disable-e10s'], 09:58:16 INFO - 'tests': ['damp', 'tps']}, 09:58:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:58:16 INFO - 'tests': ['damp', 'tps']}, 09:58:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:58:16 INFO - 'tests': ['dromaeo_dom']}, 09:58:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:58:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:58:16 INFO - 'tests': ['basic_compositor_video']}, 09:58:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:58:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:58:16 INFO - 'tests': ['a11yr', 09:58:16 INFO - 'ts_paint', 09:58:16 INFO - 'tpaint', 09:58:16 INFO - 'sessionrestore', 09:58:16 INFO - 'sessionrestore_no_auto_restore', 09:58:16 INFO - 'tabpaint']}, 09:58:16 INFO - 'other-e10s': {'tests': ['a11yr', 09:58:16 INFO - 'ts_paint', 09:58:16 INFO - 'tpaint', 09:58:16 INFO - 'sessionrestore', 09:58:16 INFO - 'sessionrestore_no_auto_restore', 09:58:16 INFO - 'tabpaint']}, 09:58:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:58:16 INFO - 'ts_paint', 09:58:16 INFO - 'tpaint', 09:58:16 INFO - 'sessionrestore', 09:58:16 INFO - 'sessionrestore_no_auto_restore', 09:58:16 INFO - 'tabpaint']}, 09:58:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:58:16 INFO - 'ts_paint', 09:58:16 INFO - 'tpaint', 09:58:16 INFO - 'sessionrestore', 09:58:16 INFO - 'sessionrestore_no_auto_restore', 09:58:16 INFO - 'tabpaint']}, 09:58:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:58:16 INFO - 'tests': ['a11yr', 09:58:16 INFO - 'ts_paint', 09:58:16 INFO - 'tpaint', 09:58:16 INFO - 'sessionrestore', 09:58:16 INFO - 'sessionrestore_no_auto_restore', 09:58:16 INFO - 'tabpaint']}, 09:58:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:58:16 INFO - 'tests': ['a11yr', 09:58:16 INFO - 'ts_paint', 09:58:16 INFO - 'tpaint', 09:58:16 INFO - 'sessionrestore', 09:58:16 INFO - 'sessionrestore_no_auto_restore', 09:58:16 INFO - 'tabpaint']}, 09:58:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:58:16 INFO - 'tests': ['tsvgx', 09:58:16 INFO - 'tsvgr_opacity', 09:58:16 INFO - 'tart', 09:58:16 INFO - 'tscrollx', 09:58:16 INFO - 'cart', 09:58:16 INFO - 'tsvg_static']}, 09:58:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:58:16 INFO - 'tsvgr_opacity', 09:58:16 INFO - 'tart', 09:58:16 INFO - 'tscrollx', 09:58:16 INFO - 'cart', 09:58:16 INFO - 'tsvg_static']}, 09:58:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:58:16 INFO - 'talos_options': ['--disable-e10s'], 09:58:16 INFO - 'tests': ['tp5o']}, 09:58:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:58:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:58:16 INFO - 'talos_options': ['--disable-e10s', 09:58:16 INFO - '--xperf_path', 09:58:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:16 INFO - 'tests': ['tp5n']}, 09:58:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:58:16 INFO - 'talos_options': ['--xperf_path', 09:58:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:16 INFO - 'tests': ['tp5n']}}, 09:58:16 INFO - 'talos.zip': {'path': '', 09:58:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:58:16 INFO - Running post-action listener: _resource_record_post_action 09:58:16 INFO - [mozharness: 2016-12-21 17:58:16.922251Z] Finished populate-webroot step (success) 09:58:16 INFO - [mozharness: 2016-12-21 17:58:16.922340Z] Running create-virtualenv step. 09:58:16 INFO - Running pre-action listener: _resource_record_pre_action 09:58:16 INFO - Running main action method: create_virtualenv 09:58:16 INFO - Creating virtualenv /builds/slave/test/build/venv 09:58:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:58:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:58:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:58:17 INFO - Using real prefix '/usr' 09:58:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:58:17 INFO - Installing distribute.............................................................................................................................................................................................done. 09:58:19 INFO - Installing pip.................done. 09:58:19 INFO - Return code: 0 09:58:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:58:19 INFO - Reading from file tmpfile_stdout 09:58:19 INFO - Output received: 09:58:19 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) 09:58:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:19 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 0xe1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118d3f0>, '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 0x118dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118e1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:58:19 INFO - Using env: {'DISPLAY': ':0', 09:58:19 INFO - 'HOME': '/home/cltbld', 09:58:19 INFO - 'LANG': 'en_US.UTF-8', 09:58:19 INFO - 'LANGUAGE': 'en_US:en', 09:58:19 INFO - 'LOGNAME': 'cltbld', 09:58:19 INFO - 'MAIL': '/var/mail/cltbld', 09:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:19 INFO - 'MOZ_NO_REMOTE': '1', 09:58:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:19 INFO - 'NO_EM_RESTART': '1', 09:58:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:19 INFO - 'PWD': '/builds/slave/test', 09:58:19 INFO - 'SHELL': '/bin/bash', 09:58:19 INFO - 'SHLVL': '1', 09:58:19 INFO - 'TERM': 'linux', 09:58:19 INFO - 'TMOUT': '86400', 09:58:19 INFO - 'USER': 'cltbld', 09:58:19 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 09:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:19 INFO - '_': '/tools/buildbot/bin/python'} 09:58:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:58:19 INFO - Cleaning up... 09:58:19 INFO - Return code: 0 09:58:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:19 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 0xe1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118d3f0>, '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 0x118dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118e1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:58:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:58:19 INFO - Using env: {'DISPLAY': ':0', 09:58:19 INFO - 'HOME': '/home/cltbld', 09:58:19 INFO - 'LANG': 'en_US.UTF-8', 09:58:19 INFO - 'LANGUAGE': 'en_US:en', 09:58:19 INFO - 'LOGNAME': 'cltbld', 09:58:19 INFO - 'MAIL': '/var/mail/cltbld', 09:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:19 INFO - 'MOZ_NO_REMOTE': '1', 09:58:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:19 INFO - 'NO_EM_RESTART': '1', 09:58:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:19 INFO - 'PWD': '/builds/slave/test', 09:58:19 INFO - 'SHELL': '/bin/bash', 09:58:19 INFO - 'SHLVL': '1', 09:58:19 INFO - 'TERM': 'linux', 09:58:19 INFO - 'TMOUT': '86400', 09:58:19 INFO - 'USER': 'cltbld', 09:58:19 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 09:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:19 INFO - '_': '/tools/buildbot/bin/python'} 09:58:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:20 INFO - Downloading/unpacking psutil>=3.1.1 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:58:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:22 INFO - Installing collected packages: psutil 09:58:22 INFO - Running setup.py install for psutil 09:58:22 INFO - building 'psutil._psutil_linux' extension 09:58:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:58:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:58:22 INFO - building 'psutil._psutil_posix' extension 09:58:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:58:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:58:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:22 INFO - Successfully installed psutil 09:58:22 INFO - Cleaning up... 09:58:22 INFO - Return code: 0 09:58:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:22 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 0xe1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118d3f0>, '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 0x118dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118e1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:58:22 INFO - Using env: {'DISPLAY': ':0', 09:58:22 INFO - 'HOME': '/home/cltbld', 09:58:22 INFO - 'LANG': 'en_US.UTF-8', 09:58:22 INFO - 'LANGUAGE': 'en_US:en', 09:58:22 INFO - 'LOGNAME': 'cltbld', 09:58:22 INFO - 'MAIL': '/var/mail/cltbld', 09:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:22 INFO - 'MOZ_NO_REMOTE': '1', 09:58:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:22 INFO - 'NO_EM_RESTART': '1', 09:58:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:22 INFO - 'PWD': '/builds/slave/test', 09:58:22 INFO - 'SHELL': '/bin/bash', 09:58:22 INFO - 'SHLVL': '1', 09:58:22 INFO - 'TERM': 'linux', 09:58:22 INFO - 'TMOUT': '86400', 09:58:22 INFO - 'USER': 'cltbld', 09:58:22 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 09:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:22 INFO - '_': '/tools/buildbot/bin/python'} 09:58:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58: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 09:58:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:58:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:58:25 INFO - Installing collected packages: mozsystemmonitor 09:58:25 INFO - Running setup.py install for mozsystemmonitor 09:58:25 INFO - Successfully installed mozsystemmonitor 09:58:25 INFO - Cleaning up... 09:58:25 INFO - Return code: 0 09:58:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:58:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:25 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 0xe1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118d3f0>, '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 0x118dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118e1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:58:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:58:25 INFO - Using env: {'DISPLAY': ':0', 09:58:25 INFO - 'HOME': '/home/cltbld', 09:58:25 INFO - 'LANG': 'en_US.UTF-8', 09:58:25 INFO - 'LANGUAGE': 'en_US:en', 09:58:25 INFO - 'LOGNAME': 'cltbld', 09:58:25 INFO - 'MAIL': '/var/mail/cltbld', 09:58:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:25 INFO - 'MOZ_NO_REMOTE': '1', 09:58:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:25 INFO - 'NO_EM_RESTART': '1', 09:58:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:25 INFO - 'PWD': '/builds/slave/test', 09:58:25 INFO - 'SHELL': '/bin/bash', 09:58:25 INFO - 'SHLVL': '1', 09:58:25 INFO - 'TERM': 'linux', 09:58:25 INFO - 'TMOUT': '86400', 09:58:25 INFO - 'USER': 'cltbld', 09:58:25 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 09:58:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:25 INFO - '_': '/tools/buildbot/bin/python'} 09:58:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:25 INFO - Downloading/unpacking jsonschema==2.5.1 09:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:58:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:58:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:58:27 INFO - warning: no files found matching '*.txt' 09:58:27 INFO - no previously-included directories found matching 'build' 09:58:27 INFO - no previously-included directories found matching 'dist' 09:58:27 INFO - no previously-included directories found matching '.git*' 09:58:27 INFO - Installing collected packages: jsonschema, functools32 09:58:27 INFO - Running setup.py install for functools32 09:58:27 INFO - warning: no files found matching '*.txt' 09:58:27 INFO - no previously-included directories found matching 'build' 09:58:27 INFO - no previously-included directories found matching 'dist' 09:58:27 INFO - no previously-included directories found matching '.git*' 09:58:27 INFO - Successfully installed jsonschema functools32 09:58:27 INFO - Cleaning up... 09:58:27 INFO - Return code: 0 09:58:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:58: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')]} 09:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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')]} 09:58:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118d3f0>, '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 0x118dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118e1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:58:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:58:28 INFO - Using env: {'DISPLAY': ':0', 09:58:28 INFO - 'HOME': '/home/cltbld', 09:58:28 INFO - 'LANG': 'en_US.UTF-8', 09:58:28 INFO - 'LANGUAGE': 'en_US:en', 09:58:28 INFO - 'LOGNAME': 'cltbld', 09:58:28 INFO - 'MAIL': '/var/mail/cltbld', 09:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:28 INFO - 'MOZ_NO_REMOTE': '1', 09:58:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:28 INFO - 'NO_EM_RESTART': '1', 09:58:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:28 INFO - 'PWD': '/builds/slave/test', 09:58:28 INFO - 'SHELL': '/bin/bash', 09:58:28 INFO - 'SHLVL': '1', 09:58:28 INFO - 'TERM': 'linux', 09:58:28 INFO - 'TMOUT': '86400', 09:58:28 INFO - 'USER': 'cltbld', 09:58:28 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 09:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:28 INFO - '_': '/tools/buildbot/bin/python'} 09:58:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:58:28 INFO - Cleaning up... 09:58:28 INFO - Return code: 0 09:58:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118d3f0>, '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 0x118dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118e1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:58:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:58:28 INFO - Using env: {'DISPLAY': ':0', 09:58:28 INFO - 'HOME': '/home/cltbld', 09:58:28 INFO - 'LANG': 'en_US.UTF-8', 09:58:28 INFO - 'LANGUAGE': 'en_US:en', 09:58:28 INFO - 'LOGNAME': 'cltbld', 09:58:28 INFO - 'MAIL': '/var/mail/cltbld', 09:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:28 INFO - 'MOZ_NO_REMOTE': '1', 09:58:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:28 INFO - 'NO_EM_RESTART': '1', 09:58:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:28 INFO - 'PWD': '/builds/slave/test', 09:58:28 INFO - 'SHELL': '/bin/bash', 09:58:28 INFO - 'SHLVL': '1', 09:58:28 INFO - 'TERM': 'linux', 09:58:28 INFO - 'TMOUT': '86400', 09:58:28 INFO - 'USER': 'cltbld', 09:58:28 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 09:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:28 INFO - '_': '/tools/buildbot/bin/python'} 09:58:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:58:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:58:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:58:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:31 INFO - Downloading docopt-0.6.1.tar.gz 09:58:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:58:31 INFO - Installing collected packages: blobuploader, requests, docopt 09:58:31 INFO - Running setup.py install for blobuploader 09:58:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:58:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:58:31 INFO - Running setup.py install for requests 09:58:31 INFO - Running setup.py install for docopt 09:58:31 INFO - Successfully installed blobuploader requests docopt 09:58:31 INFO - Cleaning up... 09:58:31 INFO - Return code: 0 09:58:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58: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')]} 09:58:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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')]} 09:58:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:31 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 0xe1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118d3f0>, '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 0x118dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118e1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:58:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:31 INFO - Using env: {'DISPLAY': ':0', 09:58:31 INFO - 'HOME': '/home/cltbld', 09:58:31 INFO - 'LANG': 'en_US.UTF-8', 09:58:31 INFO - 'LANGUAGE': 'en_US:en', 09:58:31 INFO - 'LOGNAME': 'cltbld', 09:58:31 INFO - 'MAIL': '/var/mail/cltbld', 09:58:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:31 INFO - 'MOZ_NO_REMOTE': '1', 09:58:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:31 INFO - 'NO_EM_RESTART': '1', 09:58:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:31 INFO - 'PWD': '/builds/slave/test', 09:58:31 INFO - 'SHELL': '/bin/bash', 09:58:31 INFO - 'SHLVL': '1', 09:58:31 INFO - 'TERM': 'linux', 09:58:31 INFO - 'TMOUT': '86400', 09:58:31 INFO - 'USER': 'cltbld', 09:58:31 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 09:58:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:31 INFO - '_': '/tools/buildbot/bin/python'} 09:58:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:32 INFO - Running setup.py (path:/tmp/pip-Ejizct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:32 INFO - Running setup.py (path:/tmp/pip-EGFn8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:32 INFO - Running setup.py (path:/tmp/pip-JDzi3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:32 INFO - Running setup.py (path:/tmp/pip-WN9PB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:32 INFO - Running setup.py (path:/tmp/pip-XtNoDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:32 INFO - Running setup.py (path:/tmp/pip-4c9AIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:32 INFO - Running setup.py (path:/tmp/pip-Lq6UHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:32 INFO - Running setup.py (path:/tmp/pip-oKFrjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:32 INFO - Running setup.py (path:/tmp/pip-x5dhry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:32 INFO - Running setup.py (path:/tmp/pip-ez8HTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:32 INFO - Running setup.py (path:/tmp/pip-WkbxIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:32 INFO - Running setup.py (path:/tmp/pip-HL61xt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:32 INFO - Running setup.py (path:/tmp/pip-iHRxzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:33 INFO - Running setup.py (path:/tmp/pip-MDtJaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:33 INFO - Running setup.py (path:/tmp/pip-GsKKgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:33 INFO - Running setup.py (path:/tmp/pip-KeVpSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:33 INFO - Running setup.py (path:/tmp/pip-kcojkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:58:33 INFO - Running setup.py install for manifestparser 09:58:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:58:33 INFO - Running setup.py install for mozcrash 09:58:33 INFO - Running setup.py install for mozdebug 09:58:33 INFO - Running setup.py install for mozdevice 09:58:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:58:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:58:33 INFO - Running setup.py install for mozfile 09:58:33 INFO - Running setup.py install for mozhttpd 09:58:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:58:33 INFO - Running setup.py install for mozinfo 09:58:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:58:34 INFO - Running setup.py install for mozInstall 09:58:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:58:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:58:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:58:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:58:34 INFO - Running setup.py install for mozleak 09:58:34 INFO - Running setup.py install for mozlog 09:58:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:58:34 INFO - Running setup.py install for moznetwork 09:58:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:58:34 INFO - Running setup.py install for mozprocess 09:58:34 INFO - Running setup.py install for mozprofile 09:58:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:58:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:58:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:58:34 INFO - Running setup.py install for mozrunner 09:58:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:58:34 INFO - Running setup.py install for mozscreenshot 09:58:34 INFO - Running setup.py install for moztest 09:58:35 INFO - Running setup.py install for mozversion 09:58:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:58:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:58:35 INFO - Cleaning up... 09:58:35 INFO - Return code: 0 09:58:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:35 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 0xe1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118d3f0>, '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 0x118dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118e1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:58:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:35 INFO - Using env: {'DISPLAY': ':0', 09:58:35 INFO - 'HOME': '/home/cltbld', 09:58:35 INFO - 'LANG': 'en_US.UTF-8', 09:58:35 INFO - 'LANGUAGE': 'en_US:en', 09:58:35 INFO - 'LOGNAME': 'cltbld', 09:58:35 INFO - 'MAIL': '/var/mail/cltbld', 09:58:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:35 INFO - 'MOZ_NO_REMOTE': '1', 09:58:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:35 INFO - 'NO_EM_RESTART': '1', 09:58:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:35 INFO - 'PWD': '/builds/slave/test', 09:58:35 INFO - 'SHELL': '/bin/bash', 09:58:35 INFO - 'SHLVL': '1', 09:58:35 INFO - 'TERM': 'linux', 09:58:35 INFO - 'TMOUT': '86400', 09:58:35 INFO - 'USER': 'cltbld', 09:58:35 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 09:58:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:35 INFO - '_': '/tools/buildbot/bin/python'} 09:58:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:35 INFO - Running setup.py (path:/tmp/pip-L21fv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:58:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:35 INFO - Running setup.py (path:/tmp/pip-QW7ZpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:35 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)) 09:58:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:35 INFO - Running setup.py (path:/tmp/pip-rwgUma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:58:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:35 INFO - Running setup.py (path:/tmp/pip-lHEfsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:35 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)) 09:58:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:35 INFO - Running setup.py (path:/tmp/pip-kmZFr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:58:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:35 INFO - Running setup.py (path:/tmp/pip-fXiuzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:58:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:35 INFO - Running setup.py (path:/tmp/pip-uRt9d2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:58:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:35 INFO - Running setup.py (path:/tmp/pip-gjXYP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:58:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:35 INFO - Running setup.py (path:/tmp/pip-BGynot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:36 INFO - Running setup.py (path:/tmp/pip-toAgqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:36 INFO - Running setup.py (path:/tmp/pip-kzxiy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:36 INFO - Running setup.py (path:/tmp/pip-pCaUeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:36 INFO - Running setup.py (path:/tmp/pip-J0e5Nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:36 INFO - Running setup.py (path:/tmp/pip-JmH_yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:36 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)) 09:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:36 INFO - Running setup.py (path:/tmp/pip-LavQPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:36 INFO - Running setup.py (path:/tmp/pip-wxqFF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:36 INFO - Running setup.py (path:/tmp/pip-4eHV7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:58:36 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)) 09:58:36 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)) 09:58:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:58:36 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)) 09:58:36 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)) 09:58:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:38 INFO - Downloading blessings-1.6.tar.gz 09:58:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:58:38 INFO - Installing collected packages: blessings 09:58:38 INFO - Running setup.py install for blessings 09:58:38 INFO - Successfully installed blessings 09:58:38 INFO - Cleaning up... 09:58:38 INFO - Return code: 0 09:58:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:58:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:39 INFO - Reading from file tmpfile_stdout 09:58:39 INFO - Current package versions: 09:58:39 INFO - argparse == 1.2.1 09:58:39 INFO - blessings == 1.6 09:58:39 INFO - blobuploader == 1.2.4 09:58:39 INFO - docopt == 0.6.1 09:58:39 INFO - functools32 == 3.2.3-2 09:58:39 INFO - jsonschema == 2.5.1 09:58:39 INFO - manifestparser == 1.1 09:58:39 INFO - mozInstall == 1.12 09:58:39 INFO - mozcrash == 1.0 09:58:39 INFO - mozdebug == 0.1 09:58:39 INFO - mozdevice == 0.49 09:58:39 INFO - mozfile == 1.2 09:58:39 INFO - mozhttpd == 0.7 09:58:39 INFO - mozinfo == 0.9 09:58:39 INFO - mozleak == 0.1 09:58:39 INFO - mozlog == 3.4 09:58:39 INFO - moznetwork == 0.27 09:58:39 INFO - mozprocess == 0.23 09:58:39 INFO - mozprofile == 0.28 09:58:39 INFO - mozrunner == 6.13 09:58:39 INFO - mozscreenshot == 0.1 09:58:39 INFO - mozsystemmonitor == 0.3 09:58:39 INFO - moztest == 0.7 09:58:39 INFO - mozversion == 1.4 09:58:39 INFO - psutil == 3.1.1 09:58:39 INFO - requests == 1.2.3 09:58:39 INFO - wsgiref == 0.1.2 09:58:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:39 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 0xe1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118d3f0>, '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 0x118dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118e1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:58:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:58:39 INFO - Using env: {'DISPLAY': ':0', 09:58:39 INFO - 'HOME': '/home/cltbld', 09:58:39 INFO - 'LANG': 'en_US.UTF-8', 09:58:39 INFO - 'LANGUAGE': 'en_US:en', 09:58:39 INFO - 'LOGNAME': 'cltbld', 09:58:39 INFO - 'MAIL': '/var/mail/cltbld', 09:58:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:39 INFO - 'MOZ_NO_REMOTE': '1', 09:58:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:39 INFO - 'NO_EM_RESTART': '1', 09:58:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:39 INFO - 'PWD': '/builds/slave/test', 09:58:39 INFO - 'SHELL': '/bin/bash', 09:58:39 INFO - 'SHLVL': '1', 09:58:39 INFO - 'TERM': 'linux', 09:58:39 INFO - 'TMOUT': '86400', 09:58:39 INFO - 'USER': 'cltbld', 09:58:39 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 09:58:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:39 INFO - '_': '/tools/buildbot/bin/python'} 09:58:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:58:39 INFO - Cleaning up... 09:58:39 INFO - Return code: 0 09:58:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:58:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfeaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118fc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118d3f0>, '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 0x118dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118e1a0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:58:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:58:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:58:39 INFO - Using env: {'DISPLAY': ':0', 09:58:39 INFO - 'HOME': '/home/cltbld', 09:58:39 INFO - 'LANG': 'en_US.UTF-8', 09:58:39 INFO - 'LANGUAGE': 'en_US:en', 09:58:39 INFO - 'LOGNAME': 'cltbld', 09:58:39 INFO - 'MAIL': '/var/mail/cltbld', 09:58:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:39 INFO - 'MOZ_NO_REMOTE': '1', 09:58:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:39 INFO - 'NO_EM_RESTART': '1', 09:58:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:39 INFO - 'PWD': '/builds/slave/test', 09:58:39 INFO - 'SHELL': '/bin/bash', 09:58:39 INFO - 'SHLVL': '1', 09:58:39 INFO - 'TERM': 'linux', 09:58:39 INFO - 'TMOUT': '86400', 09:58:39 INFO - 'USER': 'cltbld', 09:58:39 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 09:58:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:39 INFO - '_': '/tools/buildbot/bin/python'} 09:58:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:58:39 INFO - Cleaning up... 09:58:39 INFO - Return code: 0 09:58:39 INFO - Running post-action listener: _resource_record_post_action 09:58:39 INFO - Running post-action listener: _start_resource_monitoring 09:58:39 INFO - Starting resource monitoring. 09:58:39 INFO - [mozharness: 2016-12-21 17:58:39.547346Z] Finished create-virtualenv step (success) 09:58:39 INFO - [mozharness: 2016-12-21 17:58:39.547824Z] Running install step. 09:58:39 INFO - Running pre-action listener: _resource_record_pre_action 09:58:39 INFO - Running main action method: install 09:58:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:39 INFO - Reading from file tmpfile_stdout 09:58:39 INFO - Detecting whether we're running mozinstall >=1.0... 09:58:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:58:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:58:39 INFO - Reading from file tmpfile_stdout 09:58:39 INFO - Output received: 09:58:39 INFO - Usage: mozinstall [options] installer 09:58:39 INFO - Options: 09:58:39 INFO - -h, --help show this help message and exit 09:58:39 INFO - -d DEST, --destination=DEST 09:58:39 INFO - Directory to install application into. [default: 09:58:39 INFO - "/builds/slave/test"] 09:58:39 INFO - --app=APP Application being installed. [default: firefox] 09:58:39 INFO - mkdir: /builds/slave/test/build/application 09:58:39 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'] 09:58:39 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 09:58:55 INFO - Reading from file tmpfile_stdout 09:58:55 INFO - Output received: 09:58:55 INFO - /builds/slave/test/build/application/firefox/firefox 09:58:55 INFO - Running post-action listener: _resource_record_post_action 09:58:55 INFO - [mozharness: 2016-12-21 17:58:55.882749Z] Finished install step (success) 09:58:55 INFO - [mozharness: 2016-12-21 17:58:55.882992Z] Running run-tests step. 09:58:55 INFO - Running pre-action listener: _resource_record_pre_action 09:58:55 INFO - Running main action method: run_tests 09:58:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:58:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:58:55 INFO - Python 2.7.3 09:58:55 INFO - Return code: 0 09:58:55 INFO - grabbing minidump binary from tooltool 09:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:55 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 0x118d3f0>, '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 0x118dcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118e1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:58:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:58:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:58:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:58:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:58:55 INFO - Return code: 0 09:58:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:58:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:58:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:58:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:58:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:58:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:58:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:58:55 INFO - Using env: {'DISPLAY': ':0', 09:58:55 INFO - 'HOME': '/home/cltbld', 09:58:55 INFO - 'LANG': 'en_US.UTF-8', 09:58:55 INFO - 'LANGUAGE': 'en_US:en', 09:58:55 INFO - 'LOGNAME': 'cltbld', 09:58:55 INFO - 'MAIL': '/var/mail/cltbld', 09:58:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:58:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:55 INFO - 'MOZ_NO_REMOTE': '1', 09:58:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:58:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:55 INFO - 'NO_EM_RESTART': '1', 09:58:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:55 INFO - 'PWD': '/builds/slave/test', 09:58:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:58:55 INFO - 'SHELL': '/bin/bash', 09:58:55 INFO - 'SHLVL': '1', 09:58:55 INFO - 'TERM': 'linux', 09:58:55 INFO - 'TMOUT': '86400', 09:58:55 INFO - 'USER': 'cltbld', 09:58:55 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401', 09:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:55 INFO - '_': '/tools/buildbot/bin/python'} 09:58:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:58:56 INFO - mozversion application_buildid: 20161221075354 09:58:56 INFO - mozversion application_changeset: 8a362f5c65fbe14a6976beb15905e20fca53d3ac 09:58:56 INFO - mozversion application_display_name: Nightly 09:58:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:58:56 INFO - mozversion application_name: Firefox 09:58:56 INFO - mozversion application_remotingname: firefox 09:58:56 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 09:58:56 INFO - mozversion application_vendor: Mozilla 09:58:56 INFO - mozversion application_version: 53.0a1 09:58:56 INFO - mozversion platform_buildid: 20161221075354 09:58:56 INFO - mozversion platform_changeset: 8a362f5c65fbe14a6976beb15905e20fca53d3ac 09:58:56 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 09:58:56 INFO - mozversion platform_version: 53.0a1 09:58:56 INFO - using testdate: 1482343136 09:58:56 INFO - actual date: 1482343136 09:58:56 INFO - starting webserver on 'localhost:54354' 09:58:56 INFO - SUITE-START | Running 2 tests 09:58:56 INFO - TEST-START | dromaeo_css 09:58:56 INFO - operating with platform_type : linux_ 09:58:56 INFO - Initialising browser for dromaeo_css test... 09:58:56 INFO - TEST-INFO | started process 13738 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXbog0e/profile http://localhost:54354/getInfo.html) 09:59:00 INFO - PROCESS | 13738 | __metrics Screen width/height:1600/1200 09:59:00 INFO - PROCESS | 13738 | colorDepth:24 09:59:00 INFO - PROCESS | 13738 | Browser inner width/height: 1024/697 09:59:00 INFO - PROCESS | 13738 | __metrics 09:59:00 INFO - PROCESS | 13738 | 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" 09:59:00 INFO - PROCESS | 13738 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:59:01 INFO - PROCESS | 13738 | console.error: PushService: 09:59:01 INFO - PROCESS | 13738 | stateChangeProcessEnqueue: Error transitioning state 09:59:01 INFO - PROCESS | 13738 | UnknownError 09:59:02 INFO - TEST-INFO | 13738: exit 0 09:59:02 INFO - Browser initialized. 09:59:02 INFO - Running cycle 1/1 for dromaeo_css test... 09:59:02 INFO - TEST-INFO | started process 13819 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXbog0e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:59:14 INFO - PROCESS | 13819 | RSS: Main: 154107904 09:59:14 INFO - PROCESS | 13819 | 10:00:05 INFO - PROCESS | 13819 | 1482343205120 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] 10:02:19 INFO - PROCESS | 13819 | Cycle 1(1): loaded http://localhost:54354/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54354/tests/dromaeo/cssquery-ext.html) 10:02:21 INFO - PROCESS | 13819 | RSS: Main: 323497984 10:02:21 INFO - PROCESS | 13819 | 10:05:23 INFO - PROCESS | 13819 | Cycle 1(1): loaded http://localhost:54354/tests/dromaeo/cssquery-ext.html (next: http://localhost:54354/tests/dromaeo/cssquery-jquery.html) 10:05:24 INFO - PROCESS | 13819 | RSS: Main: 237178880 10:05:24 INFO - PROCESS | 13819 | 10:08:27 INFO - PROCESS | 13819 | Cycle 1(1): loaded http://localhost:54354/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54354/tests/dromaeo/cssquery-mootools.html) 10:08:27 INFO - PROCESS | 13819 | RSS: Main: 229408768 10:08:27 INFO - PROCESS | 13819 | 10:11:29 INFO - PROCESS | 13819 | Cycle 1(1): loaded http://localhost:54354/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54354/tests/dromaeo/cssquery-prototype.html) 10:11:30 INFO - PROCESS | 13819 | RSS: Main: 188706816 10:11:30 INFO - PROCESS | 13819 | 10:14:34 INFO - PROCESS | 13819 | Cycle 1(1): loaded http://localhost:54354/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54354/tests/dromaeo/cssquery-yui.html) 10:14:34 INFO - PROCESS | 13819 | RSS: Main: 187289600 10:14:34 INFO - PROCESS | 13819 | 10:17:37 INFO - PROCESS | 13819 | Cycle 1(1): loaded http://localhost:54354/tests/dromaeo/cssquery-yui.html (next: http://localhost:54354/tests/dromaeo/cssquery-dojo.html) 10:17:38 INFO - PROCESS | 13819 | RSS: Main: 281440256 10:17:38 INFO - PROCESS | 13819 | 10:17:38 INFO - PROCESS | 13819 | __start_tp_report 10:17:38 INFO - PROCESS | 13819 | _x_x_mozilla_page_load 10:17:38 INFO - PROCESS | 13819 | _x_x_mozilla_page_load_details 10:17:38 INFO - PROCESS | 13819 | |i|pagename|runs| 10:17:38 INFO - PROCESS | 13819 | |0;dojo.html;2602.6746656667915;2625.330540712118;2642.273374821924;2657.6677915260593;2665.853378064207;4552.476465206502;4589.518100599439;4803.135435621591;4818;4825.179719447694;4614.492405835355;4748.928766068511;4773.5226477352335;4782.880427989293;4812.831550895719;4332.1335732853395;4363.214621368153;4392.209402307583;4456.242438785414;4531.388262584542;3966.3653815389544;4304.80628371724;4438.245498265286;4482.238019536687;4527.117212143633;4219.641330486912;4250.3199488081755;4434.290513517822;4466.994926141625;4471.150481408522;4292.1630282095;4324.524302326741;4338.1540599583095;4352.630026447756;4417.889552761175;4312.82748690052;4316.115196384765;4336;4337.7397356158735;4362.912741745179;4743.715377077387;4774.474807771142;4786.282057691375;4790.281457781326;4820.517948205186;4889.633277504201;4940.777665005047;4955.628327875388;5043.52086551777;5137.075326441239;5129.5383415492415;5149.150390185615;5180.5337519623035;5181.637285390024;5185.974070129625;4797.904041919142;4812.879678008007;4813.3983252093485;4815.975920120447;4857;4979.427365852974;5287.391949925808;5296.285001524822;5297.496737809902;5299.152135658276;4834.250691142878;4897.853064408074;4940.184869496565;5019.698818070928;5058.215976523645;5096.439391666913;5102.5662818660085;5112.233165025202;5112.258722485219;5150.716710580954;5052.115879721034;5057.468965758542;5062.670926389773;5075.847724568269;5099.515546023122;4863.197572400585;4893.975530122327;4919.4834542373255;5018.272350509155;5085.516875896784;4332.956670433318;4348.760818155032;4353.782310884443;4393.582609652078;4401.449818772653;3787.8484860605818;3792.1467669774634;3799.4680744695765;3814.1608846053823;3820.4078367853026;4255.553166917491;4293.990912135646;4437.290033594674;4437.33439984005;4453.487848897354;5093.286939828427;5135.204043373283;5140.280360749498;5169.250458683545;5213.191955246943;4413.249747542925;4419.983403817076;4423;4425.468943726773;4454.420925279757;4431.44606924128;4638.536146385334;4642.51253618372;4652.790624421909;4675.789589468414;4606.30905364198;4684.672072954861;4694.859154225379;4719.4100737407825;4743.691660042086;5096.592272618108;5103.668261562986;5145;5146.614003949644;5165.92251116226;4724.598409135262;4726.527347265246;4731.597814185833;4731.929021064756;4766.285057241442;4841.2496063110275;4850.80596776125;4895.6817806843865;4960.131976904099;5056.595472362129;5024.321716568216;5025.271335656383;5026.120428924996;5029.421616514148;5031.798728050983;4862;4894.19245824435;4965.4289756678445;5017;5042.067217564616;5021.899562008813;5022.447530771539;5023.824166154167;5034.823781167642;5052.72210028452;4969.0807200668;4987.950120498748;4992.800287988585;4999.800007999639;5022.824201152942;4829.468758436499;4866.489018653132;4966.503349665005;4990.975045124751;5142.202958541432;4922.876928076684;4923.138450771173;4932.482089380707;4957.429895562056;4967.4535801062575 10:17:38 INFO - PROCESS | 13819 | |1;ext.html;498.44423467833354;506.2330569187681;511.03414546507105;512.7026324731656;549.203654700684;36881.44677829835;38906.22187556247;38958;39012.21975560494;39031;39466.60533394665;40014.79992600033;40031.59968400315;40051;40056.39915401271;7298.452616053791;7574.431917606188;7751.728689495869;7756.612169391536;7756.806079848021;7254.492185547014;9728.17310528606;10023.899761002369;10027.89972100277;10330.27688061836;9956.352837065604;10033.097021268084;10034.448105354202;10037.899621003806;10040.74898127549;9855.507224638775;9915.256355773274;9920.652777152836;9925.50372481373;9927.652532161412;9601.90398096017;9668.70993870185;9674.322797404184;9675.177609903167;9676.903230967671;37534.06164845874;37940;37956;37963.24073518542;37976;2842.7725781937397;2965.3920946205976;3007.4887269164296;3094.984525077383;3342.6824451677053;28097.735601897963;28140.577891331646;29551;29576.852115739497;29992;3414.8463319150696;3442.2599141184514;3447.207142357272;3475.669811367916;3482.0598438421484;249625;255328;256451;256561;256880;54510;56174.7191264041;56234;56248.43751562514;56280;57898.71050644804;58243;58296.12555811666;58333.708331458074;58335.416645833844;14766.335514901855;15228.314725837365;15236.542903712907;15268.694626107415;15277.923610382099;10520.316179448311;10872;11260;11571.826422603668;11721.589744358907;17894.28422863071;18447.90776046138;18453.446396608124;18504.259829606934;18517.259309627734;7028.683709233097;7051.858962820817;7191.568505889663;7304.160021597478;7339.50453848348;690.2924502384996;728.1553398058333;851.7998270406449;924.5700749151677;982.931194816356;26652.866735666197;26949.461010779676;27201.35605317162;27451.862740686567;27464.588031179548;844.3034496540379;866.7529754020075;914.3508109242513;915.688665853613;964.9228061755043;14154;14477.927610361881;14561.92719036398;14764.483243086655;14776.852231477762;344719;347331;347680;347746;350275;49864.252036219485;50306.245406318936;50337.74831125821;50403;50466.47667616604;1034.5189486888482;1099.2140619457127;1108.1578000719512;1152.8213126965502;1196.533351992741;27040;28749.28126796847;29257.85371073131;29325.96927432761;29830.70169298279;40928;41553;41760.37359439672;41768.791156044026;41853;291684;295012;295540;295573;295776;22678.433039174153;23208.767912321335;23298.417539561648;24286.757132429157;24425.51148976975;35793.28413431769;35913.82043089768;36229.637703622626;36230.63769362378;36270.4559431614;30948.22629434282;31008.68991310058;31080;31154.688453115177;31703.365932681678;30542.694573054872;30684.53973190359;30746.38507229798;31077.53383699201;32002;30553.847230764593;31040.68959310378;31196.53205201969;31231.84384078065;31437.842810785798;27442;27642.308942276602;28446;28741.42517149687;28751.281217969718;22446.663300050837;22568.887155564116;22676.319710408716;22791.772082278963;22955.311340659806 10:17:38 INFO - PROCESS | 13819 | |2;jquery.html;347.9321532301202;371.64322250639384;372.7428074628505;374.70023980815347;387.4324115171274;12402.689932751686;12409.131360804748;13095.345232738373;15852.20738963053;16387.01677899324;16265.674686506292;16442.75335869963;16527.008379497238;16636.33454661812;16646.33414663412;4857.441394239672;4921.286413470045;5149.716765577892;5161.045206636765;5227.5295223429885;3410.181556426452;5206.531412172903;5233.450487698794;5241.344831896013;5262.789488420459;3576.409892367756;5497.945020549783;5743.569232307573;5760.991826430379;5859.824205273849;5197.324347834795;5334.839954801323;5843.474087332139;5843.620164689281;5845.444682755132;5064.366954130734;5064.56951159152;5079.263506791494;5089.669171503878;5090.08378491875;11317.377544235063;11522.251053681484;16506.669866602602;18702.906485467487;18875.339363122224;1806.765120534322;1963.9901800490907;1978.5647157625299;2030.8679935804125;2322;8831.514266715392;9073.410228335137;9110.271178305786;9862.161716254128;12476.43856026472;1677.5806048487877;1751.9124043797758;1785.7678501794683;1819.9909000455045;1888.763904511936;61920.08034724361;63581;63853.68073159651;66365.66817165932;67008;8963.731088067369;9036.186743193144;9252.676156334495;9455.243580513543;10046.999999999854;13295.667608309675;13315.600531984055;13322.666933326744;13330.200187988752;13341.132826366254;7014.684339204748;7191.568505889559;7215.530990485601;7367.77896663101;7430.739924102739;6901.344372284725;6980.232174460805;7051.647417629098;7072.151341839012;7128.536645118051;4221.414593234564;4270.829166833292;4272.188284225987;4362.6509879210225;4412.24991751403;1845.4186931116656;1860.4418674397627;1861.4229588827507;1863.18951256204;1872.1107473950037;708.4226355520235;721.6247551273309;726.7601691441812;843.5234092737584;865.7619154732499;14491.20298383599;14842.628934276514;14872.182029988455;15064.924675376553;15168.772468412983;797.5135167547792;808.1110778143994;812.9552874591955;847.3560094328354;874.2787200559437;8149.307308878811;8383.646541383496;8692.652293908299;8695.565221738887;8764.342674299452;343184;350610;351280.4871951248;351362;352922;51695.741521292905;51710.22434663483;51776;52353.64408601546;56363.71818140965;874.4976369639553;881.0484676549312;901.9549022548846;902.6073657958809;903.6159632156307;13430.127041742255;13435.462581496631;13506.662333441742;13573.1856088635;13747;34385;34694.30611387808;34702.82648586741;35154.82422587871;36028.639713603574;44800;45776.31335529903;46131.30803038024;53859;54040.45959540354;6608.900866487103;7144.106986626672;7193.136823580994;7386.965824784642;7727.304542591194;10494.52774625159;11400.429978501208;11518.136139789382;11615.941920290345;11900.821487677866;5430.8099216527235;9626.566804493954;9907.702768916944;9953.701388958343;10412.739681508023;9685.467299298607;9923.503824808875;10032;10156.44139572301;10295.691129266133;8670.78323041929;10167.389956602627;10324.122449591869;10379.429131397543;10456.26806123564;7174.67713952863;7198.20819709842;7247.13034435871;7398.0012698285045;7440.925590744023;8339.749807505785;9502;9590.520473976412;9697.933227344845;9832.9016709832 10:17:38 INFO - PROCESS | 13819 | |3;mootools.html;1216.537715668046;1328.8139660447537;1337.9799303010454;1397.2943663449955;1649.1012398242956;6237.719302631381;6828.8975665365;7062.08192934919;7087.362137407638;7233.963830180842;7132.96433517833;7165.749198778044;7205.7838264852135;7206.567605943647;7224.53040552365;1827.2325623238205;1868.841148502379;2086.7808880067564;2113.3871177358546;2126.340834341359;2035.989820050905;2105.7157283766724;2106.1049054152;2111.6938043983614;2113.704081428601;2078.0337143228394;2086.739157605299;2095.538981424092;2099.6010757956014;2100.33839340608;2086.9165233390645;2109.6624540073512;2109.704641350212;2112.4296439961277;2115.2490865742593;2089.941407713401;2114.2494414482944;2114.7779483154354;2116.428564287649;2118.629239883014;7303.598302093383;7370;7374.963125184393;7376.7786966391095;7376.815579610499;4336.349547567858;4467.709598876095;4497.145542346943;4540.614047805941;4542.136993971135;2781.707920668341;2871.6554013518303;2877.2950627096284;2893.2188309156622;2930.120963710878;866.0733015673233;920.4431319052011;931.3666706639483;933.4399360383783;1619.8380161983825;9356.391834530734;9729.708108756678;9753.170980466668;9765.658201962897;9776.90223097767;3752.0432289765936;3909.276783795007;3956.586482245828;3992;4001.6598589119667;7728.806779830549;7733.188015258429;7736.497127686795;7737.497062690907;7745.186755390716;4443.688941774045;4453.487848897354;4454.309582014793;4454.710443821206;4455.754933478625;3578.0697018774786;3720.7581507202485;4021.959780402217;4147.937780933288;4156.937645935313;3184.6496885342594;3199.216192032967;3314.6519615440516;3315.651856555075;3331.1505566080978;955.0783493928393;961.8316794560924;969.7866469376726;1000.2395783421231;1002.2282842211456;743.353282644103;750.5121670913841;754.5246494708408;773.443120952913;790.0598288037332;4629.837955671535;4656.115338085753;4661.184292748755;4781.665283430127;4814.20565606678;800.3237264511479;845.4758050008911;853.317346123099;853.6329378367361;859.7721603775004;3060.5256185291314;3218.6781321867625;3259.364423937357;3297.241634424048;3313.436715758327;8488.490690558587;8690.739277821676;8750;8762.912370876336;8782.209601135928;8700.347473939353;8710.91289087101;8715.564221788885;8720.781980450412;8723.08407617191;6418.8716225675225;6608.3391660834495;6752.324767523209;6908.1019467468905;6921.653917304115;4733.313669517832;4899.167141585867;4964;4975.950240497694;5116.283720279238;7644.655990480331;7673.155952845071;7717.498362606412;7778.727744528915;7889.802754931173;7785.416093792875;7839.529628222097;7854.842903141791;7905;7911.5253084815095;6769.086173366532;6782.8643427132165;6802.523823332319;6883.311668833277;6952.304769523007;6768.153980752406;6829.760958366433;6867.587944723333;6874.415674667709;6878.828029299308;6150.231221097363;6261.022487122657;6263.091851681572;6382.553221274652;6511.218653761579;6101.786437474844;6241.906371404341;6324.335944725737;6331.96834015827;6350.142730731292;5570.442955704397;5675.687837168995;5686.317641883001;5694.943050569441;5696.316442026983;4182.435371224968;4190.392393102922;4280.892977675583;4340.218760623118;4382.101669157879;4124.237016151901;4232.449781528381;4306.397104405323;4316.330968699856;4379.0585024219945 10:17:38 INFO - PROCESS | 13819 | |4;prototype.html;337.36575238551524;348.9566197070759;351.4675266970539;357.33357288656646;364.75743630485726;4839.612830973522;4891.926621100686;4893.779779909908;4911.778969946352;4925.33507976424;4842.733649649268;4845.297431872376;4865.245886887529;4876.951230487694;4877.707337559749;3344.2141096842224;3648.2885837261742;3668.5781135169395;3734.9439758403646;3781.5462144542694;3883.98058009711;3885.2229554089154;3892.9805350973206;3894.3184942635066;3899.1032062625613;3880.359740642805;3888.3389823730035;3892.5718171001167;3897.941530877039;3901.55132159801;3669.486271921933;3744.9812750936344;3755.3615885299564;3879.5344558653;3890.8054597270025;3469.09803451102;3481.54739883816;3490.825458727079;3512.139525816166;3516.771409858351;4692.8122875084955;4701.341812146302;4728.148933192058;4729.861371300112;4745.9525404745855;4321.481422229353;4440.644748420119;4444.000099977511;4451;4458.4426946631675;5040.672356296829;5059.392872855244;5061.5950723942;5067.037262920034;5085.440601533828;3803.8668646597234;3812.4281357796553;3814.275287695357;3818.2745278397015;3835.2137811748526;5030.170021946375;5187.948120518747;5312.3890752563175;5351.357837059539;5361.437049109826;3842.865499707497;3878.5733569307354;3916.6475017248586;3943.921121577552;3949.7037722170953;3976.1650053489093;3980.8208630611693;3985.720999530005;3992.2414741199077;3996.2207369562657;4393.868183954486;4396.318570621559;4403.691741578123;4431.246688063037;4454.688171828006;4872.537108974641;5033.874153146127;5041.6470847040355;5071.18860982241;5076.213186956028;1342.7981956149192;1343.7581235377727;1344.2942455211091;1348.6291269900855;1364.6558140231864;911.2611949861645;960.6541645007792;963.3978763272954;964.5370222293338;979.480875136179;4253.787310634456;4294.162638285567;4305.203537345601;4365.148795984753;4373.431453911035;5034.597232221414;5034.983195081842;5077.7715002824225;5079.492050794965;5085.542301192911;4211.978940105341;4348.303941649572;4349.586789255016;4353.804078816461;4361.280388735823;2694.9461010780064;2696.7438093381097;2702.4866873807546;2715.9815069348992;2716.361655011071;4709.1398897436;4716.29255611661;4725.598324142417;4734.573888350065;4756.7859446325;5082.237664350377;5114.974425127925;5165.509276618715;5175.585953123817;5182.585393168538;4837.37114175155;4904.411470623548;4914.606831453475;4924.729139897268;4933.407991041098;5278.6832790031185;5282.683039017671;5293.655912365684;5298.178782288752;5307.6284660073425;4945.77744001528;5128.974355128201;5199;5228.189630607262;5228.372595288438;4622.306654001926;4755.928661070017;4787.640926930424;4918.2622606607865;4924.926126108039;3680.8527658893345;3681.39257022599;3683.9815800920824;3686.9446958296185;3690.1328187875292;5212.426633070435;5223.895522089613;5229.914350533204;5248.816291429782;5270.973645131749;4998.225275082368;5068.163752980717;5076.873078173076;5079.263506791568;5091.770870310919;5064;5087.64386492942;5095.464976177448;5105.974470127626;5114.284000239895;5005.899882002413;5016.974915125401;5051.292819005269;5053.635518409935;5063.961887813063;5019.824306149268;5157.871053223549;5183.3261675981885;5183.377994640668;5191.779931715968;5034.572061374678;5035.597152227886;5038.370203724535;5053.36832895888;5058.215976523645 10:17:38 INFO - PROCESS | 13819 | |5;yui.html;629.1694962649303;668.314977148423;672.3679741043422;720.1861896057454;727.0983979864969;12152.696182595428;13731.794023089662;13751.174929504237;13756.38096285667;13792.931035344835;13071.803922941164;13087.541936032241;13134.540291089814;13137.802932955989;13225.603231903058;600.5795942840008;634.9015902535091;717.042747931512;762.4815125714512;779.9727009554666;762.5539059650112;776.0222120128623;776.7941495503696;777.327611615951;777.3703300326754;824.3364091906013;827.1067247372853;827.7723626002868;828.1552816127546;828.3207769628907;626.2797782550058;666.3303380102966;750.7147284032087;817.9059408168048;818.4271010292789;600.5075837812996;605.9515238780931;606.6147996022513;606.8816580766752;606.9423404776584;12608.180468269533;12677.809832852514;12680;12691;12709.17390369623;12746.171498852545;12792.48830046797;12801.423935922905;12804.167729097671;13044.934775326063;10869.945650271777;10873.673789786319;11161.665150045512;11218.66344009681;11420.828687569694;3097.829619370934;3951.7826519541413;3986.40203969404;4049.6557792587664;4120.958790412117;65385.3461465384;65964;66265.66867165682;67561;67950.66024669894;10103.494825258707;10168.491575421347;10344.948275258575;10368.274220804622;10564.260501764802;17887.37394191197;17893.7315940261;17899.463016109537;17907.641847162984;17913.552161195814;6312.684365781784;6502.642354670538;6544.083828264047;6649.335066493312;6742.49431292655;3572.4105522589;3577.94633080504;3657.140571965586;3661.377565813818;3742.5321834770652;2799.0902956539207;3229.370272796829;3393.9151521212166;3399.677030682131;3444.6727560881677;492.70683943053825;502.19147173051357;503.4537526783408;517.5911030286107;521.9399769026535;431.1484817485441;566.2157911292888;570.7146426786607;594.4035229763628;625.6746491824317;7965.243301886311;8080.636371363303;8095.554744488992;8132.715354962666;8154.6738130475305;543.6955305029196;560.2604561978231;593.5785592229565;594.7115648910275;604.1059232336167;3854;3960.970147761545;3973.2450834341385;4087.3051581230666;4096.303628383182;65062.34937650563;66311.33688663148;68005;69069.65465172642;70963;41825;50751.492485074676;54525.1821222682;56255.71872140695;56290.15564766532;40273.59726402757;46668.5333146671;49405.25892111549;49904;50287.49712502901;8469.407141500036;8668.349873759367;8956.865647015169;8981.371304008919;8991.460512369016;30300.848495757382;30583;32728.34543309073;34133.65866341404;35121.8243908789;35955.64044359523;39151.021224469616;41853.372199417645;43464.56535434605;45341.31988020116;10942.17933654995;10981.670549883516;11119.944400277947;11303.773924521627;11672.796398596258;12023.158378913393;12384.071194660257;12701.618951431472;12778.424970876518;13846.376913038735;12379.133460658028;12579.559715409916;12663.873361266269;12867.233544787916;12895.097343185887;12176.452059657513;12371.443285052013;12565.18326308787;12745.362731863184;13108.279044652636;11522.004278887718;11705.25023635199;11868.881311186778;11975.76048479043;12701.174423662433;9929.553170107218;10160.390376577428;10198.286119971532;10352.53413596405;10467.214958877963;10075.748106297402;10647.627333043307;10651.414172220602;10679.679609611723;10788.352698838095 10:17:38 INFO - PROCESS | 13819 | __end_tp_report 10:17:38 INFO - PROCESS | 13819 | __start_cc_report 10:17:38 INFO - PROCESS | 13819 | _x_x_mozilla_cycle_collect,2066 10:17:38 INFO - PROCESS | 13819 | __end_cc_report 10:17:38 INFO - PROCESS | 13819 | __startTimestamp1482344258013__endTimestamp 10:17:38 INFO - PROCESS | 13819 | 10:17:38 INFO - PROCESS | 13819 | ------- Summary: start ------- 10:17:38 INFO - PROCESS | 13819 | Number of tests: 6 10:17:38 INFO - PROCESS | 13819 | 10:17:38 INFO - PROCESS | 13819 | [#0] dojo.html Cycles:165 Average:4700.29 Median:4820.52 stddev:496.18 (10.3%) stddev-sans-first:469.63 10:17:38 INFO - PROCESS | 13819 | Values: 2602.7 2625.3 2642.3 2657.7 2665.9 4552.5 4589.5 4803.1 4818.0 4825.2 4614.5 4748.9 4773.5 4782.9 4812.8 4332.1 4363.2 4392.2 4456.2 4531.4 3966.4 4304.8 4438.2 4482.2 4527.1 4219.6 4250.3 4434.3 4467.0 4471.2 4292.2 4324.5 4338.2 4352.6 4417.9 4312.8 4316.1 4336.0 4337.7 4362.9 4743.7 4774.5 4786.3 4790.3 4820.5 4889.6 4940.8 4955.6 5043.5 5137.1 5129.5 5149.2 5180.5 5181.6 5186.0 4797.9 4812.9 4813.4 4816.0 4857.0 4979.4 5287.4 5296.3 5297.5 5299.2 4834.3 4897.9 4940.2 5019.7 5058.2 5096.4 5102.6 5112.2 5112.3 5150.7 5052.1 5057.5 5062.7 5075.8 5099.5 4863.2 4894.0 4919.5 5018.3 5085.5 4333.0 4348.8 4353.8 4393.6 4401.4 3787.8 3792.1 3799.5 3814.2 3820.4 4255.6 4294.0 4437.3 4437.3 4453.5 5093.3 5135.2 5140.3 5169.3 5213.2 4413.2 4420.0 4423.0 4425.5 4454.4 4431.4 4638.5 4642.5 4652.8 4675.8 4606.3 4684.7 4694.9 4719.4 4743.7 5096.6 5103.7 5145.0 5146.6 5165.9 4724.6 4726.5 4731.6 4731.9 4766.3 4841.2 4850.8 4895.7 4960.1 5056.6 5024.3 5025.3 5026.1 5029.4 5031.8 4862.0 4894.2 4965.4 5017.0 5042.1 5021.9 5022.4 5023.8 5034.8 5052.7 4969.1 4988.0 4992.8 4999.8 5022.8 4829.5 4866.5 4966.5 4991.0 5142.2 4922.9 4923.1 4932.5 4957.4 4967.5 10:17:38 INFO - PROCESS | 13819 | 10:17:38 INFO - PROCESS | 13819 | [#1] ext.html Cycles:180 Average:45318.05 Median:17672.34 stddev:79088.70 (447.5%) stddev-sans-first:79238.97 10:17:38 INFO - PROCESS | 13819 | Values: 498.4 506.2 511.0 512.7 549.2 36881.4 38906.2 38958.0 39012.2 39031.0 39466.6 40014.8 40031.6 40051.0 40056.4 7298.5 7574.4 7751.7 7756.6 7756.8 7254.5 9728.2 10023.9 10027.9 10330.3 9956.4 10033.1 10034.4 10037.9 10040.7 9855.5 9915.3 9920.7 9925.5 9927.7 9601.9 9668.7 9674.3 9675.2 9676.9 37534.1 37940.0 37956.0 37963.2 37976.0 2842.8 2965.4 3007.5 3095.0 3342.7 28097.7 28140.6 29551.0 29576.9 29992.0 3414.8 3442.3 3447.2 3475.7 3482.1 249625.0 255328.0 256451.0 256561.0 256880.0 54510.0 56174.7 56234.0 56248.4 56280.0 57898.7 58243.0 58296.1 58333.7 58335.4 14766.3 15228.3 15236.5 15268.7 15277.9 10520.3 10872.0 11260.0 11571.8 11721.6 17894.3 18447.9 18453.4 18504.3 18517.3 7028.7 7051.9 7191.6 7304.2 7339.5 690.3 728.2 851.8 924.6 982.9 26652.9 26949.5 27201.4 27451.9 27464.6 844.3 866.8 914.4 915.7 964.9 14154.0 14477.9 14561.9 14764.5 14776.9 344719.0 347331.0 347680.0 347746.0 350275.0 49864.3 50306.2 50337.7 50403.0 50466.5 1034.5 1099.2 1108.2 1152.8 1196.5 27040.0 28749.3 29257.9 29326.0 29830.7 40928.0 41553.0 41760.4 41768.8 41853.0 291684.0 295012.0 295540.0 295573.0 295776.0 22678.4 23208.8 23298.4 24286.8 24425.5 35793.3 35913.8 36229.6 36230.6 36270.5 30948.2 31008.7 31080.0 31154.7 31703.4 30542.7 30684.5 30746.4 31077.5 32002.0 30553.8 31040.7 31196.5 31231.8 31437.8 27442.0 27642.3 28446.0 28741.4 28751.3 22446.7 22568.9 22676.3 22791.8 22955.3 10:17:38 INFO - PROCESS | 13819 | 10:17:38 INFO - PROCESS | 13819 | [#2] jquery.html Cycles:180 Average:21775.69 Median:5089.88 stddev:57505.70 (1129.8%) stddev-sans-first:57644.51 10:17:38 INFO - PROCESS | 13819 | Values: 347.9 371.6 372.7 374.7 387.4 12402.7 12409.1 13095.3 15852.2 16387.0 16265.7 16442.8 16527.0 16636.3 16646.3 4857.4 4921.3 5149.7 5161.0 5227.5 3410.2 5206.5 5233.5 5241.3 5262.8 3576.4 5497.9 5743.6 5761.0 5859.8 5197.3 5334.8 5843.5 5843.6 5845.4 5064.4 5064.6 5079.3 5089.7 5090.1 11317.4 11522.3 16506.7 18702.9 18875.3 1806.8 1964.0 1978.6 2030.9 2322.0 8831.5 9073.4 9110.3 9862.2 12476.4 1677.6 1751.9 1785.8 1820.0 1888.8 61920.1 63581.0 63853.7 66365.7 67008.0 8963.7 9036.2 9252.7 9455.2 10047.0 13295.7 13315.6 13322.7 13330.2 13341.1 7014.7 7191.6 7215.5 7367.8 7430.7 6901.3 6980.2 7051.6 7072.2 7128.5 4221.4 4270.8 4272.2 4362.7 4412.2 1845.4 1860.4 1861.4 1863.2 1872.1 708.4 721.6 726.8 843.5 865.8 14491.2 14842.6 14872.2 15064.9 15168.8 797.5 808.1 813.0 847.4 874.3 8149.3 8383.6 8692.7 8695.6 8764.3 343184.0 350610.0 351280.5 351362.0 352922.0 51695.7 51710.2 51776.0 52353.6 56363.7 874.5 881.0 902.0 902.6 903.6 13430.1 13435.5 13506.7 13573.2 13747.0 34385.0 34694.3 34702.8 35154.8 36028.6 44800.0 45776.3 46131.3 53859.0 54040.5 6608.9 7144.1 7193.1 7387.0 7727.3 10494.5 11400.4 11518.1 11615.9 11900.8 5430.8 9626.6 9907.7 9953.7 10412.7 9685.5 9923.5 10032.0 10156.4 10295.7 8670.8 10167.4 10324.1 10379.4 10456.3 7174.7 7198.2 7247.1 7398.0 7440.9 8339.7 9502.0 9590.5 9697.9 9832.9 10:17:38 INFO - PROCESS | 13819 | 10:17:38 INFO - PROCESS | 13819 | [#3] mootools.html Cycles:180 Average:4730.07 Median:5690.63 stddev:2562.80 (45.0%) stddev-sans-first:2556.39 10:17:38 INFO - PROCESS | 13819 | Values: 1216.5 1328.8 1338.0 1397.3 1649.1 6237.7 6828.9 7062.1 7087.4 7234.0 7133.0 7165.7 7205.8 7206.6 7224.5 1827.2 1868.8 2086.8 2113.4 2126.3 2036.0 2105.7 2106.1 2111.7 2113.7 2078.0 2086.7 2095.5 2099.6 2100.3 2086.9 2109.7 2109.7 2112.4 2115.2 2089.9 2114.2 2114.8 2116.4 2118.6 7303.6 7370.0 7375.0 7376.8 7376.8 4336.3 4467.7 4497.1 4540.6 4542.1 2781.7 2871.7 2877.3 2893.2 2930.1 866.1 920.4 931.4 933.4 1619.8 9356.4 9729.7 9753.2 9765.7 9776.9 3752.0 3909.3 3956.6 3992.0 4001.7 7728.8 7733.2 7736.5 7737.5 7745.2 4443.7 4453.5 4454.3 4454.7 4455.8 3578.1 3720.8 4022.0 4147.9 4156.9 3184.6 3199.2 3314.7 3315.7 3331.2 955.1 961.8 969.8 1000.2 1002.2 743.4 750.5 754.5 773.4 790.1 4629.8 4656.1 4661.2 4781.7 4814.2 800.3 845.5 853.3 853.6 859.8 3060.5 3218.7 3259.4 3297.2 3313.4 8488.5 8690.7 8750.0 8762.9 8782.2 8700.3 8710.9 8715.6 8720.8 8723.1 6418.9 6608.3 6752.3 6908.1 6921.7 4733.3 4899.2 4964.0 4976.0 5116.3 7644.7 7673.2 7717.5 7778.7 7889.8 7785.4 7839.5 7854.8 7905.0 7911.5 6769.1 6782.9 6802.5 6883.3 6952.3 6768.2 6829.8 6867.6 6874.4 6878.8 6150.2 6261.0 6263.1 6382.6 6511.2 6101.8 6241.9 6324.3 6332.0 6350.1 5570.4 5675.7 5686.3 5694.9 5696.3 4182.4 4190.4 4280.9 4340.2 4382.1 4124.2 4232.4 4306.4 4316.3 4379.1 10:17:38 INFO - PROCESS | 13819 | 10:17:38 INFO - PROCESS | 13819 | [#4] prototype.html Cycles:180 Average:4234.58 Median:4756.36 stddev:1194.62 (25.1%) stddev-sans-first:1161.61 10:17:38 INFO - PROCESS | 13819 | Values: 337.4 349.0 351.5 357.3 364.8 4839.6 4891.9 4893.8 4911.8 4925.3 4842.7 4845.3 4865.2 4877.0 4877.7 3344.2 3648.3 3668.6 3734.9 3781.5 3884.0 3885.2 3893.0 3894.3 3899.1 3880.4 3888.3 3892.6 3897.9 3901.6 3669.5 3745.0 3755.4 3879.5 3890.8 3469.1 3481.5 3490.8 3512.1 3516.8 4692.8 4701.3 4728.1 4729.9 4746.0 4321.5 4440.6 4444.0 4451.0 4458.4 5040.7 5059.4 5061.6 5067.0 5085.4 3803.9 3812.4 3814.3 3818.3 3835.2 5030.2 5187.9 5312.4 5351.4 5361.4 3842.9 3878.6 3916.6 3943.9 3949.7 3976.2 3980.8 3985.7 3992.2 3996.2 4393.9 4396.3 4403.7 4431.2 4454.7 4872.5 5033.9 5041.6 5071.2 5076.2 1342.8 1343.8 1344.3 1348.6 1364.7 911.3 960.7 963.4 964.5 979.5 4253.8 4294.2 4305.2 4365.1 4373.4 5034.6 5035.0 5077.8 5079.5 5085.5 4212.0 4348.3 4349.6 4353.8 4361.3 2694.9 2696.7 2702.5 2716.0 2716.4 4709.1 4716.3 4725.6 4734.6 4756.8 5082.2 5115.0 5165.5 5175.6 5182.6 4837.4 4904.4 4914.6 4924.7 4933.4 5278.7 5282.7 5293.7 5298.2 5307.6 4945.8 5129.0 5199.0 5228.2 5228.4 4622.3 4755.9 4787.6 4918.3 4924.9 3680.9 3681.4 3684.0 3686.9 3690.1 5212.4 5223.9 5229.9 5248.8 5271.0 4998.2 5068.2 5076.9 5079.3 5091.8 5064.0 5087.6 5095.5 5106.0 5114.3 5005.9 5017.0 5051.3 5053.6 5064.0 5019.8 5157.9 5183.3 5183.4 5191.8 5034.6 5035.6 5038.4 5053.4 5058.2 10:17:38 INFO - PROCESS | 13819 | 10:17:38 INFO - PROCESS | 13819 | [#5] yui.html Cycles:180 Average:14582.96 Median:3979.82 stddev:17988.85 (452.0%) stddev-sans-first:18008.79 10:17:38 INFO - PROCESS | 13819 | Values: 629.2 668.3 672.4 720.2 727.1 12152.7 13731.8 13751.2 13756.4 13792.9 13071.8 13087.5 13134.5 13137.8 13225.6 600.6 634.9 717.0 762.5 780.0 762.6 776.0 776.8 777.3 777.4 824.3 827.1 827.8 828.2 828.3 626.3 666.3 750.7 817.9 818.4 600.5 606.0 606.6 606.9 606.9 12608.2 12677.8 12680.0 12691.0 12709.2 12746.2 12792.5 12801.4 12804.2 13044.9 10869.9 10873.7 11161.7 11218.7 11420.8 3097.8 3951.8 3986.4 4049.7 4121.0 65385.3 65964.0 66265.7 67561.0 67950.7 10103.5 10168.5 10344.9 10368.3 10564.3 17887.4 17893.7 17899.5 17907.6 17913.6 6312.7 6502.6 6544.1 6649.3 6742.5 3572.4 3577.9 3657.1 3661.4 3742.5 2799.1 3229.4 3393.9 3399.7 3444.7 492.7 502.2 503.5 517.6 521.9 431.1 566.2 570.7 594.4 625.7 7965.2 8080.6 8095.6 8132.7 8154.7 543.7 560.3 593.6 594.7 604.1 3854.0 3961.0 3973.2 4087.3 4096.3 65062.3 66311.3 68005.0 69069.7 70963.0 41825.0 50751.5 54525.2 56255.7 56290.2 40273.6 46668.5 49405.3 49904.0 50287.5 8469.4 8668.3 8956.9 8981.4 8991.5 30300.8 30583.0 32728.3 34133.7 35121.8 35955.6 39151.0 41853.4 43464.6 45341.3 10942.2 10981.7 11119.9 11303.8 11672.8 12023.2 12384.1 12701.6 12778.4 13846.4 12379.1 12579.6 12663.9 12867.2 12895.1 12176.5 12371.4 12565.2 12745.4 13108.3 11522.0 11705.3 11868.9 11975.8 12701.2 9929.6 10160.4 10198.3 10352.5 10467.2 10075.7 10647.6 10651.4 10679.7 10788.4 10:17:38 INFO - PROCESS | 13819 | -------- Summary: end -------- 10:17:38 INFO - PROCESS | 13819 | 10:17:38 INFO - TEST-INFO | 13819: exit 0 10:17:38 INFO - TEST-OK | dromaeo_css | took 1122701ms 10:17:38 INFO - TEST-START | kraken 10:17:38 INFO - operating with platform_type : linux_ 10:17:38 INFO - Initialising browser for kraken test... 10:17:38 INFO - TEST-INFO | started process 14124 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaKwViF/profile http://localhost:54354/getInfo.html) 10:17:42 INFO - PROCESS | 14124 | __metrics Screen width/height:1600/1200 10:17:42 INFO - PROCESS | 14124 | colorDepth:24 10:17:42 INFO - PROCESS | 14124 | Browser inner width/height: 1024/697 10:17:42 INFO - PROCESS | 14124 | __metrics 10:17:43 INFO - PROCESS | 14124 | 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" 10:17:43 INFO - PROCESS | 14124 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:17:44 INFO - PROCESS | 14124 | console.error: PushService: 10:17:44 INFO - PROCESS | 14124 | stateChangeProcessEnqueue: Error transitioning state 10:17:44 INFO - PROCESS | 14124 | UnknownError 10:17:44 INFO - TEST-INFO | 14124: exit 0 10:17:44 INFO - Browser initialized. 10:17:44 INFO - Running cycle 1/1 for kraken test... 10:17:44 INFO - TEST-INFO | started process 14206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaKwViF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:17:56 INFO - PROCESS | 14206 | RSS: Main: 154591232 10:17:56 INFO - PROCESS | 14206 | 10:17:59 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54354/tests/kraken/driver.html?testName=audio-beat-detection) 10:18:00 INFO - PROCESS | 14206 | RSS: Main: 206811136 10:18:00 INFO - PROCESS | 14206 | 10:18:03 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54354/tests/kraken/driver.html?testName=audio-dft) 10:18:03 INFO - PROCESS | 14206 | RSS: Main: 185749504 10:18:03 INFO - PROCESS | 14206 | 10:18:06 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54354/tests/kraken/driver.html?testName=audio-fft) 10:18:07 INFO - PROCESS | 14206 | RSS: Main: 269090816 10:18:07 INFO - PROCESS | 14206 | 10:18:09 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54354/tests/kraken/driver.html?testName=audio-oscillator) 10:18:10 INFO - PROCESS | 14206 | RSS: Main: 180441088 10:18:10 INFO - PROCESS | 14206 | 10:18:12 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54354/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:18:13 INFO - PROCESS | 14206 | RSS: Main: 183496704 10:18:13 INFO - PROCESS | 14206 | 10:18:16 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54354/tests/kraken/driver.html?testName=imaging-darkroom) 10:18:16 INFO - PROCESS | 14206 | RSS: Main: 223182848 10:18:16 INFO - PROCESS | 14206 | 10:18:20 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54354/tests/kraken/driver.html?testName=imaging-desaturate) 10:18:21 INFO - PROCESS | 14206 | RSS: Main: 216690688 10:18:21 INFO - PROCESS | 14206 | 10:18:23 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54354/tests/kraken/driver.html?testName=json-parse-financial) 10:18:24 INFO - PROCESS | 14206 | RSS: Main: 219078656 10:18:24 INFO - PROCESS | 14206 | 10:18:26 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54354/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:18:26 INFO - PROCESS | 14206 | RSS: Main: 182104064 10:18:26 INFO - PROCESS | 14206 | 10:18:28 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54354/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:18:29 INFO - PROCESS | 14206 | RSS: Main: 188981248 10:18:29 INFO - PROCESS | 14206 | 10:18:31 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54354/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:18:32 INFO - PROCESS | 14206 | RSS: Main: 198709248 10:18:32 INFO - PROCESS | 14206 | 10:18:35 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54354/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:18:35 INFO - PROCESS | 14206 | RSS: Main: 208171008 10:18:35 INFO - PROCESS | 14206 | 10:18:38 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54354/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:18:39 INFO - PROCESS | 14206 | RSS: Main: 204148736 10:18:39 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | Cycle 1(1): loaded http://localhost:54354/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54354/tests/kraken/driver.html?testName=ai-astar) 10:18:41 INFO - PROCESS | 14206 | RSS: Main: 254169088 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | __start_tp_report 10:18:41 INFO - PROCESS | 14206 | _x_x_mozilla_page_load 10:18:41 INFO - PROCESS | 14206 | _x_x_mozilla_page_load_details 10:18:41 INFO - PROCESS | 14206 | |i|pagename|runs| 10:18:41 INFO - PROCESS | 14206 | |0;ai-astar;116;116;117;118;116;116;114;116;116;116 10:18:41 INFO - PROCESS | 14206 | |1;audio-beat-detection;151;128;129;172;127;127;127;130;148;132 10:18:41 INFO - PROCESS | 14206 | |2;audio-dft;162;161;162;162;164;161;161;165;162;162 10:18:41 INFO - PROCESS | 14206 | |3;audio-fft;76;76;78;106;79;77;76;75;90;76 10:18:41 INFO - PROCESS | 14206 | |4;audio-oscillator;85;84;117;84;85;85;108;84;84;115 10:18:41 INFO - PROCESS | 14206 | |5;imaging-gaussian-blur;117;118;118;117;118;117;117;117;118;117 10:18:41 INFO - PROCESS | 14206 | |6;imaging-darkroom;148;148;146;147;146;147;147;147;148;147 10:18:41 INFO - PROCESS | 14206 | |7;imaging-desaturate;96;95;95;96;96;95;95;96;96;95 10:18:41 INFO - PROCESS | 14206 | |8;json-parse-financial;67;65;65;65;66;67;66;65;67;67 10:18:41 INFO - PROCESS | 14206 | |9;json-stringify-tinderbox;66;67;67;66;67;67;67;67;67;67 10:18:41 INFO - PROCESS | 14206 | |10;stanford-crypto-aes;84;96;84;85;86;90;95;84;84;84 10:18:41 INFO - PROCESS | 14206 | |11;stanford-crypto-ccm;144;134;137;135;135;154;135;134;179;137 10:18:41 INFO - PROCESS | 14206 | |12;stanford-crypto-pbkdf2;181;184;183;189;180;184;182;182;181;186 10:18:41 INFO - PROCESS | 14206 | |13;stanford-crypto-sha256-iterative;69;69;69;69;69;73;74;68;75;72 10:18:41 INFO - PROCESS | 14206 | __end_tp_report 10:18:41 INFO - PROCESS | 14206 | __start_cc_report 10:18:41 INFO - PROCESS | 14206 | _x_x_mozilla_cycle_collect,1951 10:18:41 INFO - PROCESS | 14206 | __end_cc_report 10:18:41 INFO - PROCESS | 14206 | __startTimestamp1482344321589__endTimestamp 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | ------- Summary: start ------- 10:18:41 INFO - PROCESS | 14206 | Number of tests: 14 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#0] ai-astar Cycles:10 Average:116.10 Median:116.00 stddev:0.99 (0.9%) stddev-sans-first:1.05 10:18:41 INFO - PROCESS | 14206 | Values: 116.0 116.0 117.0 118.0 116.0 116.0 114.0 116.0 116.0 116.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:131.00 stddev:15.13 (11.6%) stddev-sans-first:15.19 10:18:41 INFO - PROCESS | 14206 | Values: 151.0 128.0 129.0 172.0 127.0 127.0 127.0 130.0 148.0 132.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.00 stddev:1.32 (0.8%) stddev-sans-first:1.39 10:18:41 INFO - PROCESS | 14206 | Values: 162.0 161.0 162.0 162.0 164.0 161.0 161.0 165.0 162.0 162.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.50 stddev:9.84 (12.9%) stddev-sans-first:10.27 10:18:41 INFO - PROCESS | 14206 | Values: 76.0 76.0 78.0 106.0 79.0 77.0 76.0 75.0 90.0 76.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:84.00 stddev:14.15 (16.8%) stddev-sans-first:14.70 10:18:41 INFO - PROCESS | 14206 | Values: 85.0 84.0 117.0 84.0 85.0 85.0 108.0 84.0 84.0 115.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 10:18:41 INFO - PROCESS | 14206 | Values: 117.0 118.0 118.0 117.0 118.0 117.0 117.0 117.0 118.0 117.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 10:18:41 INFO - PROCESS | 14206 | Values: 148.0 148.0 146.0 147.0 146.0 147.0 147.0 147.0 148.0 147.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 10:18:41 INFO - PROCESS | 14206 | Values: 96.0 95.0 95.0 96.0 96.0 95.0 95.0 96.0 96.0 95.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:66.50 stddev:0.94 (1.4%) stddev-sans-first:0.93 10:18:41 INFO - PROCESS | 14206 | Values: 67.0 65.0 65.0 65.0 66.0 67.0 66.0 65.0 67.0 67.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:67.00 stddev:0.42 (0.6%) stddev-sans-first:0.33 10:18:41 INFO - PROCESS | 14206 | Values: 66.0 67.0 67.0 66.0 67.0 67.0 67.0 67.0 67.0 67.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:85.50 stddev:4.76 (5.6%) stddev-sans-first:4.90 10:18:41 INFO - PROCESS | 14206 | Values: 84.0 96.0 84.0 85.0 86.0 90.0 95.0 84.0 84.0 84.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#11] stanford-crypto-ccm Cycles:10 Average:142.40 Median:137.00 stddev:14.30 (10.4%) stddev-sans-first:15.16 10:18:41 INFO - PROCESS | 14206 | Values: 144.0 134.0 137.0 135.0 135.0 154.0 135.0 134.0 179.0 137.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.20 Median:183.50 stddev:2.70 (1.5%) stddev-sans-first:2.74 10:18:41 INFO - PROCESS | 14206 | Values: 181.0 184.0 183.0 189.0 180.0 184.0 182.0 182.0 181.0 186.0 10:18:41 INFO - PROCESS | 14206 | 10:18:41 INFO - PROCESS | 14206 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:70.50 stddev:2.54 (3.6%) stddev-sans-first:2.62 10:18:41 INFO - PROCESS | 14206 | Values: 69.0 69.0 69.0 69.0 69.0 73.0 74.0 68.0 75.0 72.0 10:18:41 INFO - PROCESS | 14206 | -------- Summary: end -------- 10:18:41 INFO - PROCESS | 14206 | 10:18:42 INFO - TEST-INFO | 14206: exit 0 10:18:42 INFO - TEST-OK | kraken | took 63910ms 10:18:42 INFO - SUITE-END | took 1186s 10:18:43 INFO - Completed test suite (00:19:47) 10:18:43 INFO - javascript benchmark 10:18:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2602.6746656667915, 2625.330540712118, 2642.273374821924, 2657.6677915260593, 2665.853378064207, 4552.476465206502, 4589.518100599439, 4803.135435621591, 4818.0, 4825.179719447694, 4614.492405835355, 4748.928766068511, 4773.5226477352335, 4782.880427989293, 4812.831550895719, 4332.1335732853395, 4363.214621368153, 4392.209402307583, 4456.242438785414, 4531.388262584542, 3966.3653815389544, 4304.80628371724, 4438.245498265286, 4482.238019536687, 4527.117212143633, 4219.641330486912, 4250.3199488081755, 4434.290513517822, 4466.994926141625, 4471.150481408522, 4292.1630282095, 4324.524302326741, 4338.1540599583095, 4352.630026447756, 4417.889552761175, 4312.82748690052, 4316.115196384765, 4336.0, 4337.7397356158735, 4362.912741745179, 4743.715377077387, 4774.474807771142, 4786.282057691375, 4790.281457781326, 4820.517948205186, 4889.633277504201, 4940.777665005047, 4955.628327875388, 5043.52086551777, 5137.075326441239, 5129.5383415492415, 5149.150390185615, 5180.5337519623035, 5181.637285390024, 5185.974070129625, 4797.904041919142, 4812.879678008007, 4813.3983252093485, 4815.975920120447, 4857.0, 4979.427365852974, 5287.391949925808, 5296.285001524822, 5297.496737809902, 5299.152135658276, 4834.250691142878, 4897.853064408074, 4940.184869496565, 5019.698818070928, 5058.215976523645, 5096.439391666913, 5102.5662818660085, 5112.233165025202, 5112.258722485219, 5150.716710580954, 5052.115879721034, 5057.468965758542, 5062.670926389773, 5075.847724568269, 5099.515546023122, 4863.197572400585, 4893.975530122327, 4919.4834542373255, 5018.272350509155, 5085.516875896784, 4332.956670433318, 4348.760818155032, 4353.782310884443, 4393.582609652078, 4401.449818772653, 3787.8484860605818, 3792.1467669774634, 3799.4680744695765, 3814.1608846053823, 3820.4078367853026, 4255.553166917491, 4293.990912135646, 4437.290033594674, 4437.33439984005, 4453.487848897354, 5093.286939828427, 5135.204043373283, 5140.280360749498, 5169.250458683545, 5213.191955246943, 4413.249747542925, 4419.983403817076, 4423.0, 4425.468943726773, 4454.420925279757, 4431.44606924128, 4638.536146385334, 4642.51253618372, 4652.790624421909, 4675.789589468414, 4606.30905364198, 4684.672072954861, 4694.859154225379, 4719.4100737407825, 4743.691660042086, 5096.592272618108, 5103.668261562986, 5145.0, 5146.614003949644, 5165.92251116226, 4724.598409135262, 4726.527347265246, 4731.597814185833, 4731.929021064756, 4766.285057241442, 4841.2496063110275, 4850.80596776125, 4895.6817806843865, 4960.131976904099, 5056.595472362129, 5024.321716568216, 5025.271335656383, 5026.120428924996, 5029.421616514148, 5031.798728050983, 4862.0, 4894.19245824435, 4965.4289756678445, 5017.0, 5042.067217564616, 5021.899562008813, 5022.447530771539, 5023.824166154167, 5034.823781167642, 5052.72210028452, 4969.0807200668, 4987.950120498748, 4992.800287988585, 4999.800007999639, 5022.824201152942, 4829.468758436499, 4866.489018653132, 4966.503349665005, 4990.975045124751, 5142.202958541432, 4922.876928076684, 4923.138450771173, 4932.482089380707, 4957.429895562056, 4967.4535801062575], "value": 4668.643238490927, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.44423467833354, 506.2330569187681, 511.03414546507105, 512.7026324731656, 549.203654700684, 36881.44677829835, 38906.22187556247, 38958.0, 39012.21975560494, 39031.0, 39466.60533394665, 40014.79992600033, 40031.59968400315, 40051.0, 40056.39915401271, 7298.452616053791, 7574.431917606188, 7751.728689495869, 7756.612169391536, 7756.806079848021, 7254.492185547014, 9728.17310528606, 10023.899761002369, 10027.89972100277, 10330.27688061836, 9956.352837065604, 10033.097021268084, 10034.448105354202, 10037.899621003806, 10040.74898127549, 9855.507224638775, 9915.256355773274, 9920.652777152836, 9925.50372481373, 9927.652532161412, 9601.90398096017, 9668.70993870185, 9674.322797404184, 9675.177609903167, 9676.903230967671, 37534.06164845874, 37940.0, 37956.0, 37963.24073518542, 37976.0, 2842.7725781937397, 2965.3920946205976, 3007.4887269164296, 3094.984525077383, 3342.6824451677053, 28097.735601897963, 28140.577891331646, 29551.0, 29576.852115739497, 29992.0, 3414.8463319150696, 3442.2599141184514, 3447.207142357272, 3475.669811367916, 3482.0598438421484, 249625.0, 255328.0, 256451.0, 256561.0, 256880.0, 54510.0, 56174.7191264041, 56234.0, 56248.43751562514, 56280.0, 57898.71050644804, 58243.0, 58296.12555811666, 58333.708331458074, 58335.416645833844, 14766.335514901855, 15228.314725837365, 15236.542903712907, 15268.694626107415, 15277.923610382099, 10520.316179448311, 10872.0, 11260.0, 11571.826422603668, 11721.589744358907, 17894.28422863071, 18447.90776046138, 18453.446396608124, 18504.259829606934, 18517.259309627734, 7028.683709233097, 7051.858962820817, 7191.568505889663, 7304.160021597478, 7339.50453848348, 690.2924502384996, 728.1553398058333, 851.7998270406449, 924.5700749151677, 982.931194816356, 26652.866735666197, 26949.461010779676, 27201.35605317162, 27451.862740686567, 27464.588031179548, 844.3034496540379, 866.7529754020075, 914.3508109242513, 915.688665853613, 964.9228061755043, 14154.0, 14477.927610361881, 14561.92719036398, 14764.483243086655, 14776.852231477762, 344719.0, 347331.0, 347680.0, 347746.0, 350275.0, 49864.252036219485, 50306.245406318936, 50337.74831125821, 50403.0, 50466.47667616604, 1034.5189486888482, 1099.2140619457127, 1108.1578000719512, 1152.8213126965502, 1196.533351992741, 27040.0, 28749.28126796847, 29257.85371073131, 29325.96927432761, 29830.70169298279, 40928.0, 41553.0, 41760.37359439672, 41768.791156044026, 41853.0, 291684.0, 295012.0, 295540.0, 295573.0, 295776.0, 22678.433039174153, 23208.767912321335, 23298.417539561648, 24286.757132429157, 24425.51148976975, 35793.28413431769, 35913.82043089768, 36229.637703622626, 36230.63769362378, 36270.4559431614, 30948.22629434282, 31008.68991310058, 31080.0, 31154.688453115177, 31703.365932681678, 30542.694573054872, 30684.53973190359, 30746.38507229798, 31077.53383699201, 32002.0, 30553.847230764593, 31040.68959310378, 31196.53205201969, 31231.84384078065, 31437.842810785798, 27442.0, 27642.308942276602, 28446.0, 28741.42517149687, 28751.281217969718, 22446.663300050837, 22568.887155564116, 22676.319710408716, 22791.772082278963, 22955.311340659806], "value": 17335.648023189766, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.9321532301202, 371.64322250639384, 372.7428074628505, 374.70023980815347, 387.4324115171274, 12402.689932751686, 12409.131360804748, 13095.345232738373, 15852.20738963053, 16387.01677899324, 16265.674686506292, 16442.75335869963, 16527.008379497238, 16636.33454661812, 16646.33414663412, 4857.441394239672, 4921.286413470045, 5149.716765577892, 5161.045206636765, 5227.5295223429885, 3410.181556426452, 5206.531412172903, 5233.450487698794, 5241.344831896013, 5262.789488420459, 3576.409892367756, 5497.945020549783, 5743.569232307573, 5760.991826430379, 5859.824205273849, 5197.324347834795, 5334.839954801323, 5843.474087332139, 5843.620164689281, 5845.444682755132, 5064.366954130734, 5064.56951159152, 5079.263506791494, 5089.669171503878, 5090.08378491875, 11317.377544235063, 11522.251053681484, 16506.669866602602, 18702.906485467487, 18875.339363122224, 1806.765120534322, 1963.9901800490907, 1978.5647157625299, 2030.8679935804125, 2322.0, 8831.514266715392, 9073.410228335137, 9110.271178305786, 9862.161716254128, 12476.43856026472, 1677.5806048487877, 1751.9124043797758, 1785.7678501794683, 1819.9909000455045, 1888.763904511936, 61920.08034724361, 63581.0, 63853.68073159651, 66365.66817165932, 67008.0, 8963.731088067369, 9036.186743193144, 9252.676156334495, 9455.243580513543, 10046.999999999854, 13295.667608309675, 13315.600531984055, 13322.666933326744, 13330.200187988752, 13341.132826366254, 7014.684339204748, 7191.568505889559, 7215.530990485601, 7367.77896663101, 7430.739924102739, 6901.344372284725, 6980.232174460805, 7051.647417629098, 7072.151341839012, 7128.536645118051, 4221.414593234564, 4270.829166833292, 4272.188284225987, 4362.6509879210225, 4412.24991751403, 1845.4186931116656, 1860.4418674397627, 1861.4229588827507, 1863.18951256204, 1872.1107473950037, 708.4226355520235, 721.6247551273309, 726.7601691441812, 843.5234092737584, 865.7619154732499, 14491.20298383599, 14842.628934276514, 14872.182029988455, 15064.924675376553, 15168.772468412983, 797.5135167547792, 808.1110778143994, 812.9552874591955, 847.3560094328354, 874.2787200559437, 8149.307308878811, 8383.646541383496, 8692.652293908299, 8695.565221738887, 8764.342674299452, 343184.0, 350610.0, 351280.4871951248, 351362.0, 352922.0, 51695.741521292905, 51710.22434663483, 51776.0, 52353.64408601546, 56363.71818140965, 874.4976369639553, 881.0484676549312, 901.9549022548846, 902.6073657958809, 903.6159632156307, 13430.127041742255, 13435.462581496631, 13506.662333441742, 13573.1856088635, 13747.0, 34385.0, 34694.30611387808, 34702.82648586741, 35154.82422587871, 36028.639713603574, 44800.0, 45776.31335529903, 46131.30803038024, 53859.0, 54040.45959540354, 6608.900866487103, 7144.106986626672, 7193.136823580994, 7386.965824784642, 7727.304542591194, 10494.52774625159, 11400.429978501208, 11518.136139789382, 11615.941920290345, 11900.821487677866, 5430.8099216527235, 9626.566804493954, 9907.702768916944, 9953.701388958343, 10412.739681508023, 9685.467299298607, 9923.503824808875, 10032.0, 10156.44139572301, 10295.691129266133, 8670.78323041929, 10167.389956602627, 10324.122449591869, 10379.429131397543, 10456.26806123564, 7174.67713952863, 7198.20819709842, 7247.13034435871, 7398.0012698285045, 7440.925590744023, 8339.749807505785, 9502.0, 9590.520473976412, 9697.933227344845, 9832.9016709832], "value": 7723.881632372003, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1216.537715668046, 1328.8139660447537, 1337.9799303010454, 1397.2943663449955, 1649.1012398242956, 6237.719302631381, 6828.8975665365, 7062.08192934919, 7087.362137407638, 7233.963830180842, 7132.96433517833, 7165.749198778044, 7205.7838264852135, 7206.567605943647, 7224.53040552365, 1827.2325623238205, 1868.841148502379, 2086.7808880067564, 2113.3871177358546, 2126.340834341359, 2035.989820050905, 2105.7157283766724, 2106.1049054152, 2111.6938043983614, 2113.704081428601, 2078.0337143228394, 2086.739157605299, 2095.538981424092, 2099.6010757956014, 2100.33839340608, 2086.9165233390645, 2109.6624540073512, 2109.704641350212, 2112.4296439961277, 2115.2490865742593, 2089.941407713401, 2114.2494414482944, 2114.7779483154354, 2116.428564287649, 2118.629239883014, 7303.598302093383, 7370.0, 7374.963125184393, 7376.7786966391095, 7376.815579610499, 4336.349547567858, 4467.709598876095, 4497.145542346943, 4540.614047805941, 4542.136993971135, 2781.707920668341, 2871.6554013518303, 2877.2950627096284, 2893.2188309156622, 2930.120963710878, 866.0733015673233, 920.4431319052011, 931.3666706639483, 933.4399360383783, 1619.8380161983825, 9356.391834530734, 9729.708108756678, 9753.170980466668, 9765.658201962897, 9776.90223097767, 3752.0432289765936, 3909.276783795007, 3956.586482245828, 3992.0, 4001.6598589119667, 7728.806779830549, 7733.188015258429, 7736.497127686795, 7737.497062690907, 7745.186755390716, 4443.688941774045, 4453.487848897354, 4454.309582014793, 4454.710443821206, 4455.754933478625, 3578.0697018774786, 3720.7581507202485, 4021.959780402217, 4147.937780933288, 4156.937645935313, 3184.6496885342594, 3199.216192032967, 3314.6519615440516, 3315.651856555075, 3331.1505566080978, 955.0783493928393, 961.8316794560924, 969.7866469376726, 1000.2395783421231, 1002.2282842211456, 743.353282644103, 750.5121670913841, 754.5246494708408, 773.443120952913, 790.0598288037332, 4629.837955671535, 4656.115338085753, 4661.184292748755, 4781.665283430127, 4814.20565606678, 800.3237264511479, 845.4758050008911, 853.317346123099, 853.6329378367361, 859.7721603775004, 3060.5256185291314, 3218.6781321867625, 3259.364423937357, 3297.241634424048, 3313.436715758327, 8488.490690558587, 8690.739277821676, 8750.0, 8762.912370876336, 8782.209601135928, 8700.347473939353, 8710.91289087101, 8715.564221788885, 8720.781980450412, 8723.08407617191, 6418.8716225675225, 6608.3391660834495, 6752.324767523209, 6908.1019467468905, 6921.653917304115, 4733.313669517832, 4899.167141585867, 4964.0, 4975.950240497694, 5116.283720279238, 7644.655990480331, 7673.155952845071, 7717.498362606412, 7778.727744528915, 7889.802754931173, 7785.416093792875, 7839.529628222097, 7854.842903141791, 7905.0, 7911.5253084815095, 6769.086173366532, 6782.8643427132165, 6802.523823332319, 6883.311668833277, 6952.304769523007, 6768.153980752406, 6829.760958366433, 6867.587944723333, 6874.415674667709, 6878.828029299308, 6150.231221097363, 6261.022487122657, 6263.091851681572, 6382.553221274652, 6511.218653761579, 6101.786437474844, 6241.906371404341, 6324.335944725737, 6331.96834015827, 6350.142730731292, 5570.442955704397, 5675.687837168995, 5686.317641883001, 5694.943050569441, 5696.316442026983, 4182.435371224968, 4190.392393102922, 4280.892977675583, 4340.218760623118, 4382.101669157879, 4124.237016151901, 4232.449781528381, 4306.397104405323, 4316.330968699856, 4379.0585024219945], "value": 3855.86129952054, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.36575238551524, 348.9566197070759, 351.4675266970539, 357.33357288656646, 364.75743630485726, 4839.612830973522, 4891.926621100686, 4893.779779909908, 4911.778969946352, 4925.33507976424, 4842.733649649268, 4845.297431872376, 4865.245886887529, 4876.951230487694, 4877.707337559749, 3344.2141096842224, 3648.2885837261742, 3668.5781135169395, 3734.9439758403646, 3781.5462144542694, 3883.98058009711, 3885.2229554089154, 3892.9805350973206, 3894.3184942635066, 3899.1032062625613, 3880.359740642805, 3888.3389823730035, 3892.5718171001167, 3897.941530877039, 3901.55132159801, 3669.486271921933, 3744.9812750936344, 3755.3615885299564, 3879.5344558653, 3890.8054597270025, 3469.09803451102, 3481.54739883816, 3490.825458727079, 3512.139525816166, 3516.771409858351, 4692.8122875084955, 4701.341812146302, 4728.148933192058, 4729.861371300112, 4745.9525404745855, 4321.481422229353, 4440.644748420119, 4444.000099977511, 4451.0, 4458.4426946631675, 5040.672356296829, 5059.392872855244, 5061.5950723942, 5067.037262920034, 5085.440601533828, 3803.8668646597234, 3812.4281357796553, 3814.275287695357, 3818.2745278397015, 3835.2137811748526, 5030.170021946375, 5187.948120518747, 5312.3890752563175, 5351.357837059539, 5361.437049109826, 3842.865499707497, 3878.5733569307354, 3916.6475017248586, 3943.921121577552, 3949.7037722170953, 3976.1650053489093, 3980.8208630611693, 3985.720999530005, 3992.2414741199077, 3996.2207369562657, 4393.868183954486, 4396.318570621559, 4403.691741578123, 4431.246688063037, 4454.688171828006, 4872.537108974641, 5033.874153146127, 5041.6470847040355, 5071.18860982241, 5076.213186956028, 1342.7981956149192, 1343.7581235377727, 1344.2942455211091, 1348.6291269900855, 1364.6558140231864, 911.2611949861645, 960.6541645007792, 963.3978763272954, 964.5370222293338, 979.480875136179, 4253.787310634456, 4294.162638285567, 4305.203537345601, 4365.148795984753, 4373.431453911035, 5034.597232221414, 5034.983195081842, 5077.7715002824225, 5079.492050794965, 5085.542301192911, 4211.978940105341, 4348.303941649572, 4349.586789255016, 4353.804078816461, 4361.280388735823, 2694.9461010780064, 2696.7438093381097, 2702.4866873807546, 2715.9815069348992, 2716.361655011071, 4709.1398897436, 4716.29255611661, 4725.598324142417, 4734.573888350065, 4756.7859446325, 5082.237664350377, 5114.974425127925, 5165.509276618715, 5175.585953123817, 5182.585393168538, 4837.37114175155, 4904.411470623548, 4914.606831453475, 4924.729139897268, 4933.407991041098, 5278.6832790031185, 5282.683039017671, 5293.655912365684, 5298.178782288752, 5307.6284660073425, 4945.77744001528, 5128.974355128201, 5199.0, 5228.189630607262, 5228.372595288438, 4622.306654001926, 4755.928661070017, 4787.640926930424, 4918.2622606607865, 4924.926126108039, 3680.8527658893345, 3681.39257022599, 3683.9815800920824, 3686.9446958296185, 3690.1328187875292, 5212.426633070435, 5223.895522089613, 5229.914350533204, 5248.816291429782, 5270.973645131749, 4998.225275082368, 5068.163752980717, 5076.873078173076, 5079.263506791568, 5091.770870310919, 5064.0, 5087.64386492942, 5095.464976177448, 5105.974470127626, 5114.284000239895, 5005.899882002413, 5016.974915125401, 5051.292819005269, 5053.635518409935, 5063.961887813063, 5019.824306149268, 5157.871053223549, 5183.3261675981885, 5183.377994640668, 5191.779931715968, 5034.572061374678, 5035.597152227886, 5038.370203724535, 5053.36832895888, 5058.215976523645], "value": 3874.3487432938587, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.1694962649303, 668.314977148423, 672.3679741043422, 720.1861896057454, 727.0983979864969, 12152.696182595428, 13731.794023089662, 13751.174929504237, 13756.38096285667, 13792.931035344835, 13071.803922941164, 13087.541936032241, 13134.540291089814, 13137.802932955989, 13225.603231903058, 600.5795942840008, 634.9015902535091, 717.042747931512, 762.4815125714512, 779.9727009554666, 762.5539059650112, 776.0222120128623, 776.7941495503696, 777.327611615951, 777.3703300326754, 824.3364091906013, 827.1067247372853, 827.7723626002868, 828.1552816127546, 828.3207769628907, 626.2797782550058, 666.3303380102966, 750.7147284032087, 817.9059408168048, 818.4271010292789, 600.5075837812996, 605.9515238780931, 606.6147996022513, 606.8816580766752, 606.9423404776584, 12608.180468269533, 12677.809832852514, 12680.0, 12691.0, 12709.17390369623, 12746.171498852545, 12792.48830046797, 12801.423935922905, 12804.167729097671, 13044.934775326063, 10869.945650271777, 10873.673789786319, 11161.665150045512, 11218.66344009681, 11420.828687569694, 3097.829619370934, 3951.7826519541413, 3986.40203969404, 4049.6557792587664, 4120.958790412117, 65385.3461465384, 65964.0, 66265.66867165682, 67561.0, 67950.66024669894, 10103.494825258707, 10168.491575421347, 10344.948275258575, 10368.274220804622, 10564.260501764802, 17887.37394191197, 17893.7315940261, 17899.463016109537, 17907.641847162984, 17913.552161195814, 6312.684365781784, 6502.642354670538, 6544.083828264047, 6649.335066493312, 6742.49431292655, 3572.4105522589, 3577.94633080504, 3657.140571965586, 3661.377565813818, 3742.5321834770652, 2799.0902956539207, 3229.370272796829, 3393.9151521212166, 3399.677030682131, 3444.6727560881677, 492.70683943053825, 502.19147173051357, 503.4537526783408, 517.5911030286107, 521.9399769026535, 431.1484817485441, 566.2157911292888, 570.7146426786607, 594.4035229763628, 625.6746491824317, 7965.243301886311, 8080.636371363303, 8095.554744488992, 8132.715354962666, 8154.6738130475305, 543.6955305029196, 560.2604561978231, 593.5785592229565, 594.7115648910275, 604.1059232336167, 3854.0, 3960.970147761545, 3973.2450834341385, 4087.3051581230666, 4096.303628383182, 65062.34937650563, 66311.33688663148, 68005.0, 69069.65465172642, 70963.0, 41825.0, 50751.492485074676, 54525.1821222682, 56255.71872140695, 56290.15564766532, 40273.59726402757, 46668.5333146671, 49405.25892111549, 49904.0, 50287.49712502901, 8469.407141500036, 8668.349873759367, 8956.865647015169, 8981.371304008919, 8991.460512369016, 30300.848495757382, 30583.0, 32728.34543309073, 34133.65866341404, 35121.8243908789, 35955.64044359523, 39151.021224469616, 41853.372199417645, 43464.56535434605, 45341.31988020116, 10942.17933654995, 10981.670549883516, 11119.944400277947, 11303.773924521627, 11672.796398596258, 12023.158378913393, 12384.071194660257, 12701.618951431472, 12778.424970876518, 13846.376913038735, 12379.133460658028, 12579.559715409916, 12663.873361266269, 12867.233544787916, 12895.097343185887, 12176.452059657513, 12371.443285052013, 12565.18326308787, 12745.362731863184, 13108.279044652636, 11522.004278887718, 11705.25023635199, 11868.881311186778, 11975.76048479043, 12701.174423662433, 9929.553170107218, 10160.390376577428, 10198.286119971532, 10352.53413596405, 10467.214958877963, 10075.748106297402, 10647.627333043307, 10651.414172220602, 10679.679609611723, 10788.352698838095], "value": 6267.091893455193, "unit": "score"}], "name": "dromaeo_css", "value": 6231.134328846444, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 118.0, 116.0, 116.0, 114.0, 116.0, 116.0, 116.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 128.0, 129.0, 172.0, 127.0, 127.0, 127.0, 130.0, 148.0, 132.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 164.0, 161.0, 161.0, 165.0, 162.0, 162.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 78.0, 106.0, 79.0, 77.0, 76.0, 75.0, 90.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 117.0, 84.0, 85.0, 85.0, 108.0, 84.0, 84.0, 115.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 117.0, 118.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 146.0, 147.0, 146.0, 147.0, 147.0, 147.0, 148.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 65.0, 66.0, 67.0, 66.0, 65.0, 67.0, 67.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 96.0, 84.0, 85.0, 86.0, 90.0, 95.0, 84.0, 84.0, 84.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 134.0, 137.0, 135.0, 135.0, 154.0, 135.0, 134.0, 179.0, 137.0], "value": 142.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 183.0, 189.0, 180.0, 184.0, 182.0, 182.0, 181.0, 186.0], "value": 183.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 69.0, 69.0, 73.0, 74.0, 68.0, 75.0, 72.0], "value": 70.7, "unit": "score"}], "name": "kraken", "value": 1565.7000000000003, "alertThreshold": 2.0}]} 10:18:43 INFO - Return code: 0 10:18:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:18:43 INFO - # TBPL SUCCESS # 10:18:43 INFO - Running post-action listener: _resource_record_post_action 10:18:43 INFO - [mozharness: 2016-12-21 18:18:43.378454Z] Finished run-tests step (success) 10:18:43 INFO - Running post-run listener: _resource_record_post_run 10:18:43 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 395743232; Read time: 12; Write time: 302844 10:18:43 INFO - TinderboxPrint: CPU usage
13.1% 10:18:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 10:18:43 INFO - TinderboxPrint: I/O write bytes / time
395,743,232 / 302,844 10:18:43 INFO - TinderboxPrint: CPU idle
8,342.7 (86.9%) 10:18:43 INFO - TinderboxPrint: CPU user
1,213.3 (12.6%) 10:18:43 INFO - TinderboxPrint: Swap in / out
0 / 0 10:18:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 106397696; Read time: 0; Write time: 101112 10:18:43 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 288944128; Read time: 0; Write time: 201708 10:18:44 INFO - Running post-run listener: _upload_blobber_files 10:18:44 INFO - Blob upload gear active. 10:18:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:18:44 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 10:18:44 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:18:44 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:18:44 INFO - (blobuploader) - INFO - Open directory for files ... 10:18:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:18:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:18:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:45 INFO - (blobuploader) - INFO - Done attempting. 10:18:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:18:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:18:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:45 INFO - (blobuploader) - INFO - Done attempting. 10:18:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:18:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:18:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:47 INFO - (blobuploader) - INFO - Done attempting. 10:18:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:18:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:18:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:51 INFO - (blobuploader) - INFO - Done attempting. 10:18:51 INFO - (blobuploader) - INFO - Iteration through files over. 10:18:51 INFO - Return code: 0 10:18:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:18:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:18:51 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d2db12bc3587d79e715985d6c01222f1e326667302436ecfc8f59b661ddd8a2fc7fea27dcce3aa610b8b7a4717cb1371e0e491161ea1eae8d7f289e9c609463b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d58d101cf605c524870466124bd9ff701f76ca803b850dd1c6799209cee7e326da8e2df3185cc5c7c0fb6c6e0bb5493e5f9073bbad095580d89f77e98dd299e7", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7f14c772007e7e0800640f7eaabb5178d3f2220487cc1e044c72dae8790c1c7b41e75045ffdbdf4d8e46c7eb7a97824d92dba9f22bac18b66f485e18b72ff7dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/02912641c3fa8d5160e759548d6cf8a90a6a4a91a00e7d98d6865453924eb0afd7651cdb9c28b6610565d595bce2cdc9b2f19c63e830f0726c62738f5235980e"} 10:18:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:18:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:18:51 INFO - Contents: 10:18:51 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d2db12bc3587d79e715985d6c01222f1e326667302436ecfc8f59b661ddd8a2fc7fea27dcce3aa610b8b7a4717cb1371e0e491161ea1eae8d7f289e9c609463b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d58d101cf605c524870466124bd9ff701f76ca803b850dd1c6799209cee7e326da8e2df3185cc5c7c0fb6c6e0bb5493e5f9073bbad095580d89f77e98dd299e7", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7f14c772007e7e0800640f7eaabb5178d3f2220487cc1e044c72dae8790c1c7b41e75045ffdbdf4d8e46c7eb7a97824d92dba9f22bac18b66f485e18b72ff7dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/02912641c3fa8d5160e759548d6cf8a90a6a4a91a00e7d98d6865453924eb0afd7651cdb9c28b6610565d595bce2cdc9b2f19c63e830f0726c62738f5235980e"} 10:18:51 INFO - Running post-run listener: copy_logs_to_upload_dir 10:18:51 INFO - Copying logs to upload dir... 10:18:51 INFO - mkdir: /builds/slave/test/build/upload/logs 10:18:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.060446 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-12-21 10:18:50.979370) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 10:18:50.983702) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d2db12bc3587d79e715985d6c01222f1e326667302436ecfc8f59b661ddd8a2fc7fea27dcce3aa610b8b7a4717cb1371e0e491161ea1eae8d7f289e9c609463b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d58d101cf605c524870466124bd9ff701f76ca803b850dd1c6799209cee7e326da8e2df3185cc5c7c0fb6c6e0bb5493e5f9073bbad095580d89f77e98dd299e7", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7f14c772007e7e0800640f7eaabb5178d3f2220487cc1e044c72dae8790c1c7b41e75045ffdbdf4d8e46c7eb7a97824d92dba9f22bac18b66f485e18b72ff7dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/02912641c3fa8d5160e759548d6cf8a90a6a4a91a00e7d98d6865453924eb0afd7651cdb9c28b6610565d595bce2cdc9b2f19c63e830f0726c62738f5235980e"} build_url:https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011821 build_url: 'https://queue.taskcluster.net/v1/task/DF2VMS32Sz-kGok1l7bj_Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d2db12bc3587d79e715985d6c01222f1e326667302436ecfc8f59b661ddd8a2fc7fea27dcce3aa610b8b7a4717cb1371e0e491161ea1eae8d7f289e9c609463b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d58d101cf605c524870466124bd9ff701f76ca803b850dd1c6799209cee7e326da8e2df3185cc5c7c0fb6c6e0bb5493e5f9073bbad095580d89f77e98dd299e7", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7f14c772007e7e0800640f7eaabb5178d3f2220487cc1e044c72dae8790c1c7b41e75045ffdbdf4d8e46c7eb7a97824d92dba9f22bac18b66f485e18b72ff7dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/02912641c3fa8d5160e759548d6cf8a90a6a4a91a00e7d98d6865453924eb0afd7651cdb9c28b6610565d595bce2cdc9b2f19c63e830f0726c62738f5235980e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 10:18:51.010124) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 10:18:51.010462) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1482343080.366591-542178401 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 10:18:51.096049) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 10:18:51.096365) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 10:18:51.096655) ========= ========= Total master_lag: 0.27 =========