builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1471315023.82 results: success (0) buildid: 20160815173003 builduid: 14b1c745ba7d4a41ac6aa004423651d2 revision: 2697bf7ad45d0f70e4977aea25860052cc480a26 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:03.817986) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:03.818705) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:03.819190) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:03.834520) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:03.834856) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-15 19:37:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.9M=0s 2016-08-15 19:37:04 (50.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111725 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:03.961136) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:03.961512) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017622 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:04.011637) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:04.012019) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2697bf7ad45d0f70e4977aea25860052cc480a26 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 2697bf7ad45d0f70e4977aea25860052cc480a26 --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=9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-15 19:37:04,595 truncating revision to first 12 chars 2016-08-15 19:37:04,595 Setting DEBUG logging. 2016-08-15 19:37:04,595 attempt 1/10 2016-08-15 19:37:04,595 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2697bf7ad45d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-15 19:37:04,879 unpacking tar archive at: fx-team-2697bf7ad45d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.688050 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:04.714919) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:04.715396) ========= script_repo_revision: 2697bf7ad45d0f70e4977aea25860052cc480a26 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:04.715816) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:04.716106) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 19:37:04.726295) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-15 19:37:04.726642) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:37:05 INFO - MultiFileLogger online at 20160815 19:37:05 in /builds/slave/test 19:37:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 19:37:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:37:05 INFO - {'append_to_log': False, 19:37:05 INFO - 'base_work_dir': '/builds/slave/test', 19:37:05 INFO - 'blob_upload_branch': 'Fx-Team', 19:37:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:37:05 INFO - 'branch': 'Fx-Team', 19:37:05 INFO - 'buildbot_json_path': 'buildprops.json', 19:37:05 INFO - 'config_files': ('talos/linux_config.py',), 19:37:05 INFO - 'default_actions': ('clobber', 19:37:05 INFO - 'read-buildbot-config', 19:37:05 INFO - 'download-and-extract', 19:37:05 INFO - 'populate-webroot', 19:37:05 INFO - 'create-virtualenv', 19:37:05 INFO - 'install', 19:37:05 INFO - 'run-tests'), 19:37:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:37:05 INFO - 'download_minidump_stackwalk': True, 19:37:05 INFO - 'download_symbols': 'ondemand', 19:37:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:37:05 INFO - 'tooltool.py': '/tools/tooltool.py', 19:37:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:37:05 INFO - '/tools/misc-python/virtualenv.py')}, 19:37:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:37:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:37:05 INFO - 'installer_path': 'installer.exe', 19:37:05 INFO - 'log_level': 'info', 19:37:05 INFO - 'log_name': 'talos', 19:37:05 INFO - 'log_to_console': True, 19:37:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:37:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:37:05 INFO - 'opt_config_files': (), 19:37:05 INFO - 'pip_index': False, 19:37:05 INFO - 'sps_profile': False, 19:37:05 INFO - 'sps_profile_interval': 0, 19:37:05 INFO - 'suite': 'dromaeojs', 19:37:05 INFO - 'system_bits': '32', 19:37:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:37:05 INFO - 'title': 'talos-linux64-ix-022', 19:37:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:37:05 INFO - 'use_talos_json': True, 19:37:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:37:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:37:05 INFO - 'work_dir': 'build'} 19:37:05 INFO - [mozharness: 2016-08-16 02:37:05.411243Z] Running clobber step. 19:37:05 INFO - Running pre-action listener: _resource_record_pre_action 19:37:05 INFO - Running main action method: clobber 19:37:05 INFO - rmtree: /builds/slave/test/build 19:37:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:37:05 INFO - Running post-action listener: _resource_record_post_action 19:37:05 INFO - [mozharness: 2016-08-16 02:37:05.906473Z] Finished clobber step (success) 19:37:05 INFO - [mozharness: 2016-08-16 02:37:05.906574Z] Running read-buildbot-config step. 19:37:05 INFO - Running pre-action listener: _resource_record_pre_action 19:37:05 INFO - Running main action method: read_buildbot_config 19:37:05 INFO - Using buildbot properties: 19:37:05 INFO - { 19:37:05 INFO - "project": "", 19:37:05 INFO - "product": "firefox", 19:37:05 INFO - "script_repo_revision": "production", 19:37:05 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 19:37:05 INFO - "repository": "", 19:37:05 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 19:37:05 INFO - "buildid": "20160815173003", 19:37:05 INFO - "pgo_build": "True", 19:37:05 INFO - "basedir": "/builds/slave/test", 19:37:05 INFO - "buildnumber": 1460, 19:37:05 INFO - "slavename": "talos-linux64-ix-022", 19:37:05 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:37:05 INFO - "platform": "ubuntu64_hw", 19:37:05 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 19:37:05 INFO - "branch": "fx-team", 19:37:05 INFO - "repo_path": "integration/fx-team", 19:37:05 INFO - "revision": "2697bf7ad45d0f70e4977aea25860052cc480a26", 19:37:05 INFO - "stage_platform": "linux64-pgo", 19:37:05 INFO - "builduid": "14b1c745ba7d4a41ac6aa004423651d2", 19:37:05 INFO - "slavebuilddir": "test" 19:37:05 INFO - } 19:37:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 19:37:05 INFO - Running post-action listener: _resource_record_post_action 19:37:05 INFO - [mozharness: 2016-08-16 02:37:05.908989Z] Finished read-buildbot-config step (success) 19:37:05 INFO - [mozharness: 2016-08-16 02:37:05.909077Z] Running download-and-extract step. 19:37:05 INFO - Running pre-action listener: _resource_record_pre_action 19:37:05 INFO - Running main action method: download_and_extract 19:37:05 INFO - mkdir: /builds/slave/test/build/tests 19:37:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:05 INFO - trying https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:37:05 INFO - Downloading https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:37:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:37:06 INFO - Downloaded 1361 bytes. 19:37:06 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:37:06 INFO - Using the following test package requirements: 19:37:06 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:37:06 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:37:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:37:06 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:37:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:37:06 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:37:06 INFO - u'jsshell-linux-x86_64.zip'], 19:37:06 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:37:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:37:06 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:37:06 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:37:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:37:06 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:37:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:37:06 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:37:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:37:06 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:37:06 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:37:06 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:06 INFO - trying https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:37:06 INFO - Downloading https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:37:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:37:07 INFO - Downloaded 21837087 bytes. 19:37:07 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 19:37:11 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:11 INFO - trying https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:37:11 INFO - Downloading https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:37:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:37:12 INFO - Downloaded 21837087 bytes. 19:37:12 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 19:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:17 INFO - trying https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 19:37:17 INFO - Downloading https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 19:37:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:37:18 INFO - Downloaded 14413719 bytes. 19:37:18 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 19:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:18 INFO - trying https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:37:18 INFO - Downloading https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:37:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:37:19 INFO - Downloaded 59405437 bytes. 19:37:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:37:19 INFO - mkdir: /builds/slave/test/properties 19:37:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:37:19 INFO - Writing to file /builds/slave/test/properties/build_url 19:37:19 INFO - Contents: 19:37:19 INFO - build_url:https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:37:20 INFO - Running post-action listener: _resource_record_post_action 19:37:20 INFO - Running post-action listener: set_extra_try_arguments 19:37:20 INFO - [mozharness: 2016-08-16 02:37:20.481943Z] Finished download-and-extract step (success) 19:37:20 INFO - [mozharness: 2016-08-16 02:37:20.482169Z] Running populate-webroot step. 19:37:20 INFO - Running pre-action listener: _resource_record_pre_action 19:37:20 INFO - Running main action method: populate_webroot 19:37:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:37:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:37:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:37:20 INFO - '--tppagecycles', 19:37:20 INFO - '7'], 19:37:20 INFO - 'tests': ['tsvgm']}}, 19:37:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:37:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:37:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:37:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:37:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:37:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:37:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:37:20 INFO - 'talos_options': ['--disable-e10s'], 19:37:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:37:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:37:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:37:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:37:20 INFO - 'talos_options': ['--disable-e10s'], 19:37:20 INFO - 'tests': ['damp', 'tps']}, 19:37:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:37:20 INFO - 'tests': ['damp', 'tps']}, 19:37:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:37:20 INFO - 'tests': ['dromaeo_dom']}, 19:37:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:37:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:37:20 INFO - 'tests': ['basic_compositor_video']}, 19:37:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:37:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:37:20 INFO - 'tests': ['a11yr', 19:37:20 INFO - 'ts_paint', 19:37:20 INFO - 'tpaint', 19:37:20 INFO - 'sessionrestore', 19:37:20 INFO - 'sessionrestore_no_auto_restore', 19:37:20 INFO - 'tabpaint']}, 19:37:20 INFO - 'other-e10s': {'tests': ['a11yr', 19:37:20 INFO - 'ts_paint', 19:37:20 INFO - 'tpaint', 19:37:20 INFO - 'sessionrestore', 19:37:20 INFO - 'sessionrestore_no_auto_restore', 19:37:20 INFO - 'tabpaint']}, 19:37:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:37:20 INFO - 'ts_paint', 19:37:20 INFO - 'tpaint', 19:37:20 INFO - 'sessionrestore', 19:37:20 INFO - 'sessionrestore_no_auto_restore', 19:37:20 INFO - 'tabpaint']}, 19:37:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:37:20 INFO - 'ts_paint', 19:37:20 INFO - 'tpaint', 19:37:20 INFO - 'sessionrestore', 19:37:20 INFO - 'sessionrestore_no_auto_restore', 19:37:20 INFO - 'tabpaint']}, 19:37:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:37:20 INFO - 'tests': ['a11yr', 19:37:20 INFO - 'ts_paint', 19:37:20 INFO - 'tpaint', 19:37:20 INFO - 'sessionrestore', 19:37:20 INFO - 'sessionrestore_no_auto_restore', 19:37:20 INFO - 'tabpaint']}, 19:37:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:37:20 INFO - 'tests': ['a11yr', 19:37:20 INFO - 'ts_paint', 19:37:20 INFO - 'tpaint', 19:37:20 INFO - 'sessionrestore', 19:37:20 INFO - 'sessionrestore_no_auto_restore', 19:37:20 INFO - 'tabpaint']}, 19:37:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:37:20 INFO - 'tests': ['tsvgx', 19:37:20 INFO - 'tsvgr_opacity', 19:37:20 INFO - 'tart', 19:37:20 INFO - 'tscrollx', 19:37:20 INFO - 'cart']}, 19:37:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:37:20 INFO - 'tsvgr_opacity', 19:37:20 INFO - 'tart', 19:37:20 INFO - 'tscrollx', 19:37:20 INFO - 'cart']}, 19:37:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:37:20 INFO - 'talos_options': ['--disable-e10s'], 19:37:20 INFO - 'tests': ['tp5o']}, 19:37:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:37:20 INFO - 'tests': ['tp5o']}, 19:37:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:37:20 INFO - 'talos_options': ['--disable-e10s', 19:37:20 INFO - '--xperf_path', 19:37:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:37:20 INFO - 'tests': ['tp5n']}, 19:37:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:37:20 INFO - 'talos_options': ['--xperf_path', 19:37:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:37:20 INFO - 'tests': ['tp5n']}}, 19:37:20 INFO - 'talos.zip': {'path': '', 19:37:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:37:20 INFO - Running post-action listener: _resource_record_post_action 19:37:20 INFO - [mozharness: 2016-08-16 02:37:20.502936Z] Finished populate-webroot step (success) 19:37:20 INFO - [mozharness: 2016-08-16 02:37:20.503024Z] Running create-virtualenv step. 19:37:20 INFO - Running pre-action listener: _resource_record_pre_action 19:37:20 INFO - Running main action method: create_virtualenv 19:37:20 INFO - Creating virtualenv /builds/slave/test/build/venv 19:37:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:37:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:37:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:37:20 INFO - Using real prefix '/usr' 19:37:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:37:21 INFO - Installing distribute.............................................................................................................................................................................................done. 19:37:23 INFO - Installing pip.................done. 19:37:23 INFO - Return code: 0 19:37:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:23 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 0x7f97a06acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c168d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5760>, '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 0x2ca6660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6af0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:37:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:37:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:37:23 INFO - Using env: {'DISPLAY': ':0', 19:37:23 INFO - 'HOME': '/home/cltbld', 19:37:23 INFO - 'LANG': 'en_US.UTF-8', 19:37:23 INFO - 'LANGUAGE': 'en_US:en', 19:37:23 INFO - 'LOGNAME': 'cltbld', 19:37:23 INFO - 'MAIL': '/var/mail/cltbld', 19:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:23 INFO - 'MOZ_NO_REMOTE': '1', 19:37:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:37:23 INFO - 'NO_EM_RESTART': '1', 19:37:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:23 INFO - 'PWD': '/builds/slave/test', 19:37:23 INFO - 'SHELL': '/bin/bash', 19:37:23 INFO - 'SHLVL': '1', 19:37:23 INFO - 'TERM': 'linux', 19:37:23 INFO - 'TMOUT': '86400', 19:37:23 INFO - 'USER': 'cltbld', 19:37:23 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 19:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:23 INFO - '_': '/tools/buildbot/bin/python'} 19:37:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:37:23 INFO - Cleaning up... 19:37:23 INFO - Return code: 0 19:37:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:23 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 0x7f97a06acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c168d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5760>, '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 0x2ca6660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6af0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:37:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:37:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:37:23 INFO - Using env: {'DISPLAY': ':0', 19:37:23 INFO - 'HOME': '/home/cltbld', 19:37:23 INFO - 'LANG': 'en_US.UTF-8', 19:37:23 INFO - 'LANGUAGE': 'en_US:en', 19:37:23 INFO - 'LOGNAME': 'cltbld', 19:37:23 INFO - 'MAIL': '/var/mail/cltbld', 19:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:23 INFO - 'MOZ_NO_REMOTE': '1', 19:37:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:37:23 INFO - 'NO_EM_RESTART': '1', 19:37:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:23 INFO - 'PWD': '/builds/slave/test', 19:37:23 INFO - 'SHELL': '/bin/bash', 19:37:23 INFO - 'SHLVL': '1', 19:37:23 INFO - 'TERM': 'linux', 19:37:23 INFO - 'TMOUT': '86400', 19:37:23 INFO - 'USER': 'cltbld', 19:37:23 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 19:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:23 INFO - '_': '/tools/buildbot/bin/python'} 19:37:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:23 INFO - Downloading/unpacking psutil>=3.1.1 19:37: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 19:37: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 19:37: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 19:37: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 19:37: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 19:37: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 19:37:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:37:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:37:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:37:25 INFO - Installing collected packages: psutil 19:37:25 INFO - Running setup.py install for psutil 19:37:25 INFO - building 'psutil._psutil_linux' extension 19:37:25 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:37:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:37:25 INFO - building 'psutil._psutil_posix' extension 19:37:25 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:37:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:37:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:37:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:37:26 INFO - Successfully installed psutil 19:37:26 INFO - Cleaning up... 19:37:26 INFO - Return code: 0 19:37:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:26 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 0x7f97a06acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c168d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5760>, '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 0x2ca6660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6af0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:37:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:37:26 INFO - Using env: {'DISPLAY': ':0', 19:37:26 INFO - 'HOME': '/home/cltbld', 19:37:26 INFO - 'LANG': 'en_US.UTF-8', 19:37:26 INFO - 'LANGUAGE': 'en_US:en', 19:37:26 INFO - 'LOGNAME': 'cltbld', 19:37:26 INFO - 'MAIL': '/var/mail/cltbld', 19:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:26 INFO - 'MOZ_NO_REMOTE': '1', 19:37:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:37:26 INFO - 'NO_EM_RESTART': '1', 19:37:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:26 INFO - 'PWD': '/builds/slave/test', 19:37:26 INFO - 'SHELL': '/bin/bash', 19:37:26 INFO - 'SHLVL': '1', 19:37:26 INFO - 'TERM': 'linux', 19:37:26 INFO - 'TMOUT': '86400', 19:37:26 INFO - 'USER': 'cltbld', 19:37:26 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 19:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:26 INFO - '_': '/tools/buildbot/bin/python'} 19:37:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:37:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:37:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:37:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:37:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:37:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:37:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:37:28 INFO - Installing collected packages: mozsystemmonitor 19:37:28 INFO - Running setup.py install for mozsystemmonitor 19:37:28 INFO - Successfully installed mozsystemmonitor 19:37:28 INFO - Cleaning up... 19:37:28 INFO - Return code: 0 19:37:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:37: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')]} 19:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37: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')]} 19:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37: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', '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 0x7f97a06acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c168d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5760>, '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 0x2ca6660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6af0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:37: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:37: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 jsonschema==2.5.1 19:37:28 INFO - Using env: {'DISPLAY': ':0', 19:37:28 INFO - 'HOME': '/home/cltbld', 19:37:28 INFO - 'LANG': 'en_US.UTF-8', 19:37:28 INFO - 'LANGUAGE': 'en_US:en', 19:37:28 INFO - 'LOGNAME': 'cltbld', 19:37:28 INFO - 'MAIL': '/var/mail/cltbld', 19:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:28 INFO - 'MOZ_NO_REMOTE': '1', 19:37:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:37:28 INFO - 'NO_EM_RESTART': '1', 19:37:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:28 INFO - 'PWD': '/builds/slave/test', 19:37:28 INFO - 'SHELL': '/bin/bash', 19:37:28 INFO - 'SHLVL': '1', 19:37:28 INFO - 'TERM': 'linux', 19:37:28 INFO - 'TMOUT': '86400', 19:37:28 INFO - 'USER': 'cltbld', 19:37:28 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 19:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:28 INFO - '_': '/tools/buildbot/bin/python'} 19:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:28 INFO - Downloading/unpacking jsonschema==2.5.1 19:37: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 19:37: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 19:37: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 19:37: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 19:37: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 19:37: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 19:37:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:37:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:37: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 19:37: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 19:37: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 19:37: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 19:37: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 19:37: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 19:37:30 INFO - Downloading functools32-3.2.3-2.tar.gz 19:37:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:37:30 INFO - warning: no files found matching '*.txt' 19:37:30 INFO - no previously-included directories found matching 'build' 19:37:30 INFO - no previously-included directories found matching 'dist' 19:37:30 INFO - no previously-included directories found matching '.git*' 19:37:30 INFO - Installing collected packages: jsonschema, functools32 19:37:30 INFO - Running setup.py install for functools32 19:37:30 INFO - warning: no files found matching '*.txt' 19:37:30 INFO - no previously-included directories found matching 'build' 19:37:30 INFO - no previously-included directories found matching 'dist' 19:37:30 INFO - no previously-included directories found matching '.git*' 19:37:30 INFO - Successfully installed jsonschema functools32 19:37:30 INFO - Cleaning up... 19:37:30 INFO - Return code: 0 19:37:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:30 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 0x7f97a06acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c168d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5760>, '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 0x2ca6660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6af0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:37:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:37:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:37:30 INFO - Using env: {'DISPLAY': ':0', 19:37:30 INFO - 'HOME': '/home/cltbld', 19:37:30 INFO - 'LANG': 'en_US.UTF-8', 19:37:30 INFO - 'LANGUAGE': 'en_US:en', 19:37:30 INFO - 'LOGNAME': 'cltbld', 19:37:30 INFO - 'MAIL': '/var/mail/cltbld', 19:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:30 INFO - 'MOZ_NO_REMOTE': '1', 19:37:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:37:30 INFO - 'NO_EM_RESTART': '1', 19:37:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:30 INFO - 'PWD': '/builds/slave/test', 19:37:31 INFO - 'SHELL': '/bin/bash', 19:37:31 INFO - 'SHLVL': '1', 19:37:31 INFO - 'TERM': 'linux', 19:37:31 INFO - 'TMOUT': '86400', 19:37:31 INFO - 'USER': 'cltbld', 19:37:31 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 19:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:31 INFO - '_': '/tools/buildbot/bin/python'} 19:37:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:37:31 INFO - Cleaning up... 19:37:31 INFO - Return code: 0 19:37:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:37:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f97a06acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c168d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5760>, '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 0x2ca6660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6af0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:37:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:37:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:37:31 INFO - Using env: {'DISPLAY': ':0', 19:37:31 INFO - 'HOME': '/home/cltbld', 19:37:31 INFO - 'LANG': 'en_US.UTF-8', 19:37:31 INFO - 'LANGUAGE': 'en_US:en', 19:37:31 INFO - 'LOGNAME': 'cltbld', 19:37:31 INFO - 'MAIL': '/var/mail/cltbld', 19:37:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:31 INFO - 'MOZ_NO_REMOTE': '1', 19:37:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:37:31 INFO - 'NO_EM_RESTART': '1', 19:37:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:37:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:31 INFO - 'PWD': '/builds/slave/test', 19:37:31 INFO - 'SHELL': '/bin/bash', 19:37:31 INFO - 'SHLVL': '1', 19:37:31 INFO - 'TERM': 'linux', 19:37:31 INFO - 'TMOUT': '86400', 19:37:31 INFO - 'USER': 'cltbld', 19:37:31 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 19:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:31 INFO - '_': '/tools/buildbot/bin/python'} 19:37:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:31 INFO - Downloading/unpacking blobuploader==1.2.4 19:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:37:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:37:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:37:33 INFO - Downloading blobuploader-1.2.4.tar.gz 19:37:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:37:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:37:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:37:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:37:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:37:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:37:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:37:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:37:34 INFO - Downloading docopt-0.6.1.tar.gz 19:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:37:34 INFO - Installing collected packages: blobuploader, requests, docopt 19:37:34 INFO - Running setup.py install for blobuploader 19:37:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:37:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:37:34 INFO - Running setup.py install for requests 19:37:34 INFO - Running setup.py install for docopt 19:37:34 INFO - Successfully installed blobuploader requests docopt 19:37:34 INFO - Cleaning up... 19:37:34 INFO - Return code: 0 19:37:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:34 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 0x7f97a06acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c168d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5760>, '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 0x2ca6660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6af0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:37:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:37:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:37:34 INFO - Using env: {'DISPLAY': ':0', 19:37:34 INFO - 'HOME': '/home/cltbld', 19:37:34 INFO - 'LANG': 'en_US.UTF-8', 19:37:34 INFO - 'LANGUAGE': 'en_US:en', 19:37:34 INFO - 'LOGNAME': 'cltbld', 19:37:34 INFO - 'MAIL': '/var/mail/cltbld', 19:37:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:34 INFO - 'MOZ_NO_REMOTE': '1', 19:37:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:37:34 INFO - 'NO_EM_RESTART': '1', 19:37:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:37:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:34 INFO - 'PWD': '/builds/slave/test', 19:37:34 INFO - 'SHELL': '/bin/bash', 19:37:34 INFO - 'SHLVL': '1', 19:37:34 INFO - 'TERM': 'linux', 19:37:34 INFO - 'TMOUT': '86400', 19:37:34 INFO - 'USER': 'cltbld', 19:37:34 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 19:37:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:34 INFO - '_': '/tools/buildbot/bin/python'} 19:37:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:37:34 INFO - Running setup.py (path:/tmp/pip-ECdM7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:37:34 INFO - Running setup.py (path:/tmp/pip-HyLjWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:37:35 INFO - Running setup.py (path:/tmp/pip-q_fKHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:37:35 INFO - Running setup.py (path:/tmp/pip-tazajK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:37:35 INFO - Running setup.py (path:/tmp/pip-5w7gdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:37:35 INFO - Running setup.py (path:/tmp/pip-RsEYX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:37:35 INFO - Running setup.py (path:/tmp/pip-UGysUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:37:35 INFO - Running setup.py (path:/tmp/pip-Q9iycM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:37:35 INFO - Running setup.py (path:/tmp/pip-s4WUjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:37:35 INFO - Running setup.py (path:/tmp/pip-7elSaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:37:35 INFO - Running setup.py (path:/tmp/pip-0Ok8nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:37:35 INFO - Running setup.py (path:/tmp/pip-iew56J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:37:35 INFO - Running setup.py (path:/tmp/pip-zRvtAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:37:35 INFO - Running setup.py (path:/tmp/pip-Yd38te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:37:35 INFO - Running setup.py (path:/tmp/pip-UGh3sU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:37:36 INFO - Running setup.py (path:/tmp/pip-YEs72U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:37:36 INFO - Running setup.py (path:/tmp/pip-_BgyGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:37:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:37:36 INFO - Running setup.py install for manifestparser 19:37:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:37:36 INFO - Running setup.py install for mozcrash 19:37:36 INFO - Running setup.py install for mozdebug 19:37:36 INFO - Running setup.py install for mozdevice 19:37:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:37:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:37:36 INFO - Running setup.py install for mozfile 19:37:36 INFO - Running setup.py install for mozhttpd 19:37:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:37:36 INFO - Running setup.py install for mozinfo 19:37:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:37:36 INFO - Running setup.py install for mozInstall 19:37:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:37:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:37:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:37:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:37:36 INFO - Running setup.py install for mozleak 19:37:37 INFO - Running setup.py install for mozlog 19:37:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:37:37 INFO - Running setup.py install for moznetwork 19:37:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:37:37 INFO - Running setup.py install for mozprocess 19:37:37 INFO - Running setup.py install for mozprofile 19:37:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:37:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:37:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:37:37 INFO - Running setup.py install for mozrunner 19:37:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:37:37 INFO - Running setup.py install for mozscreenshot 19:37:37 INFO - Running setup.py install for moztest 19:37:37 INFO - Running setup.py install for mozversion 19:37:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:37:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:37:37 INFO - Cleaning up... 19:37:37 INFO - Return code: 0 19:37:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:37:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:37 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 0x7f97a06acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c168d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5760>, '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 0x2ca6660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6af0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:37:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:37:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:37:37 INFO - Using env: {'DISPLAY': ':0', 19:37:37 INFO - 'HOME': '/home/cltbld', 19:37:37 INFO - 'LANG': 'en_US.UTF-8', 19:37:37 INFO - 'LANGUAGE': 'en_US:en', 19:37:37 INFO - 'LOGNAME': 'cltbld', 19:37:37 INFO - 'MAIL': '/var/mail/cltbld', 19:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:37 INFO - 'MOZ_NO_REMOTE': '1', 19:37:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:37:37 INFO - 'NO_EM_RESTART': '1', 19:37:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:37 INFO - 'PWD': '/builds/slave/test', 19:37:37 INFO - 'SHELL': '/bin/bash', 19:37:37 INFO - 'SHLVL': '1', 19:37:37 INFO - 'TERM': 'linux', 19:37:37 INFO - 'TMOUT': '86400', 19:37:37 INFO - 'USER': 'cltbld', 19:37:37 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 19:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:37 INFO - '_': '/tools/buildbot/bin/python'} 19:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:37:38 INFO - Running setup.py (path:/tmp/pip-NbaJaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:37:38 INFO - Running setup.py (path:/tmp/pip-LHjrNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:37:38 INFO - Running setup.py (path:/tmp/pip-TLcuNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:37:38 INFO - Running setup.py (path:/tmp/pip-hUYMB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:37:38 INFO - Running setup.py (path:/tmp/pip-y_vXDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:37:38 INFO - Running setup.py (path:/tmp/pip-ktGfgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:37:38 INFO - Running setup.py (path:/tmp/pip-b9f_Ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:37:38 INFO - Running setup.py (path:/tmp/pip-xKMYWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:37:38 INFO - Running setup.py (path:/tmp/pip-f6K49H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:37:38 INFO - Running setup.py (path:/tmp/pip-xwxU98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:37:38 INFO - Running setup.py (path:/tmp/pip-xNJ6d5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:37:39 INFO - Running setup.py (path:/tmp/pip-yngm9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:37:39 INFO - Running setup.py (path:/tmp/pip-RMctQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:37:39 INFO - Running setup.py (path:/tmp/pip-p3ofqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:37:39 INFO - Running setup.py (path:/tmp/pip-cVEeWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:37:39 INFO - Running setup.py (path:/tmp/pip-xOdEBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:37:39 INFO - Running setup.py (path:/tmp/pip-cO5OcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:37:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:37:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:37:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:37:41 INFO - Downloading blessings-1.6.tar.gz 19:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:37:41 INFO - Installing collected packages: blessings 19:37:41 INFO - Running setup.py install for blessings 19:37:41 INFO - Successfully installed blessings 19:37:41 INFO - Cleaning up... 19:37:41 INFO - Return code: 0 19:37:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:37:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:37:41 INFO - Reading from file tmpfile_stdout 19:37:41 INFO - Current package versions: 19:37:41 INFO - argparse == 1.2.1 19:37:41 INFO - blessings == 1.6 19:37:41 INFO - blobuploader == 1.2.4 19:37:41 INFO - docopt == 0.6.1 19:37:41 INFO - functools32 == 3.2.3-2 19:37:41 INFO - jsonschema == 2.5.1 19:37:41 INFO - manifestparser == 1.1 19:37:41 INFO - mozInstall == 1.12 19:37:41 INFO - mozcrash == 0.17 19:37:41 INFO - mozdebug == 0.1 19:37:41 INFO - mozdevice == 0.48 19:37:41 INFO - mozfile == 1.2 19:37:41 INFO - mozhttpd == 0.7 19:37:41 INFO - mozinfo == 0.9 19:37:41 INFO - mozleak == 0.1 19:37:41 INFO - mozlog == 3.3 19:37:41 INFO - moznetwork == 0.27 19:37:41 INFO - mozprocess == 0.23 19:37:41 INFO - mozprofile == 0.28 19:37:41 INFO - mozrunner == 6.12 19:37:41 INFO - mozscreenshot == 0.1 19:37:41 INFO - mozsystemmonitor == 0.3 19:37:41 INFO - moztest == 0.7 19:37:41 INFO - mozversion == 1.4 19:37:41 INFO - psutil == 3.1.1 19:37:41 INFO - requests == 1.2.3 19:37:41 INFO - wsgiref == 0.1.2 19:37:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:41 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 0x7f97a06acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c168d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5760>, '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 0x2ca6660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6af0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:37:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:37:41 INFO - Using env: {'DISPLAY': ':0', 19:37:41 INFO - 'HOME': '/home/cltbld', 19:37:41 INFO - 'LANG': 'en_US.UTF-8', 19:37:41 INFO - 'LANGUAGE': 'en_US:en', 19:37:41 INFO - 'LOGNAME': 'cltbld', 19:37:41 INFO - 'MAIL': '/var/mail/cltbld', 19:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:41 INFO - 'MOZ_NO_REMOTE': '1', 19:37:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:37:41 INFO - 'NO_EM_RESTART': '1', 19:37:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:41 INFO - 'PWD': '/builds/slave/test', 19:37:41 INFO - 'SHELL': '/bin/bash', 19:37:41 INFO - 'SHLVL': '1', 19:37:41 INFO - 'TERM': 'linux', 19:37:41 INFO - 'TMOUT': '86400', 19:37:41 INFO - 'USER': 'cltbld', 19:37:41 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 19:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:41 INFO - '_': '/tools/buildbot/bin/python'} 19:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:37:42 INFO - Cleaning up... 19:37:42 INFO - Return code: 0 19:37:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:37:42 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 0x7f97a06acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c168d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5760>, '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 0x2ca6660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6af0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:37:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:37:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:37:42 INFO - Using env: {'DISPLAY': ':0', 19:37:42 INFO - 'HOME': '/home/cltbld', 19:37:42 INFO - 'LANG': 'en_US.UTF-8', 19:37:42 INFO - 'LANGUAGE': 'en_US:en', 19:37:42 INFO - 'LOGNAME': 'cltbld', 19:37:42 INFO - 'MAIL': '/var/mail/cltbld', 19:37:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:42 INFO - 'MOZ_NO_REMOTE': '1', 19:37:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:37:42 INFO - 'NO_EM_RESTART': '1', 19:37:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:37:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:42 INFO - 'PWD': '/builds/slave/test', 19:37:42 INFO - 'SHELL': '/bin/bash', 19:37:42 INFO - 'SHLVL': '1', 19:37:42 INFO - 'TERM': 'linux', 19:37:42 INFO - 'TMOUT': '86400', 19:37:42 INFO - 'USER': 'cltbld', 19:37:42 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 19:37:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:42 INFO - '_': '/tools/buildbot/bin/python'} 19:37:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:37:42 INFO - Cleaning up... 19:37:42 INFO - Return code: 0 19:37:42 INFO - Running post-action listener: _resource_record_post_action 19:37:42 INFO - Running post-action listener: _start_resource_monitoring 19:37:42 INFO - Starting resource monitoring. 19:37:42 INFO - [mozharness: 2016-08-16 02:37:42.276512Z] Finished create-virtualenv step (success) 19:37:42 INFO - [mozharness: 2016-08-16 02:37:42.276973Z] Running install step. 19:37:42 INFO - Running pre-action listener: _resource_record_pre_action 19:37:42 INFO - Running main action method: install 19:37:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:37:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:37:42 INFO - Reading from file tmpfile_stdout 19:37:42 INFO - Detecting whether we're running mozinstall >=1.0... 19:37:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:37:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:37:42 INFO - Reading from file tmpfile_stdout 19:37:42 INFO - Output received: 19:37:42 INFO - Usage: mozinstall [options] installer 19:37:42 INFO - Options: 19:37:42 INFO - -h, --help show this help message and exit 19:37:42 INFO - -d DEST, --destination=DEST 19:37:42 INFO - Directory to install application into. [default: 19:37:42 INFO - "/builds/slave/test"] 19:37:42 INFO - --app=APP Application being installed. [default: firefox] 19:37:42 INFO - mkdir: /builds/slave/test/build/application 19:37:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:37:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:37:59 INFO - Reading from file tmpfile_stdout 19:37:59 INFO - Output received: 19:37:59 INFO - /builds/slave/test/build/application/firefox/firefox 19:37:59 INFO - Running post-action listener: _resource_record_post_action 19:37:59 INFO - [mozharness: 2016-08-16 02:37:59.510538Z] Finished install step (success) 19:37:59 INFO - [mozharness: 2016-08-16 02:37:59.510788Z] Running run-tests step. 19:37:59 INFO - Running pre-action listener: _resource_record_pre_action 19:37:59 INFO - Running main action method: run_tests 19:37:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:37:59 INFO - Python 2.7.3 19:37:59 INFO - Return code: 0 19:37:59 INFO - grabbing minidump binary from tooltool 19:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:37:59 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 0x2ca5760>, '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 0x2ca6660>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6af0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:37:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:37:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:37:59 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:37:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:37:59 INFO - Return code: 0 19:37:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:37:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:37:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:37:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:37:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:37:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:37:59 INFO - Using env: {'DISPLAY': ':0', 19:37:59 INFO - 'HOME': '/home/cltbld', 19:37:59 INFO - 'LANG': 'en_US.UTF-8', 19:37:59 INFO - 'LANGUAGE': 'en_US:en', 19:37:59 INFO - 'LOGNAME': 'cltbld', 19:37:59 INFO - 'MAIL': '/var/mail/cltbld', 19:37:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:37:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:59 INFO - 'MOZ_NO_REMOTE': '1', 19:37:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:37:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:37:59 INFO - 'NO_EM_RESTART': '1', 19:37:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:37:59 INFO - 'PWD': '/builds/slave/test', 19:37:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:37:59 INFO - 'SHELL': '/bin/bash', 19:37:59 INFO - 'SHLVL': '1', 19:37:59 INFO - 'TERM': 'linux', 19:37:59 INFO - 'TMOUT': '86400', 19:37:59 INFO - 'USER': 'cltbld', 19:37:59 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811', 19:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:37:59 INFO - '_': '/tools/buildbot/bin/python'} 19:37:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:37:59 INFO - mozversion application_buildid: 20160815173003 19:37:59 INFO - mozversion application_changeset: 2697bf7ad45d0f70e4977aea25860052cc480a26 19:37:59 INFO - mozversion application_display_name: Nightly 19:37:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:37:59 INFO - mozversion application_name: Firefox 19:37:59 INFO - mozversion application_remotingname: firefox 19:37:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:37:59 INFO - mozversion application_vendor: Mozilla 19:37:59 INFO - mozversion application_version: 51.0a1 19:37:59 INFO - mozversion platform_buildid: 20160815173003 19:37:59 INFO - mozversion platform_changeset: 2697bf7ad45d0f70e4977aea25860052cc480a26 19:37:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:37:59 INFO - mozversion platform_version: 51.0a1 19:37:59 INFO - using testdate: 1471315079 19:37:59 INFO - actual date: 1471315079 19:37:59 INFO - starting webserver on 'localhost:46662' 19:37:59 INFO - SUITE-START | Running 2 tests 19:37:59 INFO - TEST-START | dromaeo_css 19:37:59 INFO - operating with platform_type : linux_ 19:37:59 INFO - Initialising browser for dromaeo_css test... 19:37:59 INFO - TEST-INFO | started process 7610 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUUlUx7/profile http://localhost:46662/getInfo.html) 19:38:03 INFO - PROCESS | 7610 | __metrics Screen width/height:1600/1200 19:38:03 INFO - PROCESS | 7610 | colorDepth:24 19:38:03 INFO - PROCESS | 7610 | Browser inner width/height: 1024/697 19:38:03 INFO - PROCESS | 7610 | __metrics 19:38:04 INFO - PROCESS | 7610 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:38:04 INFO - PROCESS | 7610 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:38:05 INFO - PROCESS | 7610 | console.error: PushService: 19:38:05 INFO - PROCESS | 7610 | stateChangeProcessEnqueue: Error transitioning state 19:38:05 INFO - PROCESS | 7610 | UnknownError 19:38:05 INFO - TEST-INFO | 7610: exit 0 19:38:05 INFO - Browser initialized. 19:38:05 INFO - Running cycle 1/1 for dromaeo_css test... 19:38:05 INFO - TEST-INFO | started process 7696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUUlUx7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:38:17 INFO - PROCESS | 7696 | RSS: Main: 155652096 19:38:17 INFO - PROCESS | 7696 | 19:39:08 INFO - PROCESS | 7696 | 1471315148936 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:41:18 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:46662/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46662/tests/dromaeo/cssquery-ext.html) 19:41:19 INFO - PROCESS | 7696 | RSS: Main: 271040512 19:41:19 INFO - PROCESS | 7696 | 19:44:22 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:46662/tests/dromaeo/cssquery-ext.html (next: http://localhost:46662/tests/dromaeo/cssquery-jquery.html) 19:44:22 INFO - PROCESS | 7696 | RSS: Main: 244977664 19:44:22 INFO - PROCESS | 7696 | 19:47:24 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:46662/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46662/tests/dromaeo/cssquery-mootools.html) 19:47:25 INFO - PROCESS | 7696 | RSS: Main: 224784384 19:47:25 INFO - PROCESS | 7696 | 19:50:27 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:46662/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46662/tests/dromaeo/cssquery-prototype.html) 19:50:28 INFO - PROCESS | 7696 | RSS: Main: 231251968 19:50:28 INFO - PROCESS | 7696 | 19:53:30 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:46662/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46662/tests/dromaeo/cssquery-yui.html) 19:53:31 INFO - PROCESS | 7696 | RSS: Main: 186261504 19:53:31 INFO - PROCESS | 7696 | 19:56:34 INFO - PROCESS | 7696 | Cycle 1(1): loaded http://localhost:46662/tests/dromaeo/cssquery-yui.html (next: http://localhost:46662/tests/dromaeo/cssquery-dojo.html) 19:56:34 INFO - PROCESS | 7696 | RSS: Main: 277925888 19:56:34 INFO - PROCESS | 7696 | 19:56:34 INFO - PROCESS | 7696 | __start_tp_report 19:56:34 INFO - PROCESS | 7696 | _x_x_mozilla_page_load 19:56:34 INFO - PROCESS | 7696 | _x_x_mozilla_page_load_details 19:56:34 INFO - PROCESS | 7696 | |i|pagename|runs| 19:56:34 INFO - PROCESS | 7696 | |0;dojo.html;2964.3478434744375;2983.8060526065806;3002.0243420888205;3007.338385555178;3015.336625942292;5191.792328306873;5219.739013049351;5235.842924712255;5332.093544097503;5387.865303367413;5282.2076688496745;5312.574994000481;5329.546988505982;5336.17289320155;5378.46215378462;4771.570558649721;4867.342908707333;4872.65891387603;4877.0489754497885;5056.039352523027;4955.1080805455185;4970.329005584236;5001.349824522807;5014.197728363462;5036.143855544567;4739.218029025224;4809.158397280462;4827.734474603895;4834.782434790408;4890.682105663138;4833.685810422347;4835.178019736618;4838.806447742086;4875.488073752276;4930.112579735646;4870.464248932614;4874.731889746063;4876.951230487721;4892.9510704892855;4940.827071052496;5149.45930677281;5255.553277971377;5313.495217954288;5324.973375133139;5342.118550439173;5651.632643878134;5674.631148975344;5714.885702285973;5720.370759216483;5739.483446489836;5766.567507436959;5774.220480235156;5776;5778.624389414675;5779.739911703983;5062.9746851266245;5138.58891288696;5244.737763111829;5304.469553044664;5379.246905433242;5871.178035075093;5880.8235752927485;5881.2942446906645;5887.528997680175;5895.292564892155;5632.408597097327;5638.379778224392;5646.887062258732;5656.519195868315;5657.6039677223025;5477.014137455217;5605.411431799603;5645.350784659735;5664.122061080539;5736.196932429463;5675.404082571353;5680.857978550486;5681.602287839812;5682.914756278659;5735.311762588516;5639.238702775155;5668.773249069991;5698.971505142531;5702.258706368146;5713.657180569179;4279.33670281107;4303.806328715215;4311.633511151587;4376.49670287921;4413.029133590605;3910.0420397002918;3936.842526298916;3943.9014024649614;3945.9605403946166;3946.2896678598127;5307.150855863043;5371.62398632092;5374.892502149935;5377.569794416438;5380.704061276668;5572.414896435897;5593.216949627055;5625.268715067097;5670.432956704297;5761.827145185651;5315.707636080053;5356.4107948125675;5358.651687640291;5361.222622719683;5363.436839131913;4936.234883593049;4943.233798761198;4958.876028099255;4964.751762411937;4981.32752078472;5782.826515204467;5866.530677545786;5904.232449781587;5921.319048309413;6009.549283803644;5737.311522617312;5739.368669446441;5742.28221472316;5742.885142297214;5751.396103409082;5941.732622032106;5947.256592925884;5954.702264886825;5963.075723262901;5973.790917317873;5358.46415358461;5553.333599967868;5613.073842815889;5613.354464236501;5725.7137143141845;5770.5095066919785;5780.826575202751;5785.161151633074;5785.479306862402;5797.797077102281;5604.271444712162;5633.0987042073075;5639.661620302795;5639.859003524781;5652.576056795675;5367.194920761917;5458.918116228339;5484.232207490874;5493.587980901529;5590.133529302964;5424.945750542602;5445.101558242845;5476.534494568006;5478.479544443271;5489;5589.860253493533;5597.944020559905;5638;5647.040003199548;5654.85862853432;5306.416294207557;5379.596530260168;5435.619506634498;5436.157395603688;5532.834014979557 19:56:34 INFO - PROCESS | 7696 | |1;ext.html;547.1956224350204;591.8668299632583;598.6438069348742;599.3586862057599;602.7137109872808;32398.352032959356;33013.66986330136;35630.2873942521;35638.2872342553;35713.28573428533;43196.35205471913;44239;44292.3356149658;48195.51804481954;48333.275000875015;8890.42212256203;9019.95490022549;9026.413283136608;9036.593353299084;9046;8890.155435233642;8974.596143173572;8977.371583989123;8983.95508022459;8986.23616992553;9039.593218305192;9077.273818094536;9082.54587270637;9083.31875109366;9083.727488175366;8943.686970956018;8998.730038098802;9004.954975225148;9018.970731585407;9023;8888.866667;8952.283817294601;8955.41789783662;8957.955210223972;8964.50695211763;42218.36672449885;42401;42503.7874810624;42522.57477425217;42540.36189457161;3213.373392188524;3245.432049391347;3293.3413317336626;3307.9173020674434;3447.172678557153;32243.677563224537;32498;32543;32576.348473030645;33539;4355.934660980088;4403.955960440387;4498.955010449887;4538.977305113453;4546.568076032771;358793;359098;359697;359821;360017;57741.13388299179;57757.11214439261;57823.4217657818;58234;58322.708386457794;60345;60900.08649870167;60920.69539652362;60960;61078.69460652757;13701.725965480635;14801.259937003322;15221.847781522265;15295.617609559627;15830.841691583168;12246.877531224753;12389.132760706845;12482.875171248172;12496.562620308427;12500.812487812691;17613.8295106882;17775.73336399955;17820.55448613769;17868.374606888698;17960.9101954492;4875.047142658639;5499.257600223918;5745.85635359111;5793.7392817323325;5873.559483038789;930.8510638297976;959.6209497248688;969.4609856517692;997.0926456924165;1016.9389836609827;31727.206819829687;31840.363192736015;31938.68061319357;31959.360812783612;32471.18822029468;1051;1117.5250518529592;1139.1399493382444;1139.3562637110006;1189.79178643737;15885.364585416453;16955.660886782196;17017.40439084651;17034.74447883283;17081.48755537336;576942;580452;580831;581245;584149;55826;55993;56053.15920261199;56091;56101;1185.0714880172202;1255.7488502299393;1256.1772039314264;1304.1588175626669;1336.6992426703914;31029.379412412076;32119;32834.835825820715;33113.233287858115;33177.66822331746;48921;49567.256491153385;49599.752001239765;49601.25598115959;49612.50387496223;399322;399483;400494;401318;401544;27497;28665;28706.282342940758;29784.55323170111;29918.70081299246;47040;47320;47472.52527474681;47558;48936.2659560114;39636.40545391879;39654.40518392284;39921.40117898292;40269;41850.1629967405;40551.3917291235;40879.18241635091;41401.1719765609;41528.58471415247;41828.3725744108;39917;40047.799761001006;40165.397519036655;40217.39673904952;40233.597664022986;33769;35470.46794298036;36060.81969590135;36574;36626;27068.052618158268;27217.31956701098;27562.78344954355;27808.143892080938;27965.580516291866 19:56:34 INFO - PROCESS | 7696 | |2;jquery.html;358.4104148675427;367.53323279435136;397.3860385704088;402.19159489426255;413.50999066106664;16557.91721041396;18620.813791862078;18853;19223.327183548543;19325;18557;18687.813121868778;18916.243350265973;19144.13851376688;19162.520936976583;5379.435159308275;5526.613137080406;5753.884922301551;5762.9135562966585;5766.0486019806685;5586.832395028156;5751.4536119068625;5759.712014399284;5760.222369980062;5761.135829625548;5555.611107222496;5742.569307301969;5759.049756790125;5760.942390576082;5762.3949485304065;5766.0486019806685;5788.855278618026;5795.1017592273265;5795.681237531934;5796.623219490741;5466.34403871534;5593.300837395325;5619.466150715675;5632.3804381518;5641.943580564182;11642.126840486913;11882.108841836896;11884;11884.821727674092;21694.566108677896;2251.1558165687866;2301.6547517872455;2538.6318983747437;2573.5882258838496;2832.9518078311157;9842.26183036268;10265.846012309747;10318.845217321747;11288.604898828584;14590.708185836223;1886.9150888210063;1908.4751693284459;1926.8940208288539;1936.9709454358197;1948.912298946551;56880.71559642217;80219.5989020057;80946.59526702388;83160.58419707924;83820;10891.292066015683;11020.283681560673;11026.18068018784;11239.494222759995;11476.655700329004;15599.376024958874;15621.218939053002;15631.60920976962;15636.609084772745;15640.843591564166;7561.697532098655;7662.195469475736;7869.1737367576725;8050.959745201237;8069.475484093515;7121.074260346226;7370.7788766336025;7387.889181662279;7581.962090189624;7588.582627955405;4482.8655140345845;4492.146492166478;4660.6970546914345;4766.926387021656;4902.926456103161;1942.5823447958758;1945.4260993007097;1964.695472201811;1982.0882394098876;2016.7176595276674;869.2915274051628;936.5925822267335;940.4451373689419;941.9811603768023;976.6728146071146;18514.16686249122;19674.40976770699;19845.801541984685;19926.30257940994;19968.30110946139;930.7393929699695;967.6468089147551;990.8910019897805;1009.7677534167183;1010.6513252927729;10240.385576865412;10817.513211905483;10851.240413171161;10923.453827308604;10946.945265273622;378718;381078.1353118822;385805;504064;511433;97017.02982970221;100646;100832.49583752181;102097.48951255345;102145.48927255317;950.6007476859736;974.668612671695;985.2512090811035;997.3467378866999;1002.04304888831;17385.739213912053;17434.30262789525;17974.730379044588;18017.189226484577;18477.630447391246;28579.571306430837;30251.243718907204;43897.561024389346;43921.78039109784;44604.77697611491;58919.70540147272;59461.702691486265;60641.39358606534;61501.69249153726;62744.686276568325;8206.22040906113;8216.507009579444;8356;8379.95810020946;8754.868676969723;7744.186860379579;11871.703207419883;12303.200291980993;12603.369831508204;13155.73688526205;10897.67306980792;11145.275557088764;11363.829542557034;11780.941095294469;12056.156069075081;11337.03635190985;11495.827562586735;11645.126615503701;11740.41297935117;11751.588694395998;11030.338179709242;11789.233699809485;11941.283522988648;11983.460744266355;12320.199187052813;8257.587120644064;8399.076101628938;8520.786980325542;8687.478751274944;8759.824803504021;10510.737231569272;11190;11423.257488263236;11571.942140289244;11611.88388116108 19:56:34 INFO - PROCESS | 7696 | |3;mootools.html;1304.6803533134384;1390.5272207449466;1416.1856932263947;1487.1374602730418;1650.6368598908243;8372.623231954562;8742.038375778659;8825.16160964708;9031.096890310982;9049.140331668497;8863.423877447978;8976.551172441368;8985.056569060253;8986.415882967602;8994.820103597924;2373.869437180959;2399.0883464283634;2399.1483023526657;2400.7359190489033;2407.626817843237;2360.280114565057;2391.378241657173;2398.82008849337;2408.554417432772;2411.397150712322;2354.5879471092576;2357.3752955466816;2369.763023697634;2372.240882917467;2376.6316220985773;2287.668288098233;2307.4231442139467;2311.248844125649;2315.073970411832;2326.906923723049;2287.7254729432407;2311.3874823171873;2311.838171328008;2319.130326127702;2319.153508969231;8736.912630873674;8901.109889011112;8979.910200897973;9009.459432434009;9015.639374425014;5112.233165025239;5240.187770895479;5303.920441193385;5312.840614781524;5315.495027972336;3551.147724546116;3552.5914519830294;3630.3828349180703;3641.927161456756;3642.5993140754495;1067.5943141606217;1086.5285413105132;1106.0709004436233;1123.2081382625229;1134.9773004539863;12271.75456490874;12321.753564928651;12377.195482293531;12389.194702344319;12416;4452.532484089189;4612.423447069116;4715.504871988461;4752.049590081998;4779.187538118459;9781.315307928518;9784.608615655436;9788.55951482185;9789;9790.363626364264;5335.973320133375;5365.570754339644;5379.919301210485;5380.2736630555155;5382.650127741685;4372.475302963665;4832.758362081881;4940.6788558743565;4970.801167953314;5030.170021946338;3724;3806.600306967784;3897.3569361055675;3939.955911683406;3954.762714237155;1165.0737663557493;1169.9528921615106;1176.7705297467082;1188.857337119534;1208.8186771984097;851.6380538271334;890.1943740914483;892.5046599137542;921.9585118669676;927.0312523413049;5525.143602741581;5606.719664016783;5669.6314739541795;5679.460451257124;5706.686132262683;449.2721790699088;455.4352602772538;461.71373748275613;465.6554149929028;465.995340046602;3799.2781371539318;3917.941230881539;3926.411038344214;3939.1136994176654;3951.604839516083;10599.629012984662;10694.572217111228;10729.409882456539;10830.675079747463;11388.430578471043;11054.060404865677;11124.054455371384;11128.054115400282;11128.109751219883;11134.220604557766;7760.495567788075;8152.225538573826;8237.711680091168;8238.670453181925;8239.093699693029;5896.852578685567;5920.496757775637;5976.282846058501;6058.939410605837;6204.720787564661;10167.949160254151;10535.262531622713;10543.367397956146;10629.734256643645;10706.143508519455;10836.403997780199;10878.619248326271;10885.510152043018;10887.020168184901;10911.618093366695;7073.115860517435;7548.282913123245;7642.273983971514;8071.313938315309;8212.958935205525;8656.48061116335;8832.249258813074;8834.293256539333;8839.955800221214;8844.46933184011;7465.776026719207;7492.363149132385;7534.849303014019;7682.539047657158;8036.156203598538;7738.342240909361;7781.766547003599;7787.766367008999;8111;8148.185181481805;7047.753328633473;7190.712371505081;7233.819154521179;7237.529560578546;7240.05879235696;5137.922931155961;5170.793168273227;5204.895902081861;5293.629445938747;5552.472515111058;5360.544353729977;5380.677159370451;5441.374241962225;5508.20131080983;5600.551955843604 19:56:34 INFO - PROCESS | 7696 | |4;prototype.html;306.3352525020705;318.7529664510005;324.16042450054357;325.8077734136859;326.2089433124672;5161.587073034158;5194.896102077956;5205.141151709968;5214.374275086991;5229.607779416549;4878.243872199815;5025.195968645017;5027.2459131130345;5028.094942910275;5147.584414286064;3928;3977.443157957888;3997.600239976008;4052.9797351013353;4086.652634526069;4041.8585349512773;4049.2913740095364;4050.797460126982;4051.9392209116886;4070.1859628074503;3849.403342481906;3983.8089051021966;4016.377461493473;4019.919601607966;4054.3918412238104;3993.201359728037;3997.700172487075;4015.4378387025695;4020.5778393268724;4026.798660067014;3503.8423270952867;3516.384632689269;3519;3530.894073177809;3534.2578058607724;4927.334809800667;5097.923531147036;5101;5124.590737547167;5125.974370128163;4525.275955847082;4554.681172317939;4558.065596552732;4559.3388958601145;4592.724436533818;5363;5385.973070134624;5398.623351045498;5405.162199859028;5415.783368665248;3816.484774555427;3839.308924393608;3847.1343947611845;3854.2484215577974;3870.748401353931;5386.434424385461;5576.972115139399;5610.579206559483;5633.15502674598;5635.1828984796975;3910.432987216838;3919.2161567686576;3924.529056513237;3945.171513982096;3952.3873799561115;3978.482797236341;3989.1024519482885;3997.8400863965117;4001.1197536541913;4001.7598944063448;4470.6870519064005;4471.441069866267;4527.8641640750175;4541.296099104644;4606.907861842745;5394.676319420769;5405.621606487587;5418.8916221675345;5424.105022671294;5425.674459532441;1322.7235717532628;1355.7762969109986;1363.4341748174397;1371.4582739817915;1372.9176249425066;915.3729695158837;923.35826600512;927.7402327348353;933.2953620016843;942.4251206763869;5209.192575150858;5222.399424066205;5248.343957005374;5265;5280.81517146898;5528.75120619573;5555.2222688823595;5567.137093750475;5570.136628822539;5586.31856708782;5055.014272216956;5286.735663216824;5293.3647962244;5297.417284098746;5332.600054995891;2936.662283837386;2978.776591755627;2981.5080511715323;2982.9701702983125;2983.8657260423333;5648.378678345379;5712.285964254468;5766.192733017381;5801.7099145042575;5814.127880817911;5650.773969041193;5655.236543066633;5662.546996240291;5685;5699.5155411790465;5718.771249150071;5719.571032172604;5725.713714314267;5760.222369979999;5784.537237020944;5425.321834770653;5482.23248745168;5518.972405137949;5556.527695145958;5579.2746942897165;5480.424555421783;5495.340559132929;5515.862103447446;5539.7784088636;5575.884823035328;5640.85897852557;5784.82645520635;5812.476877081083;5828.6211396259105;5837.737301821513;3896.551896531935;3945.171513982096;3964.980175099106;3965.294923183059;3999.3001224784966;5583.888322233452;5590.239074691459;5596.272484576979;5617.522510586646;5617.6910269933915;5263.789448422173;5414.75633596497;5444.373897001896;5486.835394938158;5506.807261745819;5385.0037743017665;5399.892002160013;5404.027275090364;5404.243405923095;5424.647397919122;5162.8967420652125;5177.8187763428095;5319.973400132974;5338.733063346895;5364.834747498839;5400.62195646301;5408.107662235687;5418.756155972912;5425.755840987086;5429.37118864347;5298.94701053;5329.108166600956;5338.97330513345;5340.75966581497;5371.167469042305 19:56:34 INFO - PROCESS | 7696 | |5;yui.html;682.8088135322108;701.7894631610517;715.2597062040786;736.7716008037509;786.3473317146769;13310.401031953574;14124.788128178085;14144.858551414483;14172.149671019746;15144.469943551978;15282.159481228557;15390.69218615627;15395.538133855975;15401.768973465407;15408.30662620182;734.8126227811928;735.6910097758954;738.6233021159194;739.2792027772932;743.0080842075836;625.6746491824249;728.4936968806655;731.956082635041;739.6227923758895;740.1192580828823;789.9368050555942;791.2087912087912;791.2720297326473;794.0431779705441;794.3526026288586;786.6224212378062;789.7867575754572;790.276896639577;790.6600161930368;790.7983464216645;474.80770288033403;553.3028384235852;555.3197333266716;560.0031943141215;566.0207840436069;13992;14045.43818247269;14079.577612671636;14081.64795880101;14082.718345633133;13524.93237533806;14181.503647372292;14483.058601190889;14595.562133136023;14670.926645366704;12305.569305074368;12315.384230788515;12401.22673646146;12550.97282441537;13032.108885393447;4515.345274935116;4518.028623845889;4628.421447319085;4635.2120139576355;4755.2153894607345;83484.58257708733;84102;86434;87585.99999999936;89459.55270223672;10780.51487683056;10784.514696838662;10893.509792059376;11050;11095.44522773858;20848.37454876356;20852.270170543958;20879.478013049797;20880.477988050116;20901.581968360548;7659.272369124924;7754.612269386508;7821.92178078212;7858.764237072897;8268.37987150966;4311.48262208537;4454.866354009385;4532.637389008872;4540.455145382575;4674.415698037746;3332.8333583320737;3434.1242983039165;3700.1674623209565;3866;3872.3223435898603;638.9744410223632;643.4209211709499;654.7446495866616;660.0561197487543;672.0993868216614;463.48090139043813;567.6565677764976;603.4960807725511;633.3508154141949;675.2000317274634;8876.822463550821;10157.746056348651;10163.832095633958;10298.845517317246;10383.896161038445;662.6421732264523;695.4054283587561;696.3350000749273;700.7512333121771;718.7951433841434;4318.546552611993;4721.858344249678;4735.12400205963;4735.621150307967;4759.857204283877;73035.26964730391;108257.4587127075;126443;129080.3545982264;132049;106365.46817265864;109430.45284773526;109734;111015.44492277487;112428.43785781182;93717.53141234386;94470;95312.52343738376;96061.5196924025;96477.5176124115;9402.764930876509;9448.669296574863;9462.905370946202;9494.810103798023;9648.228141748783;52598.737006314725;53091;53094.203586947;53474.46525534695;55603.44396556145;75380.62309688635;76728.61635691786;81942.59028704818;82587.58706206671;83341;13161.73676526445;13190.868091318964;13461.798073029107;13493.797593035915;14134.293285335898;14840.338630625107;15350.07899526032;15682.686346272783;15995.200239988188;16027.919860400623;13305.534306299232;13878.375473103937;14009.579712608638;14438.639034024234;15014.324355403814;14099.060461590698;14207.218602976936;14444.638884027983;15129.848701512845;15222.16278104715;14130.293485325486;14157.858421416066;14171.149731016172;14288.785668215192;14566.85433145655;10854.511546980248;11265.605703800655;11599.8260026098;11742.354170520703;12112.426834084143;11165.553377864795;11932.463039163085;12162.817557736464;12406.441710123247;12463.31451770125 19:56:34 INFO - PROCESS | 7696 | __end_tp_report 19:56:34 INFO - PROCESS | 7696 | __start_cc_report 19:56:34 INFO - PROCESS | 7696 | _x_x_mozilla_cycle_collect,1058 19:56:34 INFO - PROCESS | 7696 | __end_cc_report 19:56:34 INFO - PROCESS | 7696 | __startTimestamp1471316194859__endTimestamp 19:56:34 INFO - PROCESS | 7696 | 19:56:34 INFO - PROCESS | 7696 | ------- Summary: start ------- 19:56:34 INFO - PROCESS | 7696 | Number of tests: 6 19:56:34 INFO - PROCESS | 7696 | 19:56:34 INFO - PROCESS | 7696 | [#0] dojo.html Cycles:165 Average:5294.66 Median:5445.10 stddev:607.99 (11.2%) stddev-sans-first:581.72 19:56:34 INFO - PROCESS | 7696 | Values: 2964.3 2983.8 3002.0 3007.3 3015.3 5191.8 5219.7 5235.8 5332.1 5387.9 5282.2 5312.6 5329.5 5336.2 5378.5 4771.6 4867.3 4872.7 4877.0 5056.0 4955.1 4970.3 5001.3 5014.2 5036.1 4739.2 4809.2 4827.7 4834.8 4890.7 4833.7 4835.2 4838.8 4875.5 4930.1 4870.5 4874.7 4877.0 4893.0 4940.8 5149.5 5255.6 5313.5 5325.0 5342.1 5651.6 5674.6 5714.9 5720.4 5739.5 5766.6 5774.2 5776.0 5778.6 5779.7 5063.0 5138.6 5244.7 5304.5 5379.2 5871.2 5880.8 5881.3 5887.5 5895.3 5632.4 5638.4 5646.9 5656.5 5657.6 5477.0 5605.4 5645.4 5664.1 5736.2 5675.4 5680.9 5681.6 5682.9 5735.3 5639.2 5668.8 5699.0 5702.3 5713.7 4279.3 4303.8 4311.6 4376.5 4413.0 3910.0 3936.8 3943.9 3946.0 3946.3 5307.2 5371.6 5374.9 5377.6 5380.7 5572.4 5593.2 5625.3 5670.4 5761.8 5315.7 5356.4 5358.7 5361.2 5363.4 4936.2 4943.2 4958.9 4964.8 4981.3 5782.8 5866.5 5904.2 5921.3 6009.5 5737.3 5739.4 5742.3 5742.9 5751.4 5941.7 5947.3 5954.7 5963.1 5973.8 5358.5 5553.3 5613.1 5613.4 5725.7 5770.5 5780.8 5785.2 5785.5 5797.8 5604.3 5633.1 5639.7 5639.9 5652.6 5367.2 5458.9 5484.2 5493.6 5590.1 5424.9 5445.1 5476.5 5478.5 5489.0 5589.9 5597.9 5638.0 5647.0 5654.9 5306.4 5379.6 5435.6 5436.2 5532.8 19:56:34 INFO - PROCESS | 7696 | 19:56:34 INFO - PROCESS | 7696 | [#1] ext.html Cycles:180 Average:60286.33 Median:40715.29 stddev:121487.37 (298.4%) stddev-sans-first:121745.38 19:56:34 INFO - PROCESS | 7696 | Values: 547.2 591.9 598.6 599.4 602.7 32398.4 33013.7 35630.3 35638.3 35713.3 43196.4 44239.0 44292.3 48195.5 48333.3 8890.4 9020.0 9026.4 9036.6 9046.0 8890.2 8974.6 8977.4 8984.0 8986.2 9039.6 9077.3 9082.5 9083.3 9083.7 8943.7 8998.7 9005.0 9019.0 9023.0 8888.9 8952.3 8955.4 8958.0 8964.5 42218.4 42401.0 42503.8 42522.6 42540.4 3213.4 3245.4 3293.3 3307.9 3447.2 32243.7 32498.0 32543.0 32576.3 33539.0 4355.9 4404.0 4499.0 4539.0 4546.6 358793.0 359098.0 359697.0 359821.0 360017.0 57741.1 57757.1 57823.4 58234.0 58322.7 60345.0 60900.1 60920.7 60960.0 61078.7 13701.7 14801.3 15221.8 15295.6 15830.8 12246.9 12389.1 12482.9 12496.6 12500.8 17613.8 17775.7 17820.6 17868.4 17960.9 4875.0 5499.3 5745.9 5793.7 5873.6 930.9 959.6 969.5 997.1 1016.9 31727.2 31840.4 31938.7 31959.4 32471.2 1051.0 1117.5 1139.1 1139.4 1189.8 15885.4 16955.7 17017.4 17034.7 17081.5 576942.0 580452.0 580831.0 581245.0 584149.0 55826.0 55993.0 56053.2 56091.0 56101.0 1185.1 1255.7 1256.2 1304.2 1336.7 31029.4 32119.0 32834.8 33113.2 33177.7 48921.0 49567.3 49599.8 49601.3 49612.5 399322.0 399483.0 400494.0 401318.0 401544.0 27497.0 28665.0 28706.3 29784.6 29918.7 47040.0 47320.0 47472.5 47558.0 48936.3 39636.4 39654.4 39921.4 40269.0 41850.2 40551.4 40879.2 41401.2 41528.6 41828.4 39917.0 40047.8 40165.4 40217.4 40233.6 33769.0 35470.5 36060.8 36574.0 36626.0 27068.1 27217.3 27562.8 27808.1 27965.6 19:56:34 INFO - PROCESS | 7696 | 19:56:34 INFO - PROCESS | 7696 | [#2] jquery.html Cycles:180 Average:27186.39 Median:29415.41 stddev:72604.26 (246.8%) stddev-sans-first:72779.99 19:56:34 INFO - PROCESS | 7696 | Values: 358.4 367.5 397.4 402.2 413.5 16557.9 18620.8 18853.0 19223.3 19325.0 18557.0 18687.8 18916.2 19144.1 19162.5 5379.4 5526.6 5753.9 5762.9 5766.0 5586.8 5751.5 5759.7 5760.2 5761.1 5555.6 5742.6 5759.0 5760.9 5762.4 5766.0 5788.9 5795.1 5795.7 5796.6 5466.3 5593.3 5619.5 5632.4 5641.9 11642.1 11882.1 11884.0 11884.8 21694.6 2251.2 2301.7 2538.6 2573.6 2833.0 9842.3 10265.8 10318.8 11288.6 14590.7 1886.9 1908.5 1926.9 1937.0 1948.9 56880.7 80219.6 80946.6 83160.6 83820.0 10891.3 11020.3 11026.2 11239.5 11476.7 15599.4 15621.2 15631.6 15636.6 15640.8 7561.7 7662.2 7869.2 8051.0 8069.5 7121.1 7370.8 7387.9 7582.0 7588.6 4482.9 4492.1 4660.7 4766.9 4902.9 1942.6 1945.4 1964.7 1982.1 2016.7 869.3 936.6 940.4 942.0 976.7 18514.2 19674.4 19845.8 19926.3 19968.3 930.7 967.6 990.9 1009.8 1010.7 10240.4 10817.5 10851.2 10923.5 10946.9 378718.0 381078.1 385805.0 504064.0 511433.0 97017.0 100646.0 100832.5 102097.5 102145.5 950.6 974.7 985.3 997.3 1002.0 17385.7 17434.3 17974.7 18017.2 18477.6 28579.6 30251.2 43897.6 43921.8 44604.8 58919.7 59461.7 60641.4 61501.7 62744.7 8206.2 8216.5 8356.0 8380.0 8754.9 7744.2 11871.7 12303.2 12603.4 13155.7 10897.7 11145.3 11363.8 11780.9 12056.2 11337.0 11495.8 11645.1 11740.4 11751.6 11030.3 11789.2 11941.3 11983.5 12320.2 8257.6 8399.1 8520.8 8687.5 8759.8 10510.7 11190.0 11423.3 11571.9 11611.9 19:56:34 INFO - PROCESS | 7696 | 19:56:34 INFO - PROCESS | 7696 | [#3] mootools.html Cycles:180 Average:5842.12 Median:4805.97 stddev:3373.46 (70.2%) stddev-sans-first:3365.69 19:56:34 INFO - PROCESS | 7696 | Values: 1304.7 1390.5 1416.2 1487.1 1650.6 8372.6 8742.0 8825.2 9031.1 9049.1 8863.4 8976.6 8985.1 8986.4 8994.8 2373.9 2399.1 2399.1 2400.7 2407.6 2360.3 2391.4 2398.8 2408.6 2411.4 2354.6 2357.4 2369.8 2372.2 2376.6 2287.7 2307.4 2311.2 2315.1 2326.9 2287.7 2311.4 2311.8 2319.1 2319.2 8736.9 8901.1 8979.9 9009.5 9015.6 5112.2 5240.2 5303.9 5312.8 5315.5 3551.1 3552.6 3630.4 3641.9 3642.6 1067.6 1086.5 1106.1 1123.2 1135.0 12271.8 12321.8 12377.2 12389.2 12416.0 4452.5 4612.4 4715.5 4752.0 4779.2 9781.3 9784.6 9788.6 9789.0 9790.4 5336.0 5365.6 5379.9 5380.3 5382.7 4372.5 4832.8 4940.7 4970.8 5030.2 3724.0 3806.6 3897.4 3940.0 3954.8 1165.1 1170.0 1176.8 1188.9 1208.8 851.6 890.2 892.5 922.0 927.0 5525.1 5606.7 5669.6 5679.5 5706.7 449.3 455.4 461.7 465.7 466.0 3799.3 3917.9 3926.4 3939.1 3951.6 10599.6 10694.6 10729.4 10830.7 11388.4 11054.1 11124.1 11128.1 11128.1 11134.2 7760.5 8152.2 8237.7 8238.7 8239.1 5896.9 5920.5 5976.3 6058.9 6204.7 10167.9 10535.3 10543.4 10629.7 10706.1 10836.4 10878.6 10885.5 10887.0 10911.6 7073.1 7548.3 7642.3 8071.3 8213.0 8656.5 8832.2 8834.3 8840.0 8844.5 7465.8 7492.4 7534.8 7682.5 8036.2 7738.3 7781.8 7787.8 8111.0 8148.2 7047.8 7190.7 7233.8 7237.5 7240.1 5137.9 5170.8 5204.9 5293.6 5552.5 5360.5 5380.7 5441.4 5508.2 5600.6 19:56:34 INFO - PROCESS | 7696 | 19:56:34 INFO - PROCESS | 7696 | [#4] prototype.html Cycles:180 Average:4559.01 Median:5256.07 stddev:1346.63 (25.6%) stddev-sans-first:1312.03 19:56:34 INFO - PROCESS | 7696 | Values: 306.3 318.8 324.2 325.8 326.2 5161.6 5194.9 5205.1 5214.4 5229.6 4878.2 5025.2 5027.2 5028.1 5147.6 3928.0 3977.4 3997.6 4053.0 4086.7 4041.9 4049.3 4050.8 4051.9 4070.2 3849.4 3983.8 4016.4 4019.9 4054.4 3993.2 3997.7 4015.4 4020.6 4026.8 3503.8 3516.4 3519.0 3530.9 3534.3 4927.3 5097.9 5101.0 5124.6 5126.0 4525.3 4554.7 4558.1 4559.3 4592.7 5363.0 5386.0 5398.6 5405.2 5415.8 3816.5 3839.3 3847.1 3854.2 3870.7 5386.4 5577.0 5610.6 5633.2 5635.2 3910.4 3919.2 3924.5 3945.2 3952.4 3978.5 3989.1 3997.8 4001.1 4001.8 4470.7 4471.4 4527.9 4541.3 4606.9 5394.7 5405.6 5418.9 5424.1 5425.7 1322.7 1355.8 1363.4 1371.5 1372.9 915.4 923.4 927.7 933.3 942.4 5209.2 5222.4 5248.3 5265.0 5280.8 5528.8 5555.2 5567.1 5570.1 5586.3 5055.0 5286.7 5293.4 5297.4 5332.6 2936.7 2978.8 2981.5 2983.0 2983.9 5648.4 5712.3 5766.2 5801.7 5814.1 5650.8 5655.2 5662.5 5685.0 5699.5 5718.8 5719.6 5725.7 5760.2 5784.5 5425.3 5482.2 5519.0 5556.5 5579.3 5480.4 5495.3 5515.9 5539.8 5575.9 5640.9 5784.8 5812.5 5828.6 5837.7 3896.6 3945.2 3965.0 3965.3 3999.3 5583.9 5590.2 5596.3 5617.5 5617.7 5263.8 5414.8 5444.4 5486.8 5506.8 5385.0 5399.9 5404.0 5404.2 5424.6 5162.9 5177.8 5320.0 5338.7 5364.8 5400.6 5408.1 5418.8 5425.8 5429.4 5298.9 5329.1 5339.0 5340.8 5371.2 19:56:34 INFO - PROCESS | 7696 | 19:56:34 INFO - PROCESS | 7696 | [#5] yui.html Cycles:180 Average:21871.71 Median:4485.11 stddev:32258.77 (719.2%) stddev-sans-first:32310.03 19:56:34 INFO - PROCESS | 7696 | Values: 682.8 701.8 715.3 736.8 786.3 13310.4 14124.8 14144.9 14172.1 15144.5 15282.2 15390.7 15395.5 15401.8 15408.3 734.8 735.7 738.6 739.3 743.0 625.7 728.5 732.0 739.6 740.1 789.9 791.2 791.3 794.0 794.4 786.6 789.8 790.3 790.7 790.8 474.8 553.3 555.3 560.0 566.0 13992.0 14045.4 14079.6 14081.6 14082.7 13524.9 14181.5 14483.1 14595.6 14670.9 12305.6 12315.4 12401.2 12551.0 13032.1 4515.3 4518.0 4628.4 4635.2 4755.2 83484.6 84102.0 86434.0 87586.0 89459.6 10780.5 10784.5 10893.5 11050.0 11095.4 20848.4 20852.3 20879.5 20880.5 20901.6 7659.3 7754.6 7821.9 7858.8 8268.4 4311.5 4454.9 4532.6 4540.5 4674.4 3332.8 3434.1 3700.2 3866.0 3872.3 639.0 643.4 654.7 660.1 672.1 463.5 567.7 603.5 633.4 675.2 8876.8 10157.7 10163.8 10298.8 10383.9 662.6 695.4 696.3 700.8 718.8 4318.5 4721.9 4735.1 4735.6 4759.9 73035.3 108257.5 126443.0 129080.4 132049.0 106365.5 109430.5 109734.0 111015.4 112428.4 93717.5 94470.0 95312.5 96061.5 96477.5 9402.8 9448.7 9462.9 9494.8 9648.2 52598.7 53091.0 53094.2 53474.5 55603.4 75380.6 76728.6 81942.6 82587.6 83341.0 13161.7 13190.9 13461.8 13493.8 14134.3 14840.3 15350.1 15682.7 15995.2 16027.9 13305.5 13878.4 14009.6 14438.6 15014.3 14099.1 14207.2 14444.6 15129.8 15222.2 14130.3 14157.9 14171.1 14288.8 14566.9 10854.5 11265.6 11599.8 11742.4 12112.4 11165.6 11932.5 12162.8 12406.4 12463.3 19:56:34 INFO - PROCESS | 7696 | -------- Summary: end -------- 19:56:34 INFO - PROCESS | 7696 | 19:56:35 INFO - TEST-INFO | 7696: exit 0 19:56:35 INFO - TEST-OK | dromaeo_css | took 1115840ms 19:56:35 INFO - TEST-START | kraken 19:56:35 INFO - operating with platform_type : linux_ 19:56:35 INFO - Initialising browser for kraken test... 19:56:35 INFO - TEST-INFO | started process 8003 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7kdO3a/profile http://localhost:46662/getInfo.html) 19:56:39 INFO - PROCESS | 8003 | __metrics Screen width/height:1600/1200 19:56:39 INFO - PROCESS | 8003 | colorDepth:24 19:56:39 INFO - PROCESS | 8003 | Browser inner width/height: 1024/697 19:56:39 INFO - PROCESS | 8003 | __metrics 19:56:40 INFO - PROCESS | 8003 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:56:40 INFO - PROCESS | 8003 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:56:41 INFO - PROCESS | 8003 | console.error: PushService: 19:56:41 INFO - PROCESS | 8003 | stateChangeProcessEnqueue: Error transitioning state 19:56:41 INFO - PROCESS | 8003 | UnknownError 19:56:41 INFO - TEST-INFO | 8003: exit 0 19:56:41 INFO - Browser initialized. 19:56:41 INFO - Running cycle 1/1 for kraken test... 19:56:41 INFO - TEST-INFO | started process 8089 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7kdO3a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:56:53 INFO - PROCESS | 8089 | RSS: Main: 155566080 19:56:53 INFO - PROCESS | 8089 | 19:56:55 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46662/tests/kraken/driver.html?testName=audio-beat-detection) 19:56:56 INFO - PROCESS | 8089 | RSS: Main: 204214272 19:56:56 INFO - PROCESS | 8089 | 19:56:59 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46662/tests/kraken/driver.html?testName=audio-dft) 19:56:59 INFO - PROCESS | 8089 | RSS: Main: 194838528 19:56:59 INFO - PROCESS | 8089 | 19:57:03 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46662/tests/kraken/driver.html?testName=audio-fft) 19:57:03 INFO - PROCESS | 8089 | RSS: Main: 275918848 19:57:03 INFO - PROCESS | 8089 | 19:57:05 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46662/tests/kraken/driver.html?testName=audio-oscillator) 19:57:06 INFO - PROCESS | 8089 | RSS: Main: 186200064 19:57:06 INFO - PROCESS | 8089 | 19:57:08 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46662/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:57:09 INFO - PROCESS | 8089 | RSS: Main: 190496768 19:57:09 INFO - PROCESS | 8089 | 19:57:12 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46662/tests/kraken/driver.html?testName=imaging-darkroom) 19:57:12 INFO - PROCESS | 8089 | RSS: Main: 225878016 19:57:12 INFO - PROCESS | 8089 | 19:57:16 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46662/tests/kraken/driver.html?testName=imaging-desaturate) 19:57:16 INFO - PROCESS | 8089 | RSS: Main: 225808384 19:57:16 INFO - PROCESS | 8089 | 19:57:19 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46662/tests/kraken/driver.html?testName=json-parse-financial) 19:57:20 INFO - PROCESS | 8089 | RSS: Main: 226054144 19:57:20 INFO - PROCESS | 8089 | 19:57:21 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46662/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:57:22 INFO - PROCESS | 8089 | RSS: Main: 188952576 19:57:22 INFO - PROCESS | 8089 | 19:57:24 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46662/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:57:24 INFO - PROCESS | 8089 | RSS: Main: 196530176 19:57:24 INFO - PROCESS | 8089 | 19:57:26 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46662/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:57:27 INFO - PROCESS | 8089 | RSS: Main: 194277376 19:57:27 INFO - PROCESS | 8089 | 19:57:29 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46662/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:57:30 INFO - PROCESS | 8089 | RSS: Main: 187793408 19:57:30 INFO - PROCESS | 8089 | 19:57:33 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46662/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:57:33 INFO - PROCESS | 8089 | RSS: Main: 191668224 19:57:33 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | Cycle 1(1): loaded http://localhost:46662/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46662/tests/kraken/driver.html?testName=ai-astar) 19:57:35 INFO - PROCESS | 8089 | RSS: Main: 242995200 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | __start_tp_report 19:57:35 INFO - PROCESS | 8089 | _x_x_mozilla_page_load 19:57:35 INFO - PROCESS | 8089 | _x_x_mozilla_page_load_details 19:57:35 INFO - PROCESS | 8089 | |i|pagename|runs| 19:57:35 INFO - PROCESS | 8089 | |0;ai-astar;104;113;112;111;105;111;112;110;110;109 19:57:35 INFO - PROCESS | 8089 | |1;audio-beat-detection;142;117;118;185;117;117;154;116;149;120 19:57:35 INFO - PROCESS | 8089 | |2;audio-dft;159;159;160;160;162;162;162;162;162;162 19:57:35 INFO - PROCESS | 8089 | |3;audio-fft;74;75;75;111;75;75;74;75;108;76 19:57:35 INFO - PROCESS | 8089 | |4;audio-oscillator;83;85;135;85;85;84;120;85;84;139 19:57:35 INFO - PROCESS | 8089 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;116 19:57:35 INFO - PROCESS | 8089 | |6;imaging-darkroom;146;147;146;146;147;146;146;146;146;146 19:57:35 INFO - PROCESS | 8089 | |7;imaging-desaturate;95;95;94;94;94;95;94;95;95;94 19:57:35 INFO - PROCESS | 8089 | |8;json-parse-financial;52;51;52;51;51;53;53;52;52;50 19:57:35 INFO - PROCESS | 8089 | |9;json-stringify-tinderbox;62;63;62;62;63;63;63;63;62;63 19:57:35 INFO - PROCESS | 8089 | |10;stanford-crypto-aes;79;78;86;78;81;78;77;81;77;79 19:57:35 INFO - PROCESS | 8089 | |11;stanford-crypto-ccm;124;121;132;138;128;123;135;121;144;119 19:57:35 INFO - PROCESS | 8089 | |12;stanford-crypto-pbkdf2;162;160;162;162;160;159;160;165;165;162 19:57:35 INFO - PROCESS | 8089 | |13;stanford-crypto-sha256-iterative;66;68;64;65;64;63;72;64;63;63 19:57:35 INFO - PROCESS | 8089 | __end_tp_report 19:57:35 INFO - PROCESS | 8089 | __start_cc_report 19:57:35 INFO - PROCESS | 8089 | _x_x_mozilla_cycle_collect,1684 19:57:35 INFO - PROCESS | 8089 | __end_cc_report 19:57:35 INFO - PROCESS | 8089 | __startTimestamp1471316255852__endTimestamp 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | ------- Summary: start ------- 19:57:35 INFO - PROCESS | 8089 | Number of tests: 14 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:2.98 (2.7%) stddev-sans-first:2.35 19:57:35 INFO - PROCESS | 8089 | Values: 104.0 113.0 112.0 111.0 105.0 111.0 112.0 110.0 110.0 109.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#1] audio-beat-detection Cycles:10 Average:133.50 Median:131.00 stddev:23.41 (17.9%) stddev-sans-first:24.62 19:57:35 INFO - PROCESS | 8089 | Values: 142.0 117.0 118.0 185.0 117.0 117.0 154.0 116.0 149.0 120.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#2] audio-dft Cycles:10 Average:161.00 Median:162.00 stddev:1.33 (0.8%) stddev-sans-first:1.20 19:57:35 INFO - PROCESS | 8089 | Values: 159.0 159.0 160.0 160.0 162.0 162.0 162.0 162.0 162.0 162.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#3] audio-fft Cycles:10 Average:81.80 Median:75.00 stddev:14.63 (19.5%) stddev-sans-first:15.24 19:57:35 INFO - PROCESS | 8089 | Values: 74.0 75.0 75.0 111.0 75.0 75.0 74.0 75.0 108.0 76.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#4] audio-oscillator Cycles:10 Average:98.50 Median:84.50 stddev:23.15 (27.4%) stddev-sans-first:23.87 19:57:35 INFO - PROCESS | 8089 | Values: 83.0 85.0 135.0 85.0 85.0 84.0 120.0 85.0 84.0 139.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:57:35 INFO - PROCESS | 8089 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 19:57:35 INFO - PROCESS | 8089 | Values: 146.0 147.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 19:57:35 INFO - PROCESS | 8089 | Values: 95.0 95.0 94.0 94.0 94.0 95.0 94.0 95.0 95.0 94.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:0.95 (1.8%) stddev-sans-first:1.00 19:57:35 INFO - PROCESS | 8089 | Values: 52.0 51.0 52.0 51.0 51.0 53.0 53.0 52.0 52.0 50.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:63.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 19:57:35 INFO - PROCESS | 8089 | Values: 62.0 63.0 62.0 62.0 63.0 63.0 63.0 63.0 62.0 63.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:79.00 stddev:2.72 (3.4%) stddev-sans-first:2.88 19:57:35 INFO - PROCESS | 8089 | Values: 79.0 78.0 86.0 78.0 81.0 78.0 77.0 81.0 77.0 79.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#11] stanford-crypto-ccm Cycles:10 Average:128.50 Median:130.00 stddev:8.42 (6.5%) stddev-sans-first:8.77 19:57:35 INFO - PROCESS | 8089 | Values: 124.0 121.0 132.0 138.0 128.0 123.0 135.0 121.0 144.0 119.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.70 Median:162.00 stddev:2.06 (1.3%) stddev-sans-first:2.18 19:57:35 INFO - PROCESS | 8089 | Values: 162.0 160.0 162.0 162.0 160.0 159.0 160.0 165.0 165.0 162.0 19:57:35 INFO - PROCESS | 8089 | 19:57:35 INFO - PROCESS | 8089 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:64.50 stddev:2.86 (4.4%) stddev-sans-first:3.02 19:57:35 INFO - PROCESS | 8089 | Values: 66.0 68.0 64.0 65.0 64.0 63.0 72.0 64.0 63.0 63.0 19:57:35 INFO - PROCESS | 8089 | -------- Summary: end -------- 19:57:35 INFO - PROCESS | 8089 | 19:57:36 INFO - TEST-INFO | 8089: exit 0 19:57:36 INFO - TEST-OK | kraken | took 61244ms 19:57:36 INFO - SUITE-END | took 1177s 19:57:37 INFO - Completed test suite (00:19:37) 19:57:37 INFO - javascript benchmark 19:57:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2964.3478434744375, 2983.8060526065806, 3002.0243420888205, 3007.338385555178, 3015.336625942292, 5191.792328306873, 5219.739013049351, 5235.842924712255, 5332.093544097503, 5387.865303367413, 5282.2076688496745, 5312.574994000481, 5329.546988505982, 5336.17289320155, 5378.46215378462, 4771.570558649721, 4867.342908707333, 4872.65891387603, 4877.0489754497885, 5056.039352523027, 4955.1080805455185, 4970.329005584236, 5001.349824522807, 5014.197728363462, 5036.143855544567, 4739.218029025224, 4809.158397280462, 4827.734474603895, 4834.782434790408, 4890.682105663138, 4833.685810422347, 4835.178019736618, 4838.806447742086, 4875.488073752276, 4930.112579735646, 4870.464248932614, 4874.731889746063, 4876.951230487721, 4892.9510704892855, 4940.827071052496, 5149.45930677281, 5255.553277971377, 5313.495217954288, 5324.973375133139, 5342.118550439173, 5651.632643878134, 5674.631148975344, 5714.885702285973, 5720.370759216483, 5739.483446489836, 5766.567507436959, 5774.220480235156, 5776.0, 5778.624389414675, 5779.739911703983, 5062.9746851266245, 5138.58891288696, 5244.737763111829, 5304.469553044664, 5379.246905433242, 5871.178035075093, 5880.8235752927485, 5881.2942446906645, 5887.528997680175, 5895.292564892155, 5632.408597097327, 5638.379778224392, 5646.887062258732, 5656.519195868315, 5657.6039677223025, 5477.014137455217, 5605.411431799603, 5645.350784659735, 5664.122061080539, 5736.196932429463, 5675.404082571353, 5680.857978550486, 5681.602287839812, 5682.914756278659, 5735.311762588516, 5639.238702775155, 5668.773249069991, 5698.971505142531, 5702.258706368146, 5713.657180569179, 4279.33670281107, 4303.806328715215, 4311.633511151587, 4376.49670287921, 4413.029133590605, 3910.0420397002918, 3936.842526298916, 3943.9014024649614, 3945.9605403946166, 3946.2896678598127, 5307.150855863043, 5371.62398632092, 5374.892502149935, 5377.569794416438, 5380.704061276668, 5572.414896435897, 5593.216949627055, 5625.268715067097, 5670.432956704297, 5761.827145185651, 5315.707636080053, 5356.4107948125675, 5358.651687640291, 5361.222622719683, 5363.436839131913, 4936.234883593049, 4943.233798761198, 4958.876028099255, 4964.751762411937, 4981.32752078472, 5782.826515204467, 5866.530677545786, 5904.232449781587, 5921.319048309413, 6009.549283803644, 5737.311522617312, 5739.368669446441, 5742.28221472316, 5742.885142297214, 5751.396103409082, 5941.732622032106, 5947.256592925884, 5954.702264886825, 5963.075723262901, 5973.790917317873, 5358.46415358461, 5553.333599967868, 5613.073842815889, 5613.354464236501, 5725.7137143141845, 5770.5095066919785, 5780.826575202751, 5785.161151633074, 5785.479306862402, 5797.797077102281, 5604.271444712162, 5633.0987042073075, 5639.661620302795, 5639.859003524781, 5652.576056795675, 5367.194920761917, 5458.918116228339, 5484.232207490874, 5493.587980901529, 5590.133529302964, 5424.945750542602, 5445.101558242845, 5476.534494568006, 5478.479544443271, 5489.0, 5589.860253493533, 5597.944020559905, 5638.0, 5647.040003199548, 5654.85862853432, 5306.416294207557, 5379.596530260168, 5435.619506634498, 5436.157395603688, 5532.834014979557], "value": 5252.648909860299, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.1956224350204, 591.8668299632583, 598.6438069348742, 599.3586862057599, 602.7137109872808, 32398.352032959356, 33013.66986330136, 35630.2873942521, 35638.2872342553, 35713.28573428533, 43196.35205471913, 44239.0, 44292.3356149658, 48195.51804481954, 48333.275000875015, 8890.42212256203, 9019.95490022549, 9026.413283136608, 9036.593353299084, 9046.0, 8890.155435233642, 8974.596143173572, 8977.371583989123, 8983.95508022459, 8986.23616992553, 9039.593218305192, 9077.273818094536, 9082.54587270637, 9083.31875109366, 9083.727488175366, 8943.686970956018, 8998.730038098802, 9004.954975225148, 9018.970731585407, 9023.0, 8888.866667, 8952.283817294601, 8955.41789783662, 8957.955210223972, 8964.50695211763, 42218.36672449885, 42401.0, 42503.7874810624, 42522.57477425217, 42540.36189457161, 3213.373392188524, 3245.432049391347, 3293.3413317336626, 3307.9173020674434, 3447.172678557153, 32243.677563224537, 32498.0, 32543.0, 32576.348473030645, 33539.0, 4355.934660980088, 4403.955960440387, 4498.955010449887, 4538.977305113453, 4546.568076032771, 358793.0, 359098.0, 359697.0, 359821.0, 360017.0, 57741.13388299179, 57757.11214439261, 57823.4217657818, 58234.0, 58322.708386457794, 60345.0, 60900.08649870167, 60920.69539652362, 60960.0, 61078.69460652757, 13701.725965480635, 14801.259937003322, 15221.847781522265, 15295.617609559627, 15830.841691583168, 12246.877531224753, 12389.132760706845, 12482.875171248172, 12496.562620308427, 12500.812487812691, 17613.8295106882, 17775.73336399955, 17820.55448613769, 17868.374606888698, 17960.9101954492, 4875.047142658639, 5499.257600223918, 5745.85635359111, 5793.7392817323325, 5873.559483038789, 930.8510638297976, 959.6209497248688, 969.4609856517692, 997.0926456924165, 1016.9389836609827, 31727.206819829687, 31840.363192736015, 31938.68061319357, 31959.360812783612, 32471.18822029468, 1051.0, 1117.5250518529592, 1139.1399493382444, 1139.3562637110006, 1189.79178643737, 15885.364585416453, 16955.660886782196, 17017.40439084651, 17034.74447883283, 17081.48755537336, 576942.0, 580452.0, 580831.0, 581245.0, 584149.0, 55826.0, 55993.0, 56053.15920261199, 56091.0, 56101.0, 1185.0714880172202, 1255.7488502299393, 1256.1772039314264, 1304.1588175626669, 1336.6992426703914, 31029.379412412076, 32119.0, 32834.835825820715, 33113.233287858115, 33177.66822331746, 48921.0, 49567.256491153385, 49599.752001239765, 49601.25598115959, 49612.50387496223, 399322.0, 399483.0, 400494.0, 401318.0, 401544.0, 27497.0, 28665.0, 28706.282342940758, 29784.55323170111, 29918.70081299246, 47040.0, 47320.0, 47472.52527474681, 47558.0, 48936.2659560114, 39636.40545391879, 39654.40518392284, 39921.40117898292, 40269.0, 41850.1629967405, 40551.3917291235, 40879.18241635091, 41401.1719765609, 41528.58471415247, 41828.3725744108, 39917.0, 40047.799761001006, 40165.397519036655, 40217.39673904952, 40233.597664022986, 33769.0, 35470.46794298036, 36060.81969590135, 36574.0, 36626.0, 27068.052618158268, 27217.31956701098, 27562.78344954355, 27808.143892080938, 27965.580516291866], "value": 19657.852467888297, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.4104148675427, 367.53323279435136, 397.3860385704088, 402.19159489426255, 413.50999066106664, 16557.91721041396, 18620.813791862078, 18853.0, 19223.327183548543, 19325.0, 18557.0, 18687.813121868778, 18916.243350265973, 19144.13851376688, 19162.520936976583, 5379.435159308275, 5526.613137080406, 5753.884922301551, 5762.9135562966585, 5766.0486019806685, 5586.832395028156, 5751.4536119068625, 5759.712014399284, 5760.222369980062, 5761.135829625548, 5555.611107222496, 5742.569307301969, 5759.049756790125, 5760.942390576082, 5762.3949485304065, 5766.0486019806685, 5788.855278618026, 5795.1017592273265, 5795.681237531934, 5796.623219490741, 5466.34403871534, 5593.300837395325, 5619.466150715675, 5632.3804381518, 5641.943580564182, 11642.126840486913, 11882.108841836896, 11884.0, 11884.821727674092, 21694.566108677896, 2251.1558165687866, 2301.6547517872455, 2538.6318983747437, 2573.5882258838496, 2832.9518078311157, 9842.26183036268, 10265.846012309747, 10318.845217321747, 11288.604898828584, 14590.708185836223, 1886.9150888210063, 1908.4751693284459, 1926.8940208288539, 1936.9709454358197, 1948.912298946551, 56880.71559642217, 80219.5989020057, 80946.59526702388, 83160.58419707924, 83820.0, 10891.292066015683, 11020.283681560673, 11026.18068018784, 11239.494222759995, 11476.655700329004, 15599.376024958874, 15621.218939053002, 15631.60920976962, 15636.609084772745, 15640.843591564166, 7561.697532098655, 7662.195469475736, 7869.1737367576725, 8050.959745201237, 8069.475484093515, 7121.074260346226, 7370.7788766336025, 7387.889181662279, 7581.962090189624, 7588.582627955405, 4482.8655140345845, 4492.146492166478, 4660.6970546914345, 4766.926387021656, 4902.926456103161, 1942.5823447958758, 1945.4260993007097, 1964.695472201811, 1982.0882394098876, 2016.7176595276674, 869.2915274051628, 936.5925822267335, 940.4451373689419, 941.9811603768023, 976.6728146071146, 18514.16686249122, 19674.40976770699, 19845.801541984685, 19926.30257940994, 19968.30110946139, 930.7393929699695, 967.6468089147551, 990.8910019897805, 1009.7677534167183, 1010.6513252927729, 10240.385576865412, 10817.513211905483, 10851.240413171161, 10923.453827308604, 10946.945265273622, 378718.0, 381078.1353118822, 385805.0, 504064.0, 511433.0, 97017.02982970221, 100646.0, 100832.49583752181, 102097.48951255345, 102145.48927255317, 950.6007476859736, 974.668612671695, 985.2512090811035, 997.3467378866999, 1002.04304888831, 17385.739213912053, 17434.30262789525, 17974.730379044588, 18017.189226484577, 18477.630447391246, 28579.571306430837, 30251.243718907204, 43897.561024389346, 43921.78039109784, 44604.77697611491, 58919.70540147272, 59461.702691486265, 60641.39358606534, 61501.69249153726, 62744.686276568325, 8206.22040906113, 8216.507009579444, 8356.0, 8379.95810020946, 8754.868676969723, 7744.186860379579, 11871.703207419883, 12303.200291980993, 12603.369831508204, 13155.73688526205, 10897.67306980792, 11145.275557088764, 11363.829542557034, 11780.941095294469, 12056.156069075081, 11337.03635190985, 11495.827562586735, 11645.126615503701, 11740.41297935117, 11751.588694395998, 11030.338179709242, 11789.233699809485, 11941.283522988648, 11983.460744266355, 12320.199187052813, 8257.587120644064, 8399.076101628938, 8520.786980325542, 8687.478751274944, 8759.824803504021, 10510.737231569272, 11190.0, 11423.257488263236, 11571.942140289244, 11611.88388116108], "value": 8989.335197544358, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1304.6803533134384, 1390.5272207449466, 1416.1856932263947, 1487.1374602730418, 1650.6368598908243, 8372.623231954562, 8742.038375778659, 8825.16160964708, 9031.096890310982, 9049.140331668497, 8863.423877447978, 8976.551172441368, 8985.056569060253, 8986.415882967602, 8994.820103597924, 2373.869437180959, 2399.0883464283634, 2399.1483023526657, 2400.7359190489033, 2407.626817843237, 2360.280114565057, 2391.378241657173, 2398.82008849337, 2408.554417432772, 2411.397150712322, 2354.5879471092576, 2357.3752955466816, 2369.763023697634, 2372.240882917467, 2376.6316220985773, 2287.668288098233, 2307.4231442139467, 2311.248844125649, 2315.073970411832, 2326.906923723049, 2287.7254729432407, 2311.3874823171873, 2311.838171328008, 2319.130326127702, 2319.153508969231, 8736.912630873674, 8901.109889011112, 8979.910200897973, 9009.459432434009, 9015.639374425014, 5112.233165025239, 5240.187770895479, 5303.920441193385, 5312.840614781524, 5315.495027972336, 3551.147724546116, 3552.5914519830294, 3630.3828349180703, 3641.927161456756, 3642.5993140754495, 1067.5943141606217, 1086.5285413105132, 1106.0709004436233, 1123.2081382625229, 1134.9773004539863, 12271.75456490874, 12321.753564928651, 12377.195482293531, 12389.194702344319, 12416.0, 4452.532484089189, 4612.423447069116, 4715.504871988461, 4752.049590081998, 4779.187538118459, 9781.315307928518, 9784.608615655436, 9788.55951482185, 9789.0, 9790.363626364264, 5335.973320133375, 5365.570754339644, 5379.919301210485, 5380.2736630555155, 5382.650127741685, 4372.475302963665, 4832.758362081881, 4940.6788558743565, 4970.801167953314, 5030.170021946338, 3724.0, 3806.600306967784, 3897.3569361055675, 3939.955911683406, 3954.762714237155, 1165.0737663557493, 1169.9528921615106, 1176.7705297467082, 1188.857337119534, 1208.8186771984097, 851.6380538271334, 890.1943740914483, 892.5046599137542, 921.9585118669676, 927.0312523413049, 5525.143602741581, 5606.719664016783, 5669.6314739541795, 5679.460451257124, 5706.686132262683, 449.2721790699088, 455.4352602772538, 461.71373748275613, 465.6554149929028, 465.995340046602, 3799.2781371539318, 3917.941230881539, 3926.411038344214, 3939.1136994176654, 3951.604839516083, 10599.629012984662, 10694.572217111228, 10729.409882456539, 10830.675079747463, 11388.430578471043, 11054.060404865677, 11124.054455371384, 11128.054115400282, 11128.109751219883, 11134.220604557766, 7760.495567788075, 8152.225538573826, 8237.711680091168, 8238.670453181925, 8239.093699693029, 5896.852578685567, 5920.496757775637, 5976.282846058501, 6058.939410605837, 6204.720787564661, 10167.949160254151, 10535.262531622713, 10543.367397956146, 10629.734256643645, 10706.143508519455, 10836.403997780199, 10878.619248326271, 10885.510152043018, 10887.020168184901, 10911.618093366695, 7073.115860517435, 7548.282913123245, 7642.273983971514, 8071.313938315309, 8212.958935205525, 8656.48061116335, 8832.249258813074, 8834.293256539333, 8839.955800221214, 8844.46933184011, 7465.776026719207, 7492.363149132385, 7534.849303014019, 7682.539047657158, 8036.156203598538, 7738.342240909361, 7781.766547003599, 7787.766367008999, 8111.0, 8148.185181481805, 7047.753328633473, 7190.712371505081, 7233.819154521179, 7237.529560578546, 7240.05879235696, 5137.922931155961, 5170.793168273227, 5204.895902081861, 5293.629445938747, 5552.472515111058, 5360.544353729977, 5380.677159370451, 5441.374241962225, 5508.20131080983, 5600.551955843604], "value": 4562.4021984873, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.3352525020705, 318.7529664510005, 324.16042450054357, 325.8077734136859, 326.2089433124672, 5161.587073034158, 5194.896102077956, 5205.141151709968, 5214.374275086991, 5229.607779416549, 4878.243872199815, 5025.195968645017, 5027.2459131130345, 5028.094942910275, 5147.584414286064, 3928.0, 3977.443157957888, 3997.600239976008, 4052.9797351013353, 4086.652634526069, 4041.8585349512773, 4049.2913740095364, 4050.797460126982, 4051.9392209116886, 4070.1859628074503, 3849.403342481906, 3983.8089051021966, 4016.377461493473, 4019.919601607966, 4054.3918412238104, 3993.201359728037, 3997.700172487075, 4015.4378387025695, 4020.5778393268724, 4026.798660067014, 3503.8423270952867, 3516.384632689269, 3519.0, 3530.894073177809, 3534.2578058607724, 4927.334809800667, 5097.923531147036, 5101.0, 5124.590737547167, 5125.974370128163, 4525.275955847082, 4554.681172317939, 4558.065596552732, 4559.3388958601145, 4592.724436533818, 5363.0, 5385.973070134624, 5398.623351045498, 5405.162199859028, 5415.783368665248, 3816.484774555427, 3839.308924393608, 3847.1343947611845, 3854.2484215577974, 3870.748401353931, 5386.434424385461, 5576.972115139399, 5610.579206559483, 5633.15502674598, 5635.1828984796975, 3910.432987216838, 3919.2161567686576, 3924.529056513237, 3945.171513982096, 3952.3873799561115, 3978.482797236341, 3989.1024519482885, 3997.8400863965117, 4001.1197536541913, 4001.7598944063448, 4470.6870519064005, 4471.441069866267, 4527.8641640750175, 4541.296099104644, 4606.907861842745, 5394.676319420769, 5405.621606487587, 5418.8916221675345, 5424.105022671294, 5425.674459532441, 1322.7235717532628, 1355.7762969109986, 1363.4341748174397, 1371.4582739817915, 1372.9176249425066, 915.3729695158837, 923.35826600512, 927.7402327348353, 933.2953620016843, 942.4251206763869, 5209.192575150858, 5222.399424066205, 5248.343957005374, 5265.0, 5280.81517146898, 5528.75120619573, 5555.2222688823595, 5567.137093750475, 5570.136628822539, 5586.31856708782, 5055.014272216956, 5286.735663216824, 5293.3647962244, 5297.417284098746, 5332.600054995891, 2936.662283837386, 2978.776591755627, 2981.5080511715323, 2982.9701702983125, 2983.8657260423333, 5648.378678345379, 5712.285964254468, 5766.192733017381, 5801.7099145042575, 5814.127880817911, 5650.773969041193, 5655.236543066633, 5662.546996240291, 5685.0, 5699.5155411790465, 5718.771249150071, 5719.571032172604, 5725.713714314267, 5760.222369979999, 5784.537237020944, 5425.321834770653, 5482.23248745168, 5518.972405137949, 5556.527695145958, 5579.2746942897165, 5480.424555421783, 5495.340559132929, 5515.862103447446, 5539.7784088636, 5575.884823035328, 5640.85897852557, 5784.82645520635, 5812.476877081083, 5828.6211396259105, 5837.737301821513, 3896.551896531935, 3945.171513982096, 3964.980175099106, 3965.294923183059, 3999.3001224784966, 5583.888322233452, 5590.239074691459, 5596.272484576979, 5617.522510586646, 5617.6910269933915, 5263.789448422173, 5414.75633596497, 5444.373897001896, 5486.835394938158, 5506.807261745819, 5385.0037743017665, 5399.892002160013, 5404.027275090364, 5404.243405923095, 5424.647397919122, 5162.8967420652125, 5177.8187763428095, 5319.973400132974, 5338.733063346895, 5364.834747498839, 5400.62195646301, 5408.107662235687, 5418.756155972912, 5425.755840987086, 5429.37118864347, 5298.94701053, 5329.108166600956, 5338.97330513345, 5340.75966581497, 5371.167469042305], "value": 4128.423281841643, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.8088135322108, 701.7894631610517, 715.2597062040786, 736.7716008037509, 786.3473317146769, 13310.401031953574, 14124.788128178085, 14144.858551414483, 14172.149671019746, 15144.469943551978, 15282.159481228557, 15390.69218615627, 15395.538133855975, 15401.768973465407, 15408.30662620182, 734.8126227811928, 735.6910097758954, 738.6233021159194, 739.2792027772932, 743.0080842075836, 625.6746491824249, 728.4936968806655, 731.956082635041, 739.6227923758895, 740.1192580828823, 789.9368050555942, 791.2087912087912, 791.2720297326473, 794.0431779705441, 794.3526026288586, 786.6224212378062, 789.7867575754572, 790.276896639577, 790.6600161930368, 790.7983464216645, 474.80770288033403, 553.3028384235852, 555.3197333266716, 560.0031943141215, 566.0207840436069, 13992.0, 14045.43818247269, 14079.577612671636, 14081.64795880101, 14082.718345633133, 13524.93237533806, 14181.503647372292, 14483.058601190889, 14595.562133136023, 14670.926645366704, 12305.569305074368, 12315.384230788515, 12401.22673646146, 12550.97282441537, 13032.108885393447, 4515.345274935116, 4518.028623845889, 4628.421447319085, 4635.2120139576355, 4755.2153894607345, 83484.58257708733, 84102.0, 86434.0, 87585.99999999936, 89459.55270223672, 10780.51487683056, 10784.514696838662, 10893.509792059376, 11050.0, 11095.44522773858, 20848.37454876356, 20852.270170543958, 20879.478013049797, 20880.477988050116, 20901.581968360548, 7659.272369124924, 7754.612269386508, 7821.92178078212, 7858.764237072897, 8268.37987150966, 4311.48262208537, 4454.866354009385, 4532.637389008872, 4540.455145382575, 4674.415698037746, 3332.8333583320737, 3434.1242983039165, 3700.1674623209565, 3866.0, 3872.3223435898603, 638.9744410223632, 643.4209211709499, 654.7446495866616, 660.0561197487543, 672.0993868216614, 463.48090139043813, 567.6565677764976, 603.4960807725511, 633.3508154141949, 675.2000317274634, 8876.822463550821, 10157.746056348651, 10163.832095633958, 10298.845517317246, 10383.896161038445, 662.6421732264523, 695.4054283587561, 696.3350000749273, 700.7512333121771, 718.7951433841434, 4318.546552611993, 4721.858344249678, 4735.12400205963, 4735.621150307967, 4759.857204283877, 73035.26964730391, 108257.4587127075, 126443.0, 129080.3545982264, 132049.0, 106365.46817265864, 109430.45284773526, 109734.0, 111015.44492277487, 112428.43785781182, 93717.53141234386, 94470.0, 95312.52343738376, 96061.5196924025, 96477.5176124115, 9402.764930876509, 9448.669296574863, 9462.905370946202, 9494.810103798023, 9648.228141748783, 52598.737006314725, 53091.0, 53094.203586947, 53474.46525534695, 55603.44396556145, 75380.62309688635, 76728.61635691786, 81942.59028704818, 82587.58706206671, 83341.0, 13161.73676526445, 13190.868091318964, 13461.798073029107, 13493.797593035915, 14134.293285335898, 14840.338630625107, 15350.07899526032, 15682.686346272783, 15995.200239988188, 16027.919860400623, 13305.534306299232, 13878.375473103937, 14009.579712608638, 14438.639034024234, 15014.324355403814, 14099.060461590698, 14207.218602976936, 14444.638884027983, 15129.848701512845, 15222.16278104715, 14130.293485325486, 14157.858421416066, 14171.149731016172, 14288.785668215192, 14566.85433145655, 10854.511546980248, 11265.605703800655, 11599.8260026098, 11742.354170520703, 12112.426834084143, 11165.553377864795, 11932.463039163085, 12162.817557736464, 12406.441710123247, 12463.31451770125], "value": 7564.070724248989, "unit": "score"}], "name": "dromaeo_css", "value": 7137.895739980399, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 112.0, 111.0, 105.0, 111.0, 112.0, 110.0, 110.0, 109.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 118.0, 185.0, 117.0, 117.0, 154.0, 116.0, 149.0, 120.0], "value": 133.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 159.0, 160.0, 160.0, 162.0, 162.0, 162.0, 162.0, 162.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 111.0, 75.0, 75.0, 74.0, 75.0, 108.0, 76.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 135.0, 85.0, 85.0, 84.0, 120.0, 85.0, 84.0, 139.0], "value": 98.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 51.0, 51.0, 53.0, 53.0, 52.0, 52.0, 50.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 63.0, 63.0, 63.0, 63.0, 62.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 86.0, 78.0, 81.0, 78.0, 77.0, 81.0, 77.0, 79.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 121.0, 132.0, 138.0, 128.0, 123.0, 135.0, 121.0, 144.0, 119.0], "value": 128.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 162.0, 160.0, 159.0, 160.0, 165.0, 165.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 64.0, 65.0, 64.0, 63.0, 72.0, 64.0, 63.0, 63.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1491.2, "alertThreshold": 2.0}]} 19:57:37 INFO - Return code: 0 19:57:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:57:37 INFO - # TBPL SUCCESS # 19:57:37 INFO - Running post-action listener: _resource_record_post_action 19:57:37 INFO - [mozharness: 2016-08-16 02:57:37.144680Z] Finished run-tests step (success) 19:57:37 INFO - Running post-run listener: _resource_record_post_run 19:57:37 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 0; Write bytes: 417460224; Read time: 0; Write time: 293376 19:57:37 INFO - TinderboxPrint: CPU usage
13.0% 19:57:37 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:57:37 INFO - TinderboxPrint: I/O write bytes / time
417,460,224 / 293,376 19:57:37 INFO - TinderboxPrint: CPU idle
8,291.9 (87.0%) 19:57:37 INFO - TinderboxPrint: CPU user
1,193.3 (12.5%) 19:57:37 INFO - TinderboxPrint: Swap in / out
0 / 0 19:57:37 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 128233472; Read time: 0; Write time: 81704 19:57:37 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 287985664; Read time: 0; Write time: 211612 19:57:37 INFO - Running post-run listener: _upload_blobber_files 19:57:37 INFO - Blob upload gear active. 19:57:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:57:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:57:37 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:57:37 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:57:38 INFO - (blobuploader) - INFO - Open directory for files ... 19:57:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:57:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:57:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:57:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:57:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:57:38 INFO - (blobuploader) - INFO - Done attempting. 19:57:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:57:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:57:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:57:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:57:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:57:43 INFO - (blobuploader) - INFO - Done attempting. 19:57:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:57:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:57:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:57:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:57:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:57:44 INFO - (blobuploader) - INFO - Done attempting. 19:57:44 INFO - (blobuploader) - INFO - Iteration through files over. 19:57:44 INFO - Return code: 0 19:57:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:57:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:57:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0647b2785a3d2f70fd27d25bf7cba9514dacad1dd198261705844a0ffa881ae9ac4d99285a48cd9e9e0da13eb56abe8f682aa2a6c4de31f269e19d25510cc43a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f89e91edf37ce0e60f9adb38e61030f44c54c520a0101503e43eae6c1b16f031f6ebc150cd6dda527bd40750ad5afacd60b45e3117a7b230064c8347d83e371f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d310ccc8aa7a954a6339812436940985afb7c963c2a292aa7d85ff242e561d480e2a1ed12b1c651958fb3d956124a8e32d0ea076f5d393fa7e48c4535e13bcf4"} 19:57:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:57:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:57:44 INFO - Contents: 19:57:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0647b2785a3d2f70fd27d25bf7cba9514dacad1dd198261705844a0ffa881ae9ac4d99285a48cd9e9e0da13eb56abe8f682aa2a6c4de31f269e19d25510cc43a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f89e91edf37ce0e60f9adb38e61030f44c54c520a0101503e43eae6c1b16f031f6ebc150cd6dda527bd40750ad5afacd60b45e3117a7b230064c8347d83e371f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d310ccc8aa7a954a6339812436940985afb7c963c2a292aa7d85ff242e561d480e2a1ed12b1c651958fb3d956124a8e32d0ea076f5d393fa7e48c4535e13bcf4"} 19:57:44 INFO - Running post-run listener: copy_logs_to_upload_dir 19:57:44 INFO - Copying logs to upload dir... 19:57:44 INFO - mkdir: /builds/slave/test/build/upload/logs 19:57:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.757899 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-15 19:57:44.502450) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 19:57:44.505863) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0647b2785a3d2f70fd27d25bf7cba9514dacad1dd198261705844a0ffa881ae9ac4d99285a48cd9e9e0da13eb56abe8f682aa2a6c4de31f269e19d25510cc43a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f89e91edf37ce0e60f9adb38e61030f44c54c520a0101503e43eae6c1b16f031f6ebc150cd6dda527bd40750ad5afacd60b45e3117a7b230064c8347d83e371f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d310ccc8aa7a954a6339812436940985afb7c963c2a292aa7d85ff242e561d480e2a1ed12b1c651958fb3d956124a8e32d0ea076f5d393fa7e48c4535e13bcf4"} build_url:https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/JnLhNiySRpKZF7R5aWtpcQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0647b2785a3d2f70fd27d25bf7cba9514dacad1dd198261705844a0ffa881ae9ac4d99285a48cd9e9e0da13eb56abe8f682aa2a6c4de31f269e19d25510cc43a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f89e91edf37ce0e60f9adb38e61030f44c54c520a0101503e43eae6c1b16f031f6ebc150cd6dda527bd40750ad5afacd60b45e3117a7b230064c8347d83e371f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d310ccc8aa7a954a6339812436940985afb7c963c2a292aa7d85ff242e561d480e2a1ed12b1c651958fb3d956124a8e32d0ea076f5d393fa7e48c4535e13bcf4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 19:57:44.535664) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 19:57:44.535997) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1471315023.597434-1804722811 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004424 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 19:57:44.620921) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 19:57:44.621380) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 19:57:44.621779) ========= ========= Total master_lag: 0.18 =========