builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-047 starttime: 1467907320.65 results: success (0) buildid: 20160707082722 builduid: e66344b6701b424499d55f672a9873c8 revision: e2f5a7c70b47be82f2a8f85cac59482c8f77550a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:00.646327) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:00.646722) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:00.646995) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:00.675281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:00.675569) ========= 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=03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-07 09:02:00-- 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% 46.1M=0s 2016-07-07 09:02:00 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089217 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:00.789156) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:00.789475) ========= 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=03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018579 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:00.841123) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 09:02:00.841486) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e2f5a7c70b47be82f2a8f85cac59482c8f77550a --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 e2f5a7c70b47be82f2a8f85cac59482c8f77550a --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=03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-07 09:02:01,038 truncating revision to first 12 chars 2016-07-07 09:02:01,038 Setting DEBUG logging. 2016-07-07 09:02:01,038 attempt 1/10 2016-07-07 09:02:01,038 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e2f5a7c70b47?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 09:02:01,611 unpacking tar archive at: fx-team-e2f5a7c70b47/testing/mozharness/ program finished with exit code 0 elapsedTime=0.987779 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 09:02:01.843499) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:01.843840) ========= script_repo_revision: e2f5a7c70b47be82f2a8f85cac59482c8f77550a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:01.844427) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:01.844708) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 09:02:01.853445) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-07-07 09:02:01.853853) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:02:02 INFO - MultiFileLogger online at 20160707 09:02:02 in /builds/slave/test 09:02:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 09:02:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:02:02 INFO - {'append_to_log': False, 09:02:02 INFO - 'base_work_dir': '/builds/slave/test', 09:02:02 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:02:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:02:02 INFO - 'branch': 'Fx-Team-Non-PGO', 09:02:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:02:02 INFO - 'config_files': ('talos/linux_config.py',), 09:02:02 INFO - 'default_actions': ('clobber', 09:02:02 INFO - 'read-buildbot-config', 09:02:02 INFO - 'download-and-extract', 09:02:02 INFO - 'populate-webroot', 09:02:02 INFO - 'create-virtualenv', 09:02:02 INFO - 'install', 09:02:02 INFO - 'run-tests'), 09:02:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:02:02 INFO - 'download_minidump_stackwalk': True, 09:02:02 INFO - 'download_symbols': 'ondemand', 09:02:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:02:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:02:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:02:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:02:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:02:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:02:02 INFO - 'installer_path': 'installer.exe', 09:02:02 INFO - 'log_level': 'info', 09:02:02 INFO - 'log_name': 'talos', 09:02:02 INFO - 'log_to_console': True, 09:02:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:02:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:02:02 INFO - 'opt_config_files': (), 09:02:02 INFO - 'pip_index': False, 09:02:02 INFO - 'sps_profile': False, 09:02:02 INFO - 'sps_profile_interval': 0, 09:02:02 INFO - 'suite': 'dromaeojs', 09:02:02 INFO - 'system_bits': '32', 09:02:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:02:02 INFO - 'title': 'talos-linux64-ix-047', 09:02:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:02:02 INFO - 'use_talos_json': True, 09:02:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:02:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:02:02 INFO - 'work_dir': 'build'} 09:02:02 INFO - ##### 09:02:02 INFO - ##### Running clobber step. 09:02:02 INFO - ##### 09:02:02 INFO - Running pre-action listener: _resource_record_pre_action 09:02:02 INFO - Running main action method: clobber 09:02:02 INFO - rmtree: /builds/slave/test/build 09:02:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:02:03 INFO - Running post-action listener: _resource_record_post_action 09:02:03 INFO - ##### 09:02:03 INFO - ##### Finished clobber step (success) 09:02:03 INFO - ##### 09:02:03 INFO - ##### 09:02:03 INFO - ##### Running read-buildbot-config step. 09:02:03 INFO - ##### 09:02:03 INFO - Running pre-action listener: _resource_record_pre_action 09:02:03 INFO - Running main action method: read_buildbot_config 09:02:03 INFO - Using buildbot properties: 09:02:03 INFO - { 09:02:03 INFO - "project": "", 09:02:03 INFO - "product": "firefox", 09:02:03 INFO - "script_repo_revision": "production", 09:02:03 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:02:03 INFO - "repository": "", 09:02:03 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:02:03 INFO - "buildid": "20160707082722", 09:02:03 INFO - "pgo_build": "False", 09:02:03 INFO - "basedir": "/builds/slave/test", 09:02:03 INFO - "buildnumber": 1942, 09:02:03 INFO - "slavename": "talos-linux64-ix-047", 09:02:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:02:03 INFO - "platform": "ubuntu64_hw", 09:02:03 INFO - "scheduler": "tests-fx-team-linux64-talos", 09:02:03 INFO - "branch": "fx-team", 09:02:03 INFO - "repo_path": "integration/fx-team", 09:02:03 INFO - "revision": "e2f5a7c70b47be82f2a8f85cac59482c8f77550a", 09:02:03 INFO - "stage_platform": "linux64", 09:02:03 INFO - "builduid": "e66344b6701b424499d55f672a9873c8", 09:02:03 INFO - "slavebuilddir": "test" 09:02:03 INFO - } 09:02:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:02:03 INFO - Running post-action listener: _resource_record_post_action 09:02:03 INFO - ##### 09:02:03 INFO - ##### Finished read-buildbot-config step (success) 09:02:03 INFO - ##### 09:02:03 INFO - ##### 09:02:03 INFO - ##### Running download-and-extract step. 09:02:03 INFO - ##### 09:02:03 INFO - Running pre-action listener: _resource_record_pre_action 09:02:03 INFO - Running main action method: download_and_extract 09:02:03 INFO - mkdir: /builds/slave/test/build/tests 09:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:03 INFO - https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:02:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:02:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:02:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:02:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:02:05 INFO - Downloaded 1361 bytes. 09:02:05 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:02:05 INFO - Using the following test package requirements: 09:02:05 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:02:05 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:02:05 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:02:05 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:05 INFO - u'jsshell-linux-x86_64.zip'], 09:02:05 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:02:05 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:02:05 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:02:05 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:02:05 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:02:05 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:02:05 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:02:05 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:05 INFO - https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:02:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:02:11 INFO - Downloaded 22734517 bytes. 09:02:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:02:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:02:12 INFO - Return code: 0 09:02:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:12 INFO - https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:02:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:02:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:02:21 INFO - Downloaded 22734517 bytes. 09:02:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:02:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:02:23 INFO - Return code: 0 09:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:23 INFO - https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:02:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:02:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:02:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:02:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:02:25 INFO - Downloaded 14403333 bytes. 09:02:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:02:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:02:25 INFO - Return code: 0 09:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:25 INFO - https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:02:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:02:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:02:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:02:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:02:28 INFO - Downloaded 56643725 bytes. 09:02:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:02:28 INFO - mkdir: /builds/slave/test/properties 09:02:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:02:28 INFO - Writing to file /builds/slave/test/properties/build_url 09:02:28 INFO - Contents: 09:02:28 INFO - build_url:https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:02:29 INFO - Running post-action listener: _resource_record_post_action 09:02:29 INFO - Running post-action listener: set_extra_try_arguments 09:02:29 INFO - ##### 09:02:29 INFO - ##### Finished download-and-extract step (success) 09:02:29 INFO - ##### 09:02:29 INFO - ##### 09:02:29 INFO - ##### Running populate-webroot step. 09:02:29 INFO - ##### 09:02:29 INFO - Running pre-action listener: _resource_record_pre_action 09:02:29 INFO - Running main action method: populate_webroot 09:02:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:02:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:02:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:02:29 INFO - '--tppagecycles', 09:02:29 INFO - '7'], 09:02:29 INFO - 'tests': ['tsvgm']}}, 09:02:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:02:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:02:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:02:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:02:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['damp', 'tps']}, 09:02:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:29 INFO - 'tests': ['damp', 'tps']}, 09:02:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['dromaeo_dom']}, 09:02:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:02:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['basic_compositor_video']}, 09:02:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:02:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other-e10s': {'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['a11yr', 09:02:29 INFO - 'ts_paint', 09:02:29 INFO - 'tpaint', 09:02:29 INFO - 'sessionrestore', 09:02:29 INFO - 'sessionrestore_no_auto_restore', 09:02:29 INFO - 'tabpaint']}, 09:02:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tsvgx', 09:02:29 INFO - 'tsvgr_opacity', 09:02:29 INFO - 'tart', 09:02:29 INFO - 'tscrollx', 09:02:29 INFO - 'cart']}, 09:02:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:02:29 INFO - 'tsvgr_opacity', 09:02:29 INFO - 'tart', 09:02:29 INFO - 'tscrollx', 09:02:29 INFO - 'cart']}, 09:02:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s'], 09:02:29 INFO - 'tests': ['tp5o']}, 09:02:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:29 INFO - 'tests': ['tp5o']}, 09:02:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:29 INFO - 'talos_options': ['--disable-e10s', 09:02:29 INFO - '--xperf_path', 09:02:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:29 INFO - 'tests': ['tp5n']}, 09:02:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:29 INFO - 'talos_options': ['--xperf_path', 09:02:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:29 INFO - 'tests': ['tp5n']}}, 09:02:29 INFO - 'talos.zip': {'path': '', 09:02:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:02:29 INFO - Running post-action listener: _resource_record_post_action 09:02:29 INFO - ##### 09:02:29 INFO - ##### Finished populate-webroot step (success) 09:02:29 INFO - ##### 09:02:29 INFO - ##### 09:02:29 INFO - ##### Running create-virtualenv step. 09:02:29 INFO - ##### 09:02:29 INFO - Running pre-action listener: _resource_record_pre_action 09:02:29 INFO - Running main action method: create_virtualenv 09:02:29 INFO - Creating virtualenv /builds/slave/test/build/venv 09:02:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:02:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:02:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:02:29 INFO - Using real prefix '/usr' 09:02:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:02:29 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:31 INFO - Installing pip.................done. 09:02:31 INFO - Return code: 0 09:02:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb83b0>, '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 0x2bb92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9740>, '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': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:02:31 INFO - Using env: {'DISPLAY': ':0', 09:02:31 INFO - 'HOME': '/home/cltbld', 09:02:31 INFO - 'LANG': 'en_US.UTF-8', 09:02:31 INFO - 'LANGUAGE': 'en_US:en', 09:02:31 INFO - 'LOGNAME': 'cltbld', 09:02:31 INFO - 'MAIL': '/var/mail/cltbld', 09:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:31 INFO - 'MOZ_NO_REMOTE': '1', 09:02:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:31 INFO - 'NO_EM_RESTART': '1', 09:02:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:31 INFO - 'PWD': '/builds/slave/test', 09:02:31 INFO - 'SHELL': '/bin/bash', 09:02:31 INFO - 'SHLVL': '1', 09:02:31 INFO - 'TERM': 'linux', 09:02:31 INFO - 'TMOUT': '86400', 09:02:31 INFO - 'USER': 'cltbld', 09:02:31 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 09:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:31 INFO - '_': '/tools/buildbot/bin/python'} 09:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:02:31 INFO - Cleaning up... 09:02:31 INFO - Return code: 0 09:02:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb83b0>, '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 0x2bb92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9740>, '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': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:02:31 INFO - Using env: {'DISPLAY': ':0', 09:02:31 INFO - 'HOME': '/home/cltbld', 09:02:31 INFO - 'LANG': 'en_US.UTF-8', 09:02:31 INFO - 'LANGUAGE': 'en_US:en', 09:02:31 INFO - 'LOGNAME': 'cltbld', 09:02:31 INFO - 'MAIL': '/var/mail/cltbld', 09:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:31 INFO - 'MOZ_NO_REMOTE': '1', 09:02:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:31 INFO - 'NO_EM_RESTART': '1', 09:02:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:31 INFO - 'PWD': '/builds/slave/test', 09:02:31 INFO - 'SHELL': '/bin/bash', 09:02:31 INFO - 'SHLVL': '1', 09:02:31 INFO - 'TERM': 'linux', 09:02:31 INFO - 'TMOUT': '86400', 09:02:31 INFO - 'USER': 'cltbld', 09:02:31 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 09:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:31 INFO - '_': '/tools/buildbot/bin/python'} 09:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:32 INFO - Downloading/unpacking psutil>=3.1.1 09:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:34 INFO - Installing collected packages: psutil 09:02:34 INFO - Running setup.py install for psutil 09:02:34 INFO - building 'psutil._psutil_linux' extension 09:02:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:02:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:02:34 INFO - building 'psutil._psutil_posix' extension 09:02:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:02:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:02:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:34 INFO - Successfully installed psutil 09:02:34 INFO - Cleaning up... 09:02:34 INFO - Return code: 0 09:02:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb83b0>, '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 0x2bb92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9740>, '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': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:02:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:02:34 INFO - Using env: {'DISPLAY': ':0', 09:02:34 INFO - 'HOME': '/home/cltbld', 09:02:34 INFO - 'LANG': 'en_US.UTF-8', 09:02:34 INFO - 'LANGUAGE': 'en_US:en', 09:02:34 INFO - 'LOGNAME': 'cltbld', 09:02:34 INFO - 'MAIL': '/var/mail/cltbld', 09:02:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:34 INFO - 'MOZ_NO_REMOTE': '1', 09:02:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:34 INFO - 'NO_EM_RESTART': '1', 09:02:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:34 INFO - 'PWD': '/builds/slave/test', 09:02:34 INFO - 'SHELL': '/bin/bash', 09:02:34 INFO - 'SHLVL': '1', 09:02:34 INFO - 'TERM': 'linux', 09:02:34 INFO - 'TMOUT': '86400', 09:02:34 INFO - 'USER': 'cltbld', 09:02:34 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 09:02:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:34 INFO - '_': '/tools/buildbot/bin/python'} 09:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:02:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:02:36 INFO - Installing collected packages: mozsystemmonitor 09:02:36 INFO - Running setup.py install for mozsystemmonitor 09:02:36 INFO - Successfully installed mozsystemmonitor 09:02:36 INFO - Cleaning up... 09:02:36 INFO - Return code: 0 09:02:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb83b0>, '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 0x2bb92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9740>, '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': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:02:36 INFO - Using env: {'DISPLAY': ':0', 09:02:36 INFO - 'HOME': '/home/cltbld', 09:02:36 INFO - 'LANG': 'en_US.UTF-8', 09:02:36 INFO - 'LANGUAGE': 'en_US:en', 09:02:36 INFO - 'LOGNAME': 'cltbld', 09:02:36 INFO - 'MAIL': '/var/mail/cltbld', 09:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:36 INFO - 'MOZ_NO_REMOTE': '1', 09:02:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:36 INFO - 'NO_EM_RESTART': '1', 09:02:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:36 INFO - 'PWD': '/builds/slave/test', 09:02:36 INFO - 'SHELL': '/bin/bash', 09:02:36 INFO - 'SHLVL': '1', 09:02:36 INFO - 'TERM': 'linux', 09:02:36 INFO - 'TMOUT': '86400', 09:02:36 INFO - 'USER': 'cltbld', 09:02:36 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 09:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:36 INFO - '_': '/tools/buildbot/bin/python'} 09:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:37 INFO - Downloading/unpacking jsonschema==2.5.1 09:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:39 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:39 INFO - warning: no files found matching '*.txt' 09:02:39 INFO - no previously-included directories found matching 'build' 09:02:39 INFO - no previously-included directories found matching 'dist' 09:02:39 INFO - no previously-included directories found matching '.git*' 09:02:39 INFO - Installing collected packages: jsonschema, functools32 09:02:39 INFO - Running setup.py install for functools32 09:02:39 INFO - warning: no files found matching '*.txt' 09:02:39 INFO - no previously-included directories found matching 'build' 09:02:39 INFO - no previously-included directories found matching 'dist' 09:02:39 INFO - no previously-included directories found matching '.git*' 09:02:39 INFO - Successfully installed jsonschema functools32 09:02:39 INFO - Cleaning up... 09:02:39 INFO - Return code: 0 09:02:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb83b0>, '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 0x2bb92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9740>, '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': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:02:39 INFO - Using env: {'DISPLAY': ':0', 09:02:39 INFO - 'HOME': '/home/cltbld', 09:02:39 INFO - 'LANG': 'en_US.UTF-8', 09:02:39 INFO - 'LANGUAGE': 'en_US:en', 09:02:39 INFO - 'LOGNAME': 'cltbld', 09:02:39 INFO - 'MAIL': '/var/mail/cltbld', 09:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:39 INFO - 'MOZ_NO_REMOTE': '1', 09:02:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:39 INFO - 'NO_EM_RESTART': '1', 09:02:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:39 INFO - 'PWD': '/builds/slave/test', 09:02:39 INFO - 'SHELL': '/bin/bash', 09:02:39 INFO - 'SHLVL': '1', 09:02:39 INFO - 'TERM': 'linux', 09:02:39 INFO - 'TMOUT': '86400', 09:02:39 INFO - 'USER': 'cltbld', 09:02:39 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 09:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:39 INFO - '_': '/tools/buildbot/bin/python'} 09:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:39 INFO - Downloading/unpacking blobuploader==1.2.4 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:41 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:42 INFO - Downloading docopt-0.6.1.tar.gz 09:02:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:42 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:42 INFO - Running setup.py install for blobuploader 09:02:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:42 INFO - Running setup.py install for requests 09:02:42 INFO - Running setup.py install for docopt 09:02:42 INFO - Successfully installed blobuploader requests docopt 09:02:42 INFO - Cleaning up... 09:02:42 INFO - Return code: 0 09:02:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb83b0>, '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 0x2bb92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9740>, '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': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:02:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:42 INFO - Using env: {'DISPLAY': ':0', 09:02:42 INFO - 'HOME': '/home/cltbld', 09:02:42 INFO - 'LANG': 'en_US.UTF-8', 09:02:42 INFO - 'LANGUAGE': 'en_US:en', 09:02:42 INFO - 'LOGNAME': 'cltbld', 09:02:42 INFO - 'MAIL': '/var/mail/cltbld', 09:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:42 INFO - 'MOZ_NO_REMOTE': '1', 09:02:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:42 INFO - 'NO_EM_RESTART': '1', 09:02:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:42 INFO - 'PWD': '/builds/slave/test', 09:02:42 INFO - 'SHELL': '/bin/bash', 09:02:42 INFO - 'SHLVL': '1', 09:02:42 INFO - 'TERM': 'linux', 09:02:42 INFO - 'TMOUT': '86400', 09:02:42 INFO - 'USER': 'cltbld', 09:02:42 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 09:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:42 INFO - '_': '/tools/buildbot/bin/python'} 09:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:43 INFO - Running setup.py (path:/tmp/pip-0rhv06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:43 INFO - Running setup.py (path:/tmp/pip-BbibF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:43 INFO - Running setup.py (path:/tmp/pip-Ky4oJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:43 INFO - Running setup.py (path:/tmp/pip-ZvSerI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:43 INFO - Running setup.py (path:/tmp/pip-XPCLSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:43 INFO - Running setup.py (path:/tmp/pip-wkN3Ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:43 INFO - Running setup.py (path:/tmp/pip-qLxIC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:43 INFO - Running setup.py (path:/tmp/pip-q6Pbrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:43 INFO - Running setup.py (path:/tmp/pip-mSJ_uA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:43 INFO - Running setup.py (path:/tmp/pip-hnZbfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:43 INFO - Running setup.py (path:/tmp/pip-fcC2qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:43 INFO - Running setup.py (path:/tmp/pip-Ekf49k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:44 INFO - Running setup.py (path:/tmp/pip-WD87BB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:44 INFO - Running setup.py (path:/tmp/pip-TzFl67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:44 INFO - Running setup.py (path:/tmp/pip-AA6bEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:44 INFO - Running setup.py (path:/tmp/pip-Mlqx0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:44 INFO - Running setup.py (path:/tmp/pip-93qW9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:44 INFO - Running setup.py install for manifestparser 09:02:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:44 INFO - Running setup.py install for mozcrash 09:02:44 INFO - Running setup.py install for mozdebug 09:02:44 INFO - Running setup.py install for mozdevice 09:02:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:02:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:44 INFO - Running setup.py install for mozfile 09:02:44 INFO - Running setup.py install for mozhttpd 09:02:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Running setup.py install for mozinfo 09:02:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Running setup.py install for mozInstall 09:02:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Running setup.py install for mozleak 09:02:45 INFO - Running setup.py install for mozlog 09:02:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Running setup.py install for moznetwork 09:02:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Running setup.py install for mozprocess 09:02:45 INFO - Running setup.py install for mozprofile 09:02:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Running setup.py install for mozrunner 09:02:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:45 INFO - Running setup.py install for mozscreenshot 09:02:45 INFO - Running setup.py install for moztest 09:02:46 INFO - Running setup.py install for mozversion 09:02:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:46 INFO - Cleaning up... 09:02:46 INFO - Return code: 0 09:02:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb83b0>, '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 0x2bb92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9740>, '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': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:02:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:46 INFO - Using env: {'DISPLAY': ':0', 09:02:46 INFO - 'HOME': '/home/cltbld', 09:02:46 INFO - 'LANG': 'en_US.UTF-8', 09:02:46 INFO - 'LANGUAGE': 'en_US:en', 09:02:46 INFO - 'LOGNAME': 'cltbld', 09:02:46 INFO - 'MAIL': '/var/mail/cltbld', 09:02:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:46 INFO - 'MOZ_NO_REMOTE': '1', 09:02:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:46 INFO - 'NO_EM_RESTART': '1', 09:02:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:46 INFO - 'PWD': '/builds/slave/test', 09:02:46 INFO - 'SHELL': '/bin/bash', 09:02:46 INFO - 'SHLVL': '1', 09:02:46 INFO - 'TERM': 'linux', 09:02:46 INFO - 'TMOUT': '86400', 09:02:46 INFO - 'USER': 'cltbld', 09:02:46 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 09:02:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:46 INFO - '_': '/tools/buildbot/bin/python'} 09:02:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:46 INFO - Running setup.py (path:/tmp/pip-0br8KZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:46 INFO - Running setup.py (path:/tmp/pip-wvUY4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:46 INFO - Running setup.py (path:/tmp/pip-6NAosc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:46 INFO - Running setup.py (path:/tmp/pip-AANloy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:46 INFO - Running setup.py (path:/tmp/pip-p7GTW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:46 INFO - Running setup.py (path:/tmp/pip-0vMWTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:46 INFO - Running setup.py (path:/tmp/pip-HwW2Re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:46 INFO - Running setup.py (path:/tmp/pip-hw5q3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:46 INFO - Running setup.py (path:/tmp/pip-QAXICI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:47 INFO - Running setup.py (path:/tmp/pip-KDRNpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:47 INFO - Running setup.py (path:/tmp/pip-GS31o8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:47 INFO - Running setup.py (path:/tmp/pip-By8EtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:47 INFO - Running setup.py (path:/tmp/pip-F12e3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:47 INFO - Running setup.py (path:/tmp/pip-xysbNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:47 INFO - Running setup.py (path:/tmp/pip-MzB0l7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:47 INFO - Running setup.py (path:/tmp/pip-ldDm84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:47 INFO - Running setup.py (path:/tmp/pip-Zeu0Oq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:49 INFO - Downloading blessings-1.6.tar.gz 09:02:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:49 INFO - Installing collected packages: blessings 09:02:49 INFO - Running setup.py install for blessings 09:02:49 INFO - Successfully installed blessings 09:02:49 INFO - Cleaning up... 09:02:49 INFO - Return code: 0 09:02:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:02:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:49 INFO - Reading from file tmpfile_stdout 09:02:49 INFO - Current package versions: 09:02:49 INFO - argparse == 1.2.1 09:02:49 INFO - blessings == 1.6 09:02:49 INFO - blobuploader == 1.2.4 09:02:49 INFO - docopt == 0.6.1 09:02:49 INFO - functools32 == 3.2.3-2 09:02:49 INFO - jsonschema == 2.5.1 09:02:49 INFO - manifestparser == 1.1 09:02:49 INFO - mozInstall == 1.12 09:02:49 INFO - mozcrash == 0.17 09:02:49 INFO - mozdebug == 0.1 09:02:49 INFO - mozdevice == 0.48 09:02:49 INFO - mozfile == 1.2 09:02:49 INFO - mozhttpd == 0.7 09:02:49 INFO - mozinfo == 0.9 09:02:49 INFO - mozleak == 0.1 09:02:49 INFO - mozlog == 3.2 09:02:49 INFO - moznetwork == 0.27 09:02:49 INFO - mozprocess == 0.23 09:02:49 INFO - mozprofile == 0.28 09:02:49 INFO - mozrunner == 6.12 09:02:49 INFO - mozscreenshot == 0.1 09:02:49 INFO - mozsystemmonitor == 0.3 09:02:49 INFO - moztest == 0.7 09:02:49 INFO - mozversion == 1.4 09:02:49 INFO - psutil == 3.1.1 09:02:49 INFO - requests == 1.2.3 09:02:49 INFO - wsgiref == 0.1.2 09:02:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb83b0>, '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 0x2bb92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9740>, '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': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:02:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:49 INFO - Using env: {'DISPLAY': ':0', 09:02:49 INFO - 'HOME': '/home/cltbld', 09:02:49 INFO - 'LANG': 'en_US.UTF-8', 09:02:49 INFO - 'LANGUAGE': 'en_US:en', 09:02:49 INFO - 'LOGNAME': 'cltbld', 09:02:49 INFO - 'MAIL': '/var/mail/cltbld', 09:02:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:49 INFO - 'MOZ_NO_REMOTE': '1', 09:02:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:49 INFO - 'NO_EM_RESTART': '1', 09:02:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:49 INFO - 'PWD': '/builds/slave/test', 09:02:49 INFO - 'SHELL': '/bin/bash', 09:02:49 INFO - 'SHLVL': '1', 09:02:49 INFO - 'TERM': 'linux', 09:02:49 INFO - 'TMOUT': '86400', 09:02:49 INFO - 'USER': 'cltbld', 09:02:49 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 09:02:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:49 INFO - '_': '/tools/buildbot/bin/python'} 09:02:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:02:50 INFO - Cleaning up... 09:02:50 INFO - Return code: 0 09:02:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:02:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bba180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb83b0>, '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 0x2bb92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9740>, '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': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:02:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:02:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:02:50 INFO - Using env: {'DISPLAY': ':0', 09:02:50 INFO - 'HOME': '/home/cltbld', 09:02:50 INFO - 'LANG': 'en_US.UTF-8', 09:02:50 INFO - 'LANGUAGE': 'en_US:en', 09:02:50 INFO - 'LOGNAME': 'cltbld', 09:02:50 INFO - 'MAIL': '/var/mail/cltbld', 09:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:50 INFO - 'MOZ_NO_REMOTE': '1', 09:02:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:50 INFO - 'NO_EM_RESTART': '1', 09:02:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:50 INFO - 'PWD': '/builds/slave/test', 09:02:50 INFO - 'SHELL': '/bin/bash', 09:02:50 INFO - 'SHLVL': '1', 09:02:50 INFO - 'TERM': 'linux', 09:02:50 INFO - 'TMOUT': '86400', 09:02:50 INFO - 'USER': 'cltbld', 09:02:50 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 09:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:50 INFO - '_': '/tools/buildbot/bin/python'} 09:02:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:02:50 INFO - Cleaning up... 09:02:50 INFO - Return code: 0 09:02:50 INFO - Running post-action listener: _resource_record_post_action 09:02:50 INFO - Running post-action listener: _start_resource_monitoring 09:02:50 INFO - Starting resource monitoring. 09:02:50 INFO - ##### 09:02:50 INFO - ##### Finished create-virtualenv step (success) 09:02:50 INFO - ##### 09:02:50 INFO - ##### 09:02:50 INFO - ##### Running install step. 09:02:50 INFO - ##### 09:02:50 INFO - Running pre-action listener: _resource_record_pre_action 09:02:50 INFO - Running main action method: install 09:02:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:50 INFO - Reading from file tmpfile_stdout 09:02:50 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:02:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:02:50 INFO - Reading from file tmpfile_stdout 09:02:50 INFO - Output received: 09:02:50 INFO - Usage: mozinstall [options] installer 09:02:50 INFO - Options: 09:02:50 INFO - -h, --help show this help message and exit 09:02:50 INFO - -d DEST, --destination=DEST 09:02:50 INFO - Directory to install application into. [default: 09:02:50 INFO - "/builds/slave/test"] 09:02:50 INFO - --app=APP Application being installed. [default: firefox] 09:02:50 INFO - mkdir: /builds/slave/test/build/application 09:02:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:02:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:03:07 INFO - Reading from file tmpfile_stdout 09:03:07 INFO - Output received: 09:03:07 INFO - /builds/slave/test/build/application/firefox/firefox 09:03:07 INFO - Running post-action listener: _resource_record_post_action 09:03:07 INFO - ##### 09:03:07 INFO - ##### Finished install step (success) 09:03:07 INFO - ##### 09:03:07 INFO - ##### 09:03:07 INFO - ##### Running run-tests step. 09:03:07 INFO - ##### 09:03:07 INFO - Running pre-action listener: _resource_record_pre_action 09:03:07 INFO - Running main action method: run_tests 09:03:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:07 INFO - Python 2.7.3 09:03:07 INFO - Return code: 0 09:03:07 INFO - grabbing minidump binary from tooltool 09:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:07 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb83b0>, '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 0x2bb92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bb9740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:03:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:03:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:03:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:03:07 INFO - Return code: 0 09:03:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:03:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:03:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:03:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:03:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:03:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:03:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:03:07 INFO - Using env: {'DISPLAY': ':0', 09:03:07 INFO - 'HOME': '/home/cltbld', 09:03:07 INFO - 'LANG': 'en_US.UTF-8', 09:03:07 INFO - 'LANGUAGE': 'en_US:en', 09:03:07 INFO - 'LOGNAME': 'cltbld', 09:03:07 INFO - 'MAIL': '/var/mail/cltbld', 09:03:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:03:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:07 INFO - 'MOZ_NO_REMOTE': '1', 09:03:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:07 INFO - 'NO_EM_RESTART': '1', 09:03:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:07 INFO - 'PWD': '/builds/slave/test', 09:03:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:07 INFO - 'SHELL': '/bin/bash', 09:03:07 INFO - 'SHLVL': '1', 09:03:07 INFO - 'TERM': 'linux', 09:03:07 INFO - 'TMOUT': '86400', 09:03:07 INFO - 'USER': 'cltbld', 09:03:07 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808', 09:03:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:07 INFO - '_': '/tools/buildbot/bin/python'} 09:03:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:03:07 INFO - mozversion application_buildid: 20160707082722 09:03:07 INFO - mozversion application_changeset: e2f5a7c70b47be82f2a8f85cac59482c8f77550a 09:03:07 INFO - mozversion application_display_name: Nightly 09:03:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:03:07 INFO - mozversion application_name: Firefox 09:03:07 INFO - mozversion application_remotingname: firefox 09:03:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:03:07 INFO - mozversion application_vendor: Mozilla 09:03:07 INFO - mozversion application_version: 50.0a1 09:03:07 INFO - mozversion platform_buildid: 20160707082722 09:03:07 INFO - mozversion platform_changeset: e2f5a7c70b47be82f2a8f85cac59482c8f77550a 09:03:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:03:07 INFO - mozversion platform_version: 50.0a1 09:03:07 INFO - using testdate: 1467907387 09:03:07 INFO - actual date: 1467907387 09:03:07 INFO - starting webserver on 'localhost:59205' 09:03:07 INFO - SUITE-START | Running 2 tests 09:03:07 INFO - TEST-START | dromaeo_css 09:03:07 INFO - operating with platform_type : linux_ 09:03:07 INFO - Initialising browser for dromaeo_css test... 09:03:07 INFO - TEST-INFO | started process 20692 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGjf9Qi/profile http://localhost:59205/getInfo.html) 09:03:11 INFO - PROCESS | 20692 | __metrics Screen width/height:1600/1200 09:03:11 INFO - PROCESS | 20692 | colorDepth:24 09:03:11 INFO - PROCESS | 20692 | Browser inner width/height: 1024/697 09:03:11 INFO - PROCESS | 20692 | __metrics 09:03:12 INFO - PROCESS | 20692 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:03:13 INFO - TEST-INFO | 20692: exit 0 09:03:13 INFO - Browser initialized. 09:03:13 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:13 INFO - TEST-INFO | started process 20773 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGjf9Qi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:25 INFO - PROCESS | 20773 | RSS: Main: 159346688 09:03:25 INFO - PROCESS | 20773 | 09:04:16 INFO - PROCESS | 20773 | 1467907456679 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] 09:06:29 INFO - PROCESS | 20773 | Cycle 1(1): loaded http://localhost:59205/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59205/tests/dromaeo/cssquery-ext.html) 09:06:30 INFO - PROCESS | 20773 | RSS: Main: 238002176 09:06:30 INFO - PROCESS | 20773 | 09:09:32 INFO - PROCESS | 20773 | Cycle 1(1): loaded http://localhost:59205/tests/dromaeo/cssquery-ext.html (next: http://localhost:59205/tests/dromaeo/cssquery-jquery.html) 09:09:33 INFO - PROCESS | 20773 | RSS: Main: 246071296 09:09:33 INFO - PROCESS | 20773 | 09:12:35 INFO - PROCESS | 20773 | Cycle 1(1): loaded http://localhost:59205/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59205/tests/dromaeo/cssquery-mootools.html) 09:12:36 INFO - PROCESS | 20773 | RSS: Main: 252121088 09:12:36 INFO - PROCESS | 20773 | 09:15:38 INFO - PROCESS | 20773 | Cycle 1(1): loaded http://localhost:59205/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59205/tests/dromaeo/cssquery-prototype.html) 09:15:39 INFO - PROCESS | 20773 | RSS: Main: 243216384 09:15:39 INFO - PROCESS | 20773 | 09:18:42 INFO - PROCESS | 20773 | Cycle 1(1): loaded http://localhost:59205/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59205/tests/dromaeo/cssquery-yui.html) 09:18:42 INFO - PROCESS | 20773 | RSS: Main: 223793152 09:18:42 INFO - PROCESS | 20773 | 09:21:45 INFO - PROCESS | 20773 | Cycle 1(1): loaded http://localhost:59205/tests/dromaeo/cssquery-yui.html (next: http://localhost:59205/tests/dromaeo/cssquery-dojo.html) 09:21:45 INFO - PROCESS | 20773 | RSS: Main: 256118784 09:21:45 INFO - PROCESS | 20773 | 09:21:45 INFO - PROCESS | 20773 | __start_tp_report 09:21:45 INFO - PROCESS | 20773 | _x_x_mozilla_page_load 09:21:45 INFO - PROCESS | 20773 | _x_x_mozilla_page_load_details 09:21:45 INFO - PROCESS | 20773 | |i|pagename|runs| 09:21:45 INFO - PROCESS | 20773 | |0;dojo.html;2570.3574106473384;2576.3559110222445;2603.284096873359;2620.240130362195;2620.4497055618317;4640.350350950869;4667.649926255527;4673.9065218695605;4698.342232087506;4708.670393072486;4689.835855745049;4717.410323709536;4746.145693775119;4777.498362671921;4792.329073929653;4331.935020974688;4345.67407444443;4360.040791025969;4404.537523560028;4409.559044095597;4296.119295544421;4345.130973805236;4373.759443230637;4378.211921854065;4382.145481631083;4260.936085958713;4289.2922667759785;4292.892677683051;4293.076988447468;4332.458442694663;4330.480342358937;4348.108637729258;4349.956500434987;4358.607725304706;4367.0174210802315;4245.511766146902;4282.914341713179;4333.674974376903;4339.1104823511105;4340.717853339523;4557.886052848672;4609.03210325832;4632.583067523905;4696.882577935545;4738.715677059353;4960.107180707508;4963.60291176705;5001.924971125436;5011.32347133136;5015;4924.236743304794;4942.110420124376;4970.105381031413;5010.974945125287;5031.698098114089;4810.326554282403;4818.734969576707;4820.204666230068;4822.107910036584;4823.493533179036;5252.789888404421;5259.237410575522;5276.419593844674;5288.576913846883;5291.1798671206025;4775.403074615673;4886.682365646222;4949.084419382426;4953.578945789576;5046.520580544842;5036.445990940993;5042.268871013755;5061.164907790247;5083.796648134107;5102.948970510248;4993.80024799004;4995.525425084611;4999.500049995044;5007.223880298559;5051.191809310566;4886.047220791983;4920.065187614342;4920.089783390084;4966.602671786249;4967.925481117786;4225.070484493406;4243.3634954757035;4245.61789438952;4255.553166917429;4293.613574778285;3711.239195964875;3724.2551489701627;3725.385311423639;3751.5123034005405;3759.3984962405884;4247.49030116388;4285.785710714452;4288.120935208275;4321.222180007629;4376.234159022159;5122.795088196431;5128.666636668605;5172.8965420691375;5175.45657705943;5187.792488300502;4348.326009468537;4370.366296886934;4371.628411585051;4382.364557139261;4418.8453404130705;4386.0569977455;4463.977680111579;4514.909701805946;4526.049529598821;4574.245249533857;4743.312219728189;4780.593649539828;4794.7842347093765;4795.064962332312;4838.056578967208;5119.948800511947;5122;5127.1540195867265;5141.408737995179;5182.144946083854;4656.580907718321;4658.86023419298;4699.17764391237;4768.1417344878255;4798.47216806144;4940.160172770709;4963.8262660806695;4973.651844370859;4977.925331119964;5007.3240112584335;5037.496250374933;5048.570871475966;5052.646314757932;5052.924206136838;5073.3911930568565;4728.739919304471;4769.189237829647;4817.662763606514;4844.346013288161;4940.654154209171;4844.757762111951;4852.902941941071;4860.076585448754;4860.805567777249;4913.97543012297;4793.089313030513;4796.112719146969;4798.928016079832;4855.247436647325;4855.95144048555;4850.902981940412;4866.440359358719;4882.145624515767;4906.607471402351;4975.253711943093;4813.446453657874;4825.059113472979;4834.830780922651;4845.127876982177;4883.413990321184 09:21:45 INFO - PROCESS | 20773 | |1;ext.html;571.6198727845982;573.1545969694698;585.1982783586487;585.6427579176701;588.5909293041335;26387.340316492096;29518.85240573797;29560.852195739044;29596.704032959664;29624.40751184975;29756;41092.38361424574;42147;42357.36463953043;46202.537974620245;7744.148143704202;7848.646810893495;7854.175311592287;7865.410094242926;7869;7599.696012159507;7793.103793063813;7885.172056934025;7888.329491993188;7893.842123157533;10116.393016419002;10399.896001039968;10414.114800241989;10721.35671859687;10731.731706707316;10118.544665490033;10223.591056357736;10230.181585473181;10251.948740256326;10265.538050787733;7479.065116860393;9835;10056.748581285454;10133.493325333704;10226.335288206317;39493.80253098745;39789.40315895264;39831;39858.40212396816;39899.20201595952;2846.558783388578;3001.83489908055;3034.5913516637925;3168.065420700899;3324.401607710611;29340.736428901906;29951.10146695581;29957;30472.84763576168;30554.236144096576;3500.789952602852;3510.5611798525183;3536.5756109267054;3555.4489054196642;3568.411212149992;183667;184005;184088;184679.07660461613;185246.99999999866;52964.205536916976;53534;54130;54166.72916635471;54172;55297.72351138219;55966.72016639891;56158.71920640371;56227;56237.718811405684;14571.708565828623;14925.328360223815;14979.925100374647;14990.850091499164;15026.398944042116;10731.731706707395;10740.94629526863;10765.24643274979;10780.137588992862;10786;14380.640483987983;16103.597410064607;16489.422870199487;16493.34026638921;16742.916285418738;5889.1755154278435;5921.03948025987;5931.347551769302;6098.664573448503;6123.877522449526;799.1089934722743;876.2886597938119;894.2756367342473;962.8988956159642;974.4737841565475;27476;28464.573031404547;28820;28933.421331573252;29122.563161552593;945.6784693204206;964.8890377606525;981.617169303972;995.1839491616805;1024.7694268789462;14708.485203017843;14812.777808332883;14891.70216595662;15091.622709432351;15095.924520377548;353748;354806;357263;359965;360063;48070.5192948066;48564.75717621389;48622.27066594004;48627.5137248623;48699.26951095736;844.0335815491228;1084.2572837606265;1117.3798541809374;1170.1341007654275;1201.4113084588664;26824.731752682223;28869.566956496088;29282.97509587154;29490.410191795614;29657.5551366734;41987.37018944657;42339.364909525764;42481.36277955771;42516.57483425126;42528.57471425246;280100;281259;281585;282695;282828;22118.447038824157;24518.38704032342;24980.37549061288;25459.61810572806;25725.228243152735;41189.79405102955;41489.37765933574;41710.58289417067;41723.37414938718;42334.57665423306;35666;35962.820185898905;36084.27831443304;36360.63639363572;36487.45268821023;36124.4581331275;36189.0952726184;36196.27607447889;36365;37719.81140094282;36063.63936360603;36484.27031459409;36545.634543654225;36820.81589592035;37261.62738372581;32164.195895101875;33407;33537.664623353456;33609.327813444084;33610.159746006546;25155;25355.36611584725;25761;25840.483190336465;25852.22443326703 09:21:45 INFO - PROCESS | 20773 | |2;jquery.html;343.0054234625653;369.85205917632925;370.98516059357627;372.51758972131097;387.347319766194;12963.935180324097;13299.7340053199;15032.849671503282;17488.300467981266;17563.82436175638;16227.94518356306;17660.02869842158;17762.200700968457;17766.555836104104;17786.733199001992;5077.187649976014;5261.973690131544;5291.100512912814;5298.205269209611;5298.205269209611;3788.242351529705;6027.758889644409;6331.113644089808;6350.809475715713;6415.294317625057;6093.512518998492;6275.623462592236;6294.213223347082;6331.77838775643;6341.5877967932165;6144.2012538369945;6147.354527774564;6148.631082135086;6188;6193.504519638418;5165.483451654842;5374.811881584125;5480.958617862593;5501.614886957915;5503.036968530491;21258.14967401302;21825.890870545703;22023.66964495534;22141.114355425765;22187.778122218733;1839.8068202838776;2078.293380250707;2529.6837895263093;2533.7972962163167;2534.746525347469;13088.280144592043;13843.238621875793;14093;14363.49727759534;14421.78367324491;1727.93952211672;1751.422030729856;1765.1174412793603;1796.4520821149545;1803.422904670506;72348.76512348723;73094.63452682756;73284;73987;74430.02279908796;9504.714858554255;9577.664781732607;9740.853887191697;9801.607935682636;9927.950360248153;13302.334883255799;13472.124311919693;13492.5277615283;13507.797383039262;13510.729785404237;7396.96301518489;7438.367738742159;7438.516496427823;7561.281678240558;7624.923750762532;7184;7186.640667966685;7192.820179495555;7280.9635951820965;7286.781396558112;6157.476614487729;6216.378362163748;6239.563758891022;6287.371262873768;6316.641921986795;1751.8510926571385;1776.4404212672966;1795.2818872451123;1796.8742188046972;1797.068151736888;870.9303255739652;921.8755468011733;928.3547934185676;930.9627614895463;943.1605870775015;16974.40589579359;17415.47753567395;17848.91075544614;17862.821371786376;18188.272469101088;887.3655336434481;905.0723008915783;915.8214148241162;922.1275726113244;960.5053397500293;9830.852537211947;10154.543045562967;10314.123299519475;10337.06966373015;10361.948190259;366990;374096;374140;375376;375694;67153.66423167853;69381.65309173422;69718.30281697118;69818.65090674514;70543.29456705367;863.9006514250941;881.1540920716056;892.0499667853715;896.1128482801979;902.9051949545335;15498.302576384316;15786.763198551802;15980.760288595913;16001.199940003185;16027.919601886768;34539.13652158716;34626.65373346234;35601.64398356087;36133;36479.45280820737;45859.54140458686;46148;46324.7683761579;46885.76557117193;47846;7380.597686439559;7410.6665200065045;7419.851602968019;7437.293457121565;7685.193054729173;10881.238313317992;11208.103351732005;11272.436378180893;11274.774504510027;11386.715332116763;9451.952740236255;9627.855582166414;9697.6605818796;9709.902900971183;9837.557309920927;10282;10344.27590068718;10386.584536618451;10503.264771466229;10505.054545090978;9673.37123086997;9776.657816976372;9962.302638815214;10027.749306267402;10116.08955194036;7585.16563178061;7645.923540764744;7647.770566883002;7698.153203147761;7729.995100636881;9344.672936447192;9349.27567266078;9442.480663563296;9621.56702948355;9739.366941148803 09:21:45 INFO - PROCESS | 20773 | |3;mootools.html;1245.7944439167538;1353.844307904591;1412.2867951684398;1495.200067963639;1706.0275642883553;6687.097241872346;7424.814379640512;7694.269044440784;7710.807229819257;7883.763487095382;7657.310842024217;7809.765707028798;7811.2579304966075;7818.569978651186;7821.061472623279;1815.3464752689022;1890.6407782521346;1894.4032629721594;2193.747719012314;2230.7769223077726;1907.2275728330048;2136.6367717487988;2234.1510226114056;2235.541713948645;2253.2339004738387;2179.814715749163;2206.1947389202905;2214.1254204589177;2219.6892435059103;2220.3338998300446;1785.9464216073409;2109.2301310021885;2109.2828438330966;2113.3448630924463;2113.968290475644;2015.3752336775647;2108.989455052715;2114.1860383752214;2125.8405619578593;2129.9254526091668;7849.725259615886;7879.330256928168;7900.288973992311;7902.091259505173;7914.129445760962;4524.524924882873;4639.744814035227;4643.698159619647;4646.256598944153;4649.395578574764;2841.417509410566;2882.6684931232967;2935.6330458692664;2942.9705702943124;2944.646642402904;930.4556834251971;938.976525586859;940.7930255343813;962.7352478068517;963.4171326347599;10755.784884302348;10793.67618971432;10823.134149268119;10851.945740271327;10867.619633312876;4034.3545032794614;4133.91732165361;4220.303649897794;4316.050468896838;4316.719413238192;9147.17675409203;9152;9153.954230228806;9164.40431371972;9167.77080572991;4656.767161641972;4661.906761864744;4667.206574882278;4669.95330046702;4671.43942726875;3649.927001459956;3665.816709164531;4272.401863739079;4277.251480990814;4279.935800962988;3654.5066416033496;3732.9066773330883;3803.809809509569;3829.463875057494;3832.6550610444638;1205.52984336109;1205.9095567832449;1212.5877201751448;1214.6598952293373;1220.7070303127364;701.4598758955677;756.2701992577189;799.2886331165267;807.7334479621711;830;5034.043531729033;5119.104156772624;5178.766955487012;5345.1714984177515;5395.541378982831;404.2117870153213;417.92268430339874;419.490319262098;424.67512353049943;428.83143434141715;3330.633630300665;3349.1961929136833;3377.8648854046055;3388.1698983748647;3428.5542879425516;9314.254859611216;9659.758506037406;9691.757706057406;9758.219342452587;9776.657816976514;9642.807143857084;9752.95123524392;9756.609735610638;9764.462954537568;9768.609255629695;7289.380402665833;7301.89047164293;7331.083614548182;7367.4106071515225;7390.002349682832;5101.566366858859;5157.329547158845;5158.587313014875;5184.066867963803;5196.428392876856;8897.822043559223;8990.91009089927;9073.001969783187;9102.726918192464;9145.451272923647;8068.919310806817;8987.236084932592;9221.49281789508;9232.815343693223;9318.813623727623;6501.382368674968;6793.864122717419;6793.898091528532;6952.409045231212;6962.477814164059;6981.895271571032;7028.683709233199;7091.574505529685;7107.964460177666;7113.0753002109395;6651.50113741481;6701.899471508029;6711.161104861892;6780.864382712417;6902.551334163263;6755.3244675534015;6814.557053791487;6856.760013399507;6869.072675188985;6903.309669033057;5800.7099645018425;5846.327672317738;5850.473457388855;5859.824205273849;5864.208331875313;4319.200947824663;4489.393931819162;4510.807142111345;4546.977265113654;4592.632589392907;4440.578145076212;4631.814727411001;4645.674802763774;4677.602403795579;4802.255650374198 09:21:45 INFO - PROCESS | 20773 | |4;prototype.html;300.52366998307684;302.7850226339299;308.487910069285;308.7021024711156;308.73757306289656;4898.5101489850995;4929.926051109236;4942.110420124381;4947.936193718349;4963.726995015281;4832.4442689090765;4834.371531700883;4883.316335713003;4892.192788189945;4904.435989861167;3647.689946354563;3743.494628225182;3767.792771397572;3853.5761066282685;3879.7778699709643;3815.1606646537716;3833.271678381099;3850.5752871437608;3854.6338097880657;3863.517060367454;3635.509206257161;3812.82842272097;3821.4840996465537;3828.4065969774592;3831.712621553381;3700.5374328208973;3774.1885494618787;3775.093977445421;3791.3554695701796;3811.9237615247816;3336.1659585103725;3340.1482621931495;3345.1971526833627;3361.83190840457;3377.7804442711144;4706.3411122442885;4725.621950243973;4726.314684370781;4729.527047295243;4740.857774266743;4248.617624413787;4321.157374312011;4354.934675979863;4360.389545463638;4377.627901628365;5048.772805223737;5057.544820966131;5061.240813877911;5071.898562028776;5085.491450854922;3593.3352329819068;3662.1943172502006;3684.2631473705364;3685.723570732179;3687.8893633191046;5102.948970510284;5252.763625636855;5259;5275.4460781618145;5301.1783173608155;3718.535183102112;3737.8131093445218;3745.400735882246;3747.7189210809297;3752.6622603965775;3809.8476060957805;3811.104390468238;3812.4281357796553;3812.5043744313616;3812.942805857914;4342.1532801103485;4345.130973805251;4348.478182618106;4364.410804541346;4366.912661746811;4868.659193856396;5020.874478138076;5033.899322013613;5034.84895453137;5052.898942021139;1307.4181989014987;1330.946762129504;1332.253937794838;1335.4791631263815;1337.498438085718;870.255931178833;877.5217506458868;881.3345923827469;890.857462805961;892.2014796756866;4313.978430107893;4331.805068771912;4485.887852803706;4505.909881802411;4515.887102822456;5197.402298735694;5200.739963001834;5209.609279304067;5227.764750586232;5242.9213561796605;4244.792707791931;4460.821567137343;4481.395011673448;4495.662825288116;4500.639948804088;2743.986280068587;2756.3109222694325;2770.4320614274025;2772.1960631416664;2784.5683918992186;4776.328285100187;4871.415430148405;4879.950810575743;4891.413030436299;4906.656534042654;5091.694498330112;5258.342707161605;5291.312129423227;5295.4969277918535;5311.787528498817;4871.76027941415;4873.756312184377;4875.34182885313;4878.292647566154;4898.24077268024;5198.844034678966;5200.479952004769;5218.165093585008;5257.4216836147225;5261.894762104813;4994.375703037121;5117.667351622133;5139.254808052886;5144.254083157846;5167.328247327823;4835.734034628129;4857.757112144449;4858.878528036827;4865.683730557503;4876.439209490812;3401.2857299967286;3417.8979927345;3429.3141371725255;3442.6041005284715;3442.913927151841;4508.436445444319;4674.766261686834;4690.3433519306645;4703.788329525248;4720.268358404453;4381.67137464685;4417.271150260171;4491.191585514639;4528.660350473661;4537.682362234612;4497.4708599076475;4534.319852022223;4560.931586026146;4582.312653101928;4582.381378513991;4215.409842621974;4431.667624928079;4471.863526238151;4478.529754375743;4506.481754598263;4523.547645235451;4533.818647254205;4541.409616749801;4553.931691024571;4567.748773817339;4484.417025786757;4485.394471746403;4512.05246898155;4519.68362214655;4524.298733696282 09:21:45 INFO - PROCESS | 20773 | |5;yui.html;659.8449364399365;684.5653010338434;734.1703874621677;753.9095308562974;761.7460648079267;12141.57504487343;12801.295928723917;12900.870991290085;12965.546205882796;13086.803697944539;13160.868391316084;13181.340932953339;13314.866851331484;13315.733685326288;13321.933390333059;612.9325774164838;737.5906371963558;772.9381649468057;793.6825269892032;799.6561478564215;799.2207597592359;799.940004499662;802.5184889066543;802.9678812847508;803.4737247103157;846.272205902923;847.1316900177306;847.2036285891273;847.267113946435;847.7795773098977;643.2184895352146;663.7610460234312;664.0703015777901;665.5806841689705;837.0666706622134;572.4790440698944;592.6444133519897;597.2981746447949;597.6563476001317;597.8744463662636;13003.154794938298;13024.153430027018;13504.662383440493;13522.661933451644;13540.187588744708;12860.228386296852;13333.866661333457;13410.93294533531;13510;13748.862511374859;12111.45498452563;12127.575534856325;12173.939130304381;12254.44854981519;12352.62942111738;4283.678724095706;4290.828366865321;4313.28830742927;4317.11499142675;4402.669799765031;57989;58092.99999999958;58128;58243;58497;10310.896891031143;10388.532516036796;10595.94702026485;10651.786964260671;10763.461826908624;19332.613347732968;19340.4197874064;19341.51646208856;19354.8064519353;19356.806431935784;6825.419839313615;6865.9313406866295;6927.58434493932;6949.2008419031445;7004.474664400191;3614.8192590370377;3669.541307336583;3672.1003354178397;3694.833732482045;3704.981475092607;2939.3974235282144;3038.2708150044273;3042.969570304313;3042.984785076105;3044;490.65408886734764;494.1426624805959;496.3200415430883;507.76388979124886;507.85272271040986;521.1817446608787;529.1401472607014;539.7867842202387;554.4427850010713;568.7184843502492;6971.616561089088;7182;7298.927010729824;7329;7369.22623124575;522.8588281164065;525.8869343091254;531.8829857431359;543.5352773378704;585.0755805826783;3619.113317237294;3648.1426864686778;3704;3741.9625803741615;3744.719146064056;53754.462455374945;54077.72961135169;54775;59158;98470.5076474613;50515.747421262655;66757.33242667609;67955.66022169957;84209;84697.57651211704;43894.34158487625;44560.331595025455;44880;46547.53452465432;73686.63156684182;6946.479014074066;7044.506884518035;7122.501424900414;7160;7225.385842203472;42358.5764142367;43906.780466097465;44618.776906115265;45302.77348613368;45524.77237613791;60656.393436065075;63865;64683;64818.351816481234;64998;9550.617975280911;9846.33151971011;9984.301098923006;9987.450690212108;10032.548535316075;10093.444860532447;10283.588656453658;10310.742231444274;10328.225383096147;10669.839952400875;8198.918010819816;9442.716718498456;9520.714378568655;10029.29794914349;10359;11755.235909665844;12152.696182595153;12165.574204902787;12249.387530623611;12331.50673973031;10355.585776568852;10659.307145035547;10776.137908967421;10805.945970270099;10842.403667798348;10054;10133.79732405333;10314.48427578603;10421.114205292331;10658.89341106579;9892.752681183028;10219.108482514972;10224.795504090025;10287.331323463952;10340.224483163642 09:21:45 INFO - PROCESS | 20773 | __end_tp_report 09:21:45 INFO - PROCESS | 20773 | __start_cc_report 09:21:45 INFO - PROCESS | 20773 | _x_x_mozilla_cycle_collect,1080 09:21:45 INFO - PROCESS | 20773 | __end_cc_report 09:21:45 INFO - PROCESS | 20773 | __startTimestamp1467908505471__endTimestamp 09:21:45 INFO - PROCESS | 20773 | 09:21:45 INFO - PROCESS | 20773 | ------- Summary: start ------- 09:21:45 INFO - PROCESS | 20773 | Number of tests: 6 09:21:45 INFO - PROCESS | 20773 | 09:21:45 INFO - PROCESS | 20773 | [#0] dojo.html Cycles:165 Average:4653.56 Median:4796.11 stddev:495.76 (10.3%) stddev-sans-first:469.57 09:21:45 INFO - PROCESS | 20773 | Values: 2570.4 2576.4 2603.3 2620.2 2620.4 4640.4 4667.6 4673.9 4698.3 4708.7 4689.8 4717.4 4746.1 4777.5 4792.3 4331.9 4345.7 4360.0 4404.5 4409.6 4296.1 4345.1 4373.8 4378.2 4382.1 4260.9 4289.3 4292.9 4293.1 4332.5 4330.5 4348.1 4350.0 4358.6 4367.0 4245.5 4282.9 4333.7 4339.1 4340.7 4557.9 4609.0 4632.6 4696.9 4738.7 4960.1 4963.6 5001.9 5011.3 5015.0 4924.2 4942.1 4970.1 5011.0 5031.7 4810.3 4818.7 4820.2 4822.1 4823.5 5252.8 5259.2 5276.4 5288.6 5291.2 4775.4 4886.7 4949.1 4953.6 5046.5 5036.4 5042.3 5061.2 5083.8 5102.9 4993.8 4995.5 4999.5 5007.2 5051.2 4886.0 4920.1 4920.1 4966.6 4967.9 4225.1 4243.4 4245.6 4255.6 4293.6 3711.2 3724.3 3725.4 3751.5 3759.4 4247.5 4285.8 4288.1 4321.2 4376.2 5122.8 5128.7 5172.9 5175.5 5187.8 4348.3 4370.4 4371.6 4382.4 4418.8 4386.1 4464.0 4514.9 4526.0 4574.2 4743.3 4780.6 4794.8 4795.1 4838.1 5119.9 5122.0 5127.2 5141.4 5182.1 4656.6 4658.9 4699.2 4768.1 4798.5 4940.2 4963.8 4973.7 4977.9 5007.3 5037.5 5048.6 5052.6 5052.9 5073.4 4728.7 4769.2 4817.7 4844.3 4940.7 4844.8 4852.9 4860.1 4860.8 4914.0 4793.1 4796.1 4798.9 4855.2 4856.0 4850.9 4866.4 4882.1 4906.6 4975.3 4813.4 4825.1 4834.8 4845.1 4883.4 09:21:45 INFO - PROCESS | 20773 | 09:21:45 INFO - PROCESS | 20773 | [#1] ext.html Cycles:180 Average:43704.46 Median:3523.57 stddev:74433.74 (2112.5%) stddev-sans-first:74572.09 09:21:45 INFO - PROCESS | 20773 | Values: 571.6 573.2 585.2 585.6 588.6 26387.3 29518.9 29560.9 29596.7 29624.4 29756.0 41092.4 42147.0 42357.4 46202.5 7744.1 7848.6 7854.2 7865.4 7869.0 7599.7 7793.1 7885.2 7888.3 7893.8 10116.4 10399.9 10414.1 10721.4 10731.7 10118.5 10223.6 10230.2 10251.9 10265.5 7479.1 9835.0 10056.7 10133.5 10226.3 39493.8 39789.4 39831.0 39858.4 39899.2 2846.6 3001.8 3034.6 3168.1 3324.4 29340.7 29951.1 29957.0 30472.8 30554.2 3500.8 3510.6 3536.6 3555.4 3568.4 183667.0 184005.0 184088.0 184679.1 185247.0 52964.2 53534.0 54130.0 54166.7 54172.0 55297.7 55966.7 56158.7 56227.0 56237.7 14571.7 14925.3 14979.9 14990.9 15026.4 10731.7 10740.9 10765.2 10780.1 10786.0 14380.6 16103.6 16489.4 16493.3 16742.9 5889.2 5921.0 5931.3 6098.7 6123.9 799.1 876.3 894.3 962.9 974.5 27476.0 28464.6 28820.0 28933.4 29122.6 945.7 964.9 981.6 995.2 1024.8 14708.5 14812.8 14891.7 15091.6 15095.9 353748.0 354806.0 357263.0 359965.0 360063.0 48070.5 48564.8 48622.3 48627.5 48699.3 844.0 1084.3 1117.4 1170.1 1201.4 26824.7 28869.6 29283.0 29490.4 29657.6 41987.4 42339.4 42481.4 42516.6 42528.6 280100.0 281259.0 281585.0 282695.0 282828.0 22118.4 24518.4 24980.4 25459.6 25725.2 41189.8 41489.4 41710.6 41723.4 42334.6 35666.0 35962.8 36084.3 36360.6 36487.5 36124.5 36189.1 36196.3 36365.0 37719.8 36063.6 36484.3 36545.6 36820.8 37261.6 32164.2 33407.0 33537.7 33609.3 33610.2 25155.0 25355.4 25761.0 25840.5 25852.2 09:21:45 INFO - PROCESS | 20773 | 09:21:45 INFO - PROCESS | 20773 | [#2] jquery.html Cycles:180 Average:23837.00 Median:5294.65 stddev:61550.83 (1162.5%) stddev-sans-first:61698.22 09:21:45 INFO - PROCESS | 20773 | Values: 343.0 369.9 371.0 372.5 387.3 12963.9 13299.7 15032.8 17488.3 17563.8 16227.9 17660.0 17762.2 17766.6 17786.7 5077.2 5262.0 5291.1 5298.2 5298.2 3788.2 6027.8 6331.1 6350.8 6415.3 6093.5 6275.6 6294.2 6331.8 6341.6 6144.2 6147.4 6148.6 6188.0 6193.5 5165.5 5374.8 5481.0 5501.6 5503.0 21258.1 21825.9 22023.7 22141.1 22187.8 1839.8 2078.3 2529.7 2533.8 2534.7 13088.3 13843.2 14093.0 14363.5 14421.8 1727.9 1751.4 1765.1 1796.5 1803.4 72348.8 73094.6 73284.0 73987.0 74430.0 9504.7 9577.7 9740.9 9801.6 9928.0 13302.3 13472.1 13492.5 13507.8 13510.7 7397.0 7438.4 7438.5 7561.3 7624.9 7184.0 7186.6 7192.8 7281.0 7286.8 6157.5 6216.4 6239.6 6287.4 6316.6 1751.9 1776.4 1795.3 1796.9 1797.1 870.9 921.9 928.4 931.0 943.2 16974.4 17415.5 17848.9 17862.8 18188.3 887.4 905.1 915.8 922.1 960.5 9830.9 10154.5 10314.1 10337.1 10361.9 366990.0 374096.0 374140.0 375376.0 375694.0 67153.7 69381.7 69718.3 69818.7 70543.3 863.9 881.2 892.0 896.1 902.9 15498.3 15786.8 15980.8 16001.2 16027.9 34539.1 34626.7 35601.6 36133.0 36479.5 45859.5 46148.0 46324.8 46885.8 47846.0 7380.6 7410.7 7419.9 7437.3 7685.2 10881.2 11208.1 11272.4 11274.8 11386.7 9452.0 9627.9 9697.7 9709.9 9837.6 10282.0 10344.3 10386.6 10503.3 10505.1 9673.4 9776.7 9962.3 10027.7 10116.1 7585.2 7645.9 7647.8 7698.2 7730.0 9344.7 9349.3 9442.5 9621.6 9739.4 09:21:45 INFO - PROCESS | 20773 | 09:21:45 INFO - PROCESS | 20773 | [#3] mootools.html Cycles:180 Average:5098.33 Median:5067.80 stddev:2918.70 (57.6%) stddev-sans-first:2912.52 09:21:45 INFO - PROCESS | 20773 | Values: 1245.8 1353.8 1412.3 1495.2 1706.0 6687.1 7424.8 7694.3 7710.8 7883.8 7657.3 7809.8 7811.3 7818.6 7821.1 1815.3 1890.6 1894.4 2193.7 2230.8 1907.2 2136.6 2234.2 2235.5 2253.2 2179.8 2206.2 2214.1 2219.7 2220.3 1785.9 2109.2 2109.3 2113.3 2114.0 2015.4 2109.0 2114.2 2125.8 2129.9 7849.7 7879.3 7900.3 7902.1 7914.1 4524.5 4639.7 4643.7 4646.3 4649.4 2841.4 2882.7 2935.6 2943.0 2944.6 930.5 939.0 940.8 962.7 963.4 10755.8 10793.7 10823.1 10851.9 10867.6 4034.4 4133.9 4220.3 4316.1 4316.7 9147.2 9152.0 9154.0 9164.4 9167.8 4656.8 4661.9 4667.2 4670.0 4671.4 3649.9 3665.8 4272.4 4277.3 4279.9 3654.5 3732.9 3803.8 3829.5 3832.7 1205.5 1205.9 1212.6 1214.7 1220.7 701.5 756.3 799.3 807.7 830.0 5034.0 5119.1 5178.8 5345.2 5395.5 404.2 417.9 419.5 424.7 428.8 3330.6 3349.2 3377.9 3388.2 3428.6 9314.3 9659.8 9691.8 9758.2 9776.7 9642.8 9753.0 9756.6 9764.5 9768.6 7289.4 7301.9 7331.1 7367.4 7390.0 5101.6 5157.3 5158.6 5184.1 5196.4 8897.8 8990.9 9073.0 9102.7 9145.5 8068.9 8987.2 9221.5 9232.8 9318.8 6501.4 6793.9 6793.9 6952.4 6962.5 6981.9 7028.7 7091.6 7108.0 7113.1 6651.5 6701.9 6711.2 6780.9 6902.6 6755.3 6814.6 6856.8 6869.1 6903.3 5800.7 5846.3 5850.5 5859.8 5864.2 4319.2 4489.4 4510.8 4547.0 4592.6 4440.6 4631.8 4645.7 4677.6 4802.3 09:21:45 INFO - PROCESS | 20773 | 09:21:45 INFO - PROCESS | 20773 | [#4] prototype.html Cycles:180 Average:4122.40 Median:4510.24 stddev:1168.52 (25.9%) stddev-sans-first:1136.04 09:21:45 INFO - PROCESS | 20773 | Values: 300.5 302.8 308.5 308.7 308.7 4898.5 4929.9 4942.1 4947.9 4963.7 4832.4 4834.4 4883.3 4892.2 4904.4 3647.7 3743.5 3767.8 3853.6 3879.8 3815.2 3833.3 3850.6 3854.6 3863.5 3635.5 3812.8 3821.5 3828.4 3831.7 3700.5 3774.2 3775.1 3791.4 3811.9 3336.2 3340.1 3345.2 3361.8 3377.8 4706.3 4725.6 4726.3 4729.5 4740.9 4248.6 4321.2 4354.9 4360.4 4377.6 5048.8 5057.5 5061.2 5071.9 5085.5 3593.3 3662.2 3684.3 3685.7 3687.9 5102.9 5252.8 5259.0 5275.4 5301.2 3718.5 3737.8 3745.4 3747.7 3752.7 3809.8 3811.1 3812.4 3812.5 3812.9 4342.2 4345.1 4348.5 4364.4 4366.9 4868.7 5020.9 5033.9 5034.8 5052.9 1307.4 1330.9 1332.3 1335.5 1337.5 870.3 877.5 881.3 890.9 892.2 4314.0 4331.8 4485.9 4505.9 4515.9 5197.4 5200.7 5209.6 5227.8 5242.9 4244.8 4460.8 4481.4 4495.7 4500.6 2744.0 2756.3 2770.4 2772.2 2784.6 4776.3 4871.4 4880.0 4891.4 4906.7 5091.7 5258.3 5291.3 5295.5 5311.8 4871.8 4873.8 4875.3 4878.3 4898.2 5198.8 5200.5 5218.2 5257.4 5261.9 4994.4 5117.7 5139.3 5144.3 5167.3 4835.7 4857.8 4858.9 4865.7 4876.4 3401.3 3417.9 3429.3 3442.6 3442.9 4508.4 4674.8 4690.3 4703.8 4720.3 4381.7 4417.3 4491.2 4528.7 4537.7 4497.5 4534.3 4560.9 4582.3 4582.4 4215.4 4431.7 4471.9 4478.5 4506.5 4523.5 4533.8 4541.4 4553.9 4567.7 4484.4 4485.4 4512.1 4519.7 4524.3 09:21:45 INFO - PROCESS | 20773 | 09:21:45 INFO - PROCESS | 20773 | [#5] yui.html Cycles:180 Average:15603.69 Median:23519.09 stddev:20641.88 (87.8%) stddev-sans-first:20669.29 09:21:45 INFO - PROCESS | 20773 | Values: 659.8 684.6 734.2 753.9 761.7 12141.6 12801.3 12900.9 12965.5 13086.8 13160.9 13181.3 13314.9 13315.7 13321.9 612.9 737.6 772.9 793.7 799.7 799.2 799.9 802.5 803.0 803.5 846.3 847.1 847.2 847.3 847.8 643.2 663.8 664.1 665.6 837.1 572.5 592.6 597.3 597.7 597.9 13003.2 13024.2 13504.7 13522.7 13540.2 12860.2 13333.9 13410.9 13510.0 13748.9 12111.5 12127.6 12173.9 12254.4 12352.6 4283.7 4290.8 4313.3 4317.1 4402.7 57989.0 58093.0 58128.0 58243.0 58497.0 10310.9 10388.5 10595.9 10651.8 10763.5 19332.6 19340.4 19341.5 19354.8 19356.8 6825.4 6865.9 6927.6 6949.2 7004.5 3614.8 3669.5 3672.1 3694.8 3705.0 2939.4 3038.3 3043.0 3043.0 3044.0 490.7 494.1 496.3 507.8 507.9 521.2 529.1 539.8 554.4 568.7 6971.6 7182.0 7298.9 7329.0 7369.2 522.9 525.9 531.9 543.5 585.1 3619.1 3648.1 3704.0 3742.0 3744.7 53754.5 54077.7 54775.0 59158.0 98470.5 50515.7 66757.3 67955.7 84209.0 84697.6 43894.3 44560.3 44880.0 46547.5 73686.6 6946.5 7044.5 7122.5 7160.0 7225.4 42358.6 43906.8 44618.8 45302.8 45524.8 60656.4 63865.0 64683.0 64818.4 64998.0 9550.6 9846.3 9984.3 9987.5 10032.5 10093.4 10283.6 10310.7 10328.2 10669.8 8198.9 9442.7 9520.7 10029.3 10359.0 11755.2 12152.7 12165.6 12249.4 12331.5 10355.6 10659.3 10776.1 10805.9 10842.4 10054.0 10133.8 10314.5 10421.1 10658.9 9892.8 10219.1 10224.8 10287.3 10340.2 09:21:45 INFO - PROCESS | 20773 | -------- Summary: end -------- 09:21:45 INFO - PROCESS | 20773 | 09:21:46 INFO - TEST-INFO | 20773: exit 0 09:21:46 INFO - TEST-OK | dromaeo_css | took 1118910ms 09:21:46 INFO - TEST-START | kraken 09:21:46 INFO - operating with platform_type : linux_ 09:21:46 INFO - Initialising browser for kraken test... 09:21:46 INFO - TEST-INFO | started process 20973 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxifMJj/profile http://localhost:59205/getInfo.html) 09:21:50 INFO - PROCESS | 20973 | __metrics Screen width/height:1600/1200 09:21:50 INFO - PROCESS | 20973 | colorDepth:24 09:21:50 INFO - PROCESS | 20973 | Browser inner width/height: 1024/697 09:21:50 INFO - PROCESS | 20973 | __metrics 09:21:51 INFO - PROCESS | 20973 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:21:52 INFO - TEST-INFO | 20973: exit 0 09:21:52 INFO - Browser initialized. 09:21:52 INFO - Running cycle 1/1 for kraken test... 09:21:52 INFO - TEST-INFO | started process 21054 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxifMJj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:04 INFO - PROCESS | 21054 | RSS: Main: 159182848 09:22:04 INFO - PROCESS | 21054 | 09:22:07 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59205/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:07 INFO - PROCESS | 21054 | RSS: Main: 217346048 09:22:07 INFO - PROCESS | 21054 | 09:22:10 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59205/tests/kraken/driver.html?testName=audio-dft) 09:22:11 INFO - PROCESS | 21054 | RSS: Main: 203091968 09:22:11 INFO - PROCESS | 21054 | 09:22:14 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59205/tests/kraken/driver.html?testName=audio-fft) 09:22:15 INFO - PROCESS | 21054 | RSS: Main: 271609856 09:22:15 INFO - PROCESS | 21054 | 09:22:17 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59205/tests/kraken/driver.html?testName=audio-oscillator) 09:22:17 INFO - PROCESS | 21054 | RSS: Main: 181825536 09:22:17 INFO - PROCESS | 21054 | 09:22:20 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59205/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:20 INFO - PROCESS | 21054 | RSS: Main: 185778176 09:22:20 INFO - PROCESS | 21054 | 09:22:24 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59205/tests/kraken/driver.html?testName=imaging-darkroom) 09:22:24 INFO - PROCESS | 21054 | RSS: Main: 222375936 09:22:24 INFO - PROCESS | 21054 | 09:22:28 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59205/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:28 INFO - PROCESS | 21054 | RSS: Main: 217886720 09:22:28 INFO - PROCESS | 21054 | 09:22:31 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59205/tests/kraken/driver.html?testName=json-parse-financial) 09:22:32 INFO - PROCESS | 21054 | RSS: Main: 220213248 09:22:32 INFO - PROCESS | 21054 | 09:22:34 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59205/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:34 INFO - PROCESS | 21054 | RSS: Main: 205529088 09:22:34 INFO - PROCESS | 21054 | 09:22:36 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59205/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:37 INFO - PROCESS | 21054 | RSS: Main: 209702912 09:22:37 INFO - PROCESS | 21054 | 09:22:39 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59205/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:40 INFO - PROCESS | 21054 | RSS: Main: 212246528 09:22:40 INFO - PROCESS | 21054 | 09:22:42 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59205/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:43 INFO - PROCESS | 21054 | RSS: Main: 229502976 09:22:43 INFO - PROCESS | 21054 | 09:22:46 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59205/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:46 INFO - PROCESS | 21054 | RSS: Main: 224575488 09:22:46 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | Cycle 1(1): loaded http://localhost:59205/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59205/tests/kraken/driver.html?testName=ai-astar) 09:22:48 INFO - PROCESS | 21054 | RSS: Main: 267653120 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | __start_tp_report 09:22:48 INFO - PROCESS | 21054 | _x_x_mozilla_page_load 09:22:48 INFO - PROCESS | 21054 | _x_x_mozilla_page_load_details 09:22:48 INFO - PROCESS | 21054 | |i|pagename|runs| 09:22:48 INFO - PROCESS | 21054 | |0;ai-astar;104;114;114;114;113;115;113;112;111;112 09:22:48 INFO - PROCESS | 21054 | |1;audio-beat-detection;152;121;125;182;123;125;144;121;162;129 09:22:48 INFO - PROCESS | 21054 | |2;audio-dft;162;162;162;162;161;160;161;162;162;162 09:22:48 INFO - PROCESS | 21054 | |3;audio-fft;76;77;76;111;77;76;74;75;108;77 09:22:48 INFO - PROCESS | 21054 | |4;audio-oscillator;85;87;126;85;86;86;126;85;85;135 09:22:48 INFO - PROCESS | 21054 | |5;imaging-gaussian-blur;117;117;117;117;116;116;117;117;116;117 09:22:48 INFO - PROCESS | 21054 | |6;imaging-darkroom;148;147;147;147;147;146;148;148;146;146 09:22:48 INFO - PROCESS | 21054 | |7;imaging-desaturate;95;94;95;94;95;95;95;94;94;95 09:22:48 INFO - PROCESS | 21054 | |8;json-parse-financial;65;65;65;66;65;63;65;72;63;63 09:22:48 INFO - PROCESS | 21054 | |9;json-stringify-tinderbox;64;64;64;64;65;64;67;65;65;64 09:22:48 INFO - PROCESS | 21054 | |10;stanford-crypto-aes;78;86;78;79;79;78;81;78;84;78 09:22:48 INFO - PROCESS | 21054 | |11;stanford-crypto-ccm;130;138;133;139;128;129;130;129;174;131 09:22:48 INFO - PROCESS | 21054 | |12;stanford-crypto-pbkdf2;165;165;164;166;165;169;166;170;167;169 09:22:48 INFO - PROCESS | 21054 | |13;stanford-crypto-sha256-iterative;66;68;67;67;66;66;67;68;66;65 09:22:48 INFO - PROCESS | 21054 | __end_tp_report 09:22:48 INFO - PROCESS | 21054 | __start_cc_report 09:22:48 INFO - PROCESS | 21054 | _x_x_mozilla_cycle_collect,1928 09:22:48 INFO - PROCESS | 21054 | __end_cc_report 09:22:48 INFO - PROCESS | 21054 | __startTimestamp1467908568855__endTimestamp 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | ------- Summary: start ------- 09:22:48 INFO - PROCESS | 21054 | Number of tests: 14 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.50 stddev:3.12 (2.7%) stddev-sans-first:1.27 09:22:48 INFO - PROCESS | 21054 | Values: 104.0 114.0 114.0 114.0 113.0 115.0 113.0 112.0 111.0 112.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#1] audio-beat-detection Cycles:10 Average:138.40 Median:136.50 stddev:20.99 (15.4%) stddev-sans-first:21.68 09:22:48 INFO - PROCESS | 21054 | Values: 152.0 121.0 125.0 182.0 123.0 125.0 144.0 121.0 162.0 129.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.70 (0.4%) stddev-sans-first:0.73 09:22:48 INFO - PROCESS | 21054 | Values: 162.0 162.0 162.0 162.0 161.0 160.0 161.0 162.0 162.0 162.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#3] audio-fft Cycles:10 Average:82.70 Median:76.00 stddev:14.17 (18.6%) stddev-sans-first:14.82 09:22:48 INFO - PROCESS | 21054 | Values: 76.0 77.0 76.0 111.0 77.0 76.0 74.0 75.0 108.0 77.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:85.00 stddev:21.13 (24.9%) stddev-sans-first:21.83 09:22:48 INFO - PROCESS | 21054 | Values: 85.0 87.0 126.0 85.0 86.0 86.0 126.0 85.0 85.0 135.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:22:48 INFO - PROCESS | 21054 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 116.0 117.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 09:22:48 INFO - PROCESS | 21054 | Values: 148.0 147.0 147.0 147.0 147.0 146.0 148.0 148.0 146.0 146.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:22:48 INFO - PROCESS | 21054 | Values: 95.0 94.0 95.0 94.0 95.0 95.0 95.0 94.0 94.0 95.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:65.00 stddev:2.62 (4.0%) stddev-sans-first:2.77 09:22:48 INFO - PROCESS | 21054 | Values: 65.0 65.0 65.0 66.0 65.0 63.0 65.0 72.0 63.0 63.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:64.50 stddev:0.97 (1.5%) stddev-sans-first:1.00 09:22:48 INFO - PROCESS | 21054 | Values: 64.0 64.0 64.0 64.0 65.0 64.0 67.0 65.0 65.0 64.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:79.00 stddev:2.88 (3.7%) stddev-sans-first:2.98 09:22:48 INFO - PROCESS | 21054 | Values: 78.0 86.0 78.0 79.0 79.0 78.0 81.0 78.0 84.0 78.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#11] stanford-crypto-ccm Cycles:10 Average:136.10 Median:132.00 stddev:13.84 (10.5%) stddev-sans-first:14.51 09:22:48 INFO - PROCESS | 21054 | Values: 130.0 138.0 133.0 139.0 128.0 129.0 130.0 129.0 174.0 131.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:166.50 stddev:2.07 (1.2%) stddev-sans-first:2.11 09:22:48 INFO - PROCESS | 21054 | Values: 165.0 165.0 164.0 166.0 165.0 169.0 166.0 170.0 167.0 169.0 09:22:48 INFO - PROCESS | 21054 | 09:22:48 INFO - PROCESS | 21054 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.97 (1.4%) stddev-sans-first:1.00 09:22:48 INFO - PROCESS | 21054 | Values: 66.0 68.0 67.0 67.0 66.0 66.0 67.0 68.0 66.0 65.0 09:22:48 INFO - PROCESS | 21054 | -------- Summary: end -------- 09:22:48 INFO - PROCESS | 21054 | 09:22:49 INFO - TEST-INFO | 21054: exit 0 09:22:49 INFO - TEST-OK | kraken | took 63669ms 09:22:49 INFO - SUITE-END | took 1182s 09:22:50 INFO - Completed test suite (00:19:42) 09:22:50 INFO - javascript benchmark 09:22:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2570.3574106473384, 2576.3559110222445, 2603.284096873359, 2620.240130362195, 2620.4497055618317, 4640.350350950869, 4667.649926255527, 4673.9065218695605, 4698.342232087506, 4708.670393072486, 4689.835855745049, 4717.410323709536, 4746.145693775119, 4777.498362671921, 4792.329073929653, 4331.935020974688, 4345.67407444443, 4360.040791025969, 4404.537523560028, 4409.559044095597, 4296.119295544421, 4345.130973805236, 4373.759443230637, 4378.211921854065, 4382.145481631083, 4260.936085958713, 4289.2922667759785, 4292.892677683051, 4293.076988447468, 4332.458442694663, 4330.480342358937, 4348.108637729258, 4349.956500434987, 4358.607725304706, 4367.0174210802315, 4245.511766146902, 4282.914341713179, 4333.674974376903, 4339.1104823511105, 4340.717853339523, 4557.886052848672, 4609.03210325832, 4632.583067523905, 4696.882577935545, 4738.715677059353, 4960.107180707508, 4963.60291176705, 5001.924971125436, 5011.32347133136, 5015.0, 4924.236743304794, 4942.110420124376, 4970.105381031413, 5010.974945125287, 5031.698098114089, 4810.326554282403, 4818.734969576707, 4820.204666230068, 4822.107910036584, 4823.493533179036, 5252.789888404421, 5259.237410575522, 5276.419593844674, 5288.576913846883, 5291.1798671206025, 4775.403074615673, 4886.682365646222, 4949.084419382426, 4953.578945789576, 5046.520580544842, 5036.445990940993, 5042.268871013755, 5061.164907790247, 5083.796648134107, 5102.948970510248, 4993.80024799004, 4995.525425084611, 4999.500049995044, 5007.223880298559, 5051.191809310566, 4886.047220791983, 4920.065187614342, 4920.089783390084, 4966.602671786249, 4967.925481117786, 4225.070484493406, 4243.3634954757035, 4245.61789438952, 4255.553166917429, 4293.613574778285, 3711.239195964875, 3724.2551489701627, 3725.385311423639, 3751.5123034005405, 3759.3984962405884, 4247.49030116388, 4285.785710714452, 4288.120935208275, 4321.222180007629, 4376.234159022159, 5122.795088196431, 5128.666636668605, 5172.8965420691375, 5175.45657705943, 5187.792488300502, 4348.326009468537, 4370.366296886934, 4371.628411585051, 4382.364557139261, 4418.8453404130705, 4386.0569977455, 4463.977680111579, 4514.909701805946, 4526.049529598821, 4574.245249533857, 4743.312219728189, 4780.593649539828, 4794.7842347093765, 4795.064962332312, 4838.056578967208, 5119.948800511947, 5122.0, 5127.1540195867265, 5141.408737995179, 5182.144946083854, 4656.580907718321, 4658.86023419298, 4699.17764391237, 4768.1417344878255, 4798.47216806144, 4940.160172770709, 4963.8262660806695, 4973.651844370859, 4977.925331119964, 5007.3240112584335, 5037.496250374933, 5048.570871475966, 5052.646314757932, 5052.924206136838, 5073.3911930568565, 4728.739919304471, 4769.189237829647, 4817.662763606514, 4844.346013288161, 4940.654154209171, 4844.757762111951, 4852.902941941071, 4860.076585448754, 4860.805567777249, 4913.97543012297, 4793.089313030513, 4796.112719146969, 4798.928016079832, 4855.247436647325, 4855.95144048555, 4850.902981940412, 4866.440359358719, 4882.145624515767, 4906.607471402351, 4975.253711943093, 4813.446453657874, 4825.059113472979, 4834.830780922651, 4845.127876982177, 4883.413990321184], "value": 4621.231697972284, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.6198727845982, 573.1545969694698, 585.1982783586487, 585.6427579176701, 588.5909293041335, 26387.340316492096, 29518.85240573797, 29560.852195739044, 29596.704032959664, 29624.40751184975, 29756.0, 41092.38361424574, 42147.0, 42357.36463953043, 46202.537974620245, 7744.148143704202, 7848.646810893495, 7854.175311592287, 7865.410094242926, 7869.0, 7599.696012159507, 7793.103793063813, 7885.172056934025, 7888.329491993188, 7893.842123157533, 10116.393016419002, 10399.896001039968, 10414.114800241989, 10721.35671859687, 10731.731706707316, 10118.544665490033, 10223.591056357736, 10230.181585473181, 10251.948740256326, 10265.538050787733, 7479.065116860393, 9835.0, 10056.748581285454, 10133.493325333704, 10226.335288206317, 39493.80253098745, 39789.40315895264, 39831.0, 39858.40212396816, 39899.20201595952, 2846.558783388578, 3001.83489908055, 3034.5913516637925, 3168.065420700899, 3324.401607710611, 29340.736428901906, 29951.10146695581, 29957.0, 30472.84763576168, 30554.236144096576, 3500.789952602852, 3510.5611798525183, 3536.5756109267054, 3555.4489054196642, 3568.411212149992, 183667.0, 184005.0, 184088.0, 184679.07660461613, 185246.99999999866, 52964.205536916976, 53534.0, 54130.0, 54166.72916635471, 54172.0, 55297.72351138219, 55966.72016639891, 56158.71920640371, 56227.0, 56237.718811405684, 14571.708565828623, 14925.328360223815, 14979.925100374647, 14990.850091499164, 15026.398944042116, 10731.731706707395, 10740.94629526863, 10765.24643274979, 10780.137588992862, 10786.0, 14380.640483987983, 16103.597410064607, 16489.422870199487, 16493.34026638921, 16742.916285418738, 5889.1755154278435, 5921.03948025987, 5931.347551769302, 6098.664573448503, 6123.877522449526, 799.1089934722743, 876.2886597938119, 894.2756367342473, 962.8988956159642, 974.4737841565475, 27476.0, 28464.573031404547, 28820.0, 28933.421331573252, 29122.563161552593, 945.6784693204206, 964.8890377606525, 981.617169303972, 995.1839491616805, 1024.7694268789462, 14708.485203017843, 14812.777808332883, 14891.70216595662, 15091.622709432351, 15095.924520377548, 353748.0, 354806.0, 357263.0, 359965.0, 360063.0, 48070.5192948066, 48564.75717621389, 48622.27066594004, 48627.5137248623, 48699.26951095736, 844.0335815491228, 1084.2572837606265, 1117.3798541809374, 1170.1341007654275, 1201.4113084588664, 26824.731752682223, 28869.566956496088, 29282.97509587154, 29490.410191795614, 29657.5551366734, 41987.37018944657, 42339.364909525764, 42481.36277955771, 42516.57483425126, 42528.57471425246, 280100.0, 281259.0, 281585.0, 282695.0, 282828.0, 22118.447038824157, 24518.38704032342, 24980.37549061288, 25459.61810572806, 25725.228243152735, 41189.79405102955, 41489.37765933574, 41710.58289417067, 41723.37414938718, 42334.57665423306, 35666.0, 35962.820185898905, 36084.27831443304, 36360.63639363572, 36487.45268821023, 36124.4581331275, 36189.0952726184, 36196.27607447889, 36365.0, 37719.81140094282, 36063.63936360603, 36484.27031459409, 36545.634543654225, 36820.81589592035, 37261.62738372581, 32164.195895101875, 33407.0, 33537.664623353456, 33609.327813444084, 33610.159746006546, 25155.0, 25355.36611584725, 25761.0, 25840.483190336465, 25852.22443326703], "value": 17429.98862797902, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.0054234625653, 369.85205917632925, 370.98516059357627, 372.51758972131097, 387.347319766194, 12963.935180324097, 13299.7340053199, 15032.849671503282, 17488.300467981266, 17563.82436175638, 16227.94518356306, 17660.02869842158, 17762.200700968457, 17766.555836104104, 17786.733199001992, 5077.187649976014, 5261.973690131544, 5291.100512912814, 5298.205269209611, 5298.205269209611, 3788.242351529705, 6027.758889644409, 6331.113644089808, 6350.809475715713, 6415.294317625057, 6093.512518998492, 6275.623462592236, 6294.213223347082, 6331.77838775643, 6341.5877967932165, 6144.2012538369945, 6147.354527774564, 6148.631082135086, 6188.0, 6193.504519638418, 5165.483451654842, 5374.811881584125, 5480.958617862593, 5501.614886957915, 5503.036968530491, 21258.14967401302, 21825.890870545703, 22023.66964495534, 22141.114355425765, 22187.778122218733, 1839.8068202838776, 2078.293380250707, 2529.6837895263093, 2533.7972962163167, 2534.746525347469, 13088.280144592043, 13843.238621875793, 14093.0, 14363.49727759534, 14421.78367324491, 1727.93952211672, 1751.422030729856, 1765.1174412793603, 1796.4520821149545, 1803.422904670506, 72348.76512348723, 73094.63452682756, 73284.0, 73987.0, 74430.02279908796, 9504.714858554255, 9577.664781732607, 9740.853887191697, 9801.607935682636, 9927.950360248153, 13302.334883255799, 13472.124311919693, 13492.5277615283, 13507.797383039262, 13510.729785404237, 7396.96301518489, 7438.367738742159, 7438.516496427823, 7561.281678240558, 7624.923750762532, 7184.0, 7186.640667966685, 7192.820179495555, 7280.9635951820965, 7286.781396558112, 6157.476614487729, 6216.378362163748, 6239.563758891022, 6287.371262873768, 6316.641921986795, 1751.8510926571385, 1776.4404212672966, 1795.2818872451123, 1796.8742188046972, 1797.068151736888, 870.9303255739652, 921.8755468011733, 928.3547934185676, 930.9627614895463, 943.1605870775015, 16974.40589579359, 17415.47753567395, 17848.91075544614, 17862.821371786376, 18188.272469101088, 887.3655336434481, 905.0723008915783, 915.8214148241162, 922.1275726113244, 960.5053397500293, 9830.852537211947, 10154.543045562967, 10314.123299519475, 10337.06966373015, 10361.948190259, 366990.0, 374096.0, 374140.0, 375376.0, 375694.0, 67153.66423167853, 69381.65309173422, 69718.30281697118, 69818.65090674514, 70543.29456705367, 863.9006514250941, 881.1540920716056, 892.0499667853715, 896.1128482801979, 902.9051949545335, 15498.302576384316, 15786.763198551802, 15980.760288595913, 16001.199940003185, 16027.919601886768, 34539.13652158716, 34626.65373346234, 35601.64398356087, 36133.0, 36479.45280820737, 45859.54140458686, 46148.0, 46324.7683761579, 46885.76557117193, 47846.0, 7380.597686439559, 7410.6665200065045, 7419.851602968019, 7437.293457121565, 7685.193054729173, 10881.238313317992, 11208.103351732005, 11272.436378180893, 11274.774504510027, 11386.715332116763, 9451.952740236255, 9627.855582166414, 9697.6605818796, 9709.902900971183, 9837.557309920927, 10282.0, 10344.27590068718, 10386.584536618451, 10503.264771466229, 10505.054545090978, 9673.37123086997, 9776.657816976372, 9962.302638815214, 10027.749306267402, 10116.08955194036, 7585.16563178061, 7645.923540764744, 7647.770566883002, 7698.153203147761, 7729.995100636881, 9344.672936447192, 9349.27567266078, 9442.480663563296, 9621.56702948355, 9739.366941148803], "value": 8467.078323989352, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1245.7944439167538, 1353.844307904591, 1412.2867951684398, 1495.200067963639, 1706.0275642883553, 6687.097241872346, 7424.814379640512, 7694.269044440784, 7710.807229819257, 7883.763487095382, 7657.310842024217, 7809.765707028798, 7811.2579304966075, 7818.569978651186, 7821.061472623279, 1815.3464752689022, 1890.6407782521346, 1894.4032629721594, 2193.747719012314, 2230.7769223077726, 1907.2275728330048, 2136.6367717487988, 2234.1510226114056, 2235.541713948645, 2253.2339004738387, 2179.814715749163, 2206.1947389202905, 2214.1254204589177, 2219.6892435059103, 2220.3338998300446, 1785.9464216073409, 2109.2301310021885, 2109.2828438330966, 2113.3448630924463, 2113.968290475644, 2015.3752336775647, 2108.989455052715, 2114.1860383752214, 2125.8405619578593, 2129.9254526091668, 7849.725259615886, 7879.330256928168, 7900.288973992311, 7902.091259505173, 7914.129445760962, 4524.524924882873, 4639.744814035227, 4643.698159619647, 4646.256598944153, 4649.395578574764, 2841.417509410566, 2882.6684931232967, 2935.6330458692664, 2942.9705702943124, 2944.646642402904, 930.4556834251971, 938.976525586859, 940.7930255343813, 962.7352478068517, 963.4171326347599, 10755.784884302348, 10793.67618971432, 10823.134149268119, 10851.945740271327, 10867.619633312876, 4034.3545032794614, 4133.91732165361, 4220.303649897794, 4316.050468896838, 4316.719413238192, 9147.17675409203, 9152.0, 9153.954230228806, 9164.40431371972, 9167.77080572991, 4656.767161641972, 4661.906761864744, 4667.206574882278, 4669.95330046702, 4671.43942726875, 3649.927001459956, 3665.816709164531, 4272.401863739079, 4277.251480990814, 4279.935800962988, 3654.5066416033496, 3732.9066773330883, 3803.809809509569, 3829.463875057494, 3832.6550610444638, 1205.52984336109, 1205.9095567832449, 1212.5877201751448, 1214.6598952293373, 1220.7070303127364, 701.4598758955677, 756.2701992577189, 799.2886331165267, 807.7334479621711, 830.0, 5034.043531729033, 5119.104156772624, 5178.766955487012, 5345.1714984177515, 5395.541378982831, 404.2117870153213, 417.92268430339874, 419.490319262098, 424.67512353049943, 428.83143434141715, 3330.633630300665, 3349.1961929136833, 3377.8648854046055, 3388.1698983748647, 3428.5542879425516, 9314.254859611216, 9659.758506037406, 9691.757706057406, 9758.219342452587, 9776.657816976514, 9642.807143857084, 9752.95123524392, 9756.609735610638, 9764.462954537568, 9768.609255629695, 7289.380402665833, 7301.89047164293, 7331.083614548182, 7367.4106071515225, 7390.002349682832, 5101.566366858859, 5157.329547158845, 5158.587313014875, 5184.066867963803, 5196.428392876856, 8897.822043559223, 8990.91009089927, 9073.001969783187, 9102.726918192464, 9145.451272923647, 8068.919310806817, 8987.236084932592, 9221.49281789508, 9232.815343693223, 9318.813623727623, 6501.382368674968, 6793.864122717419, 6793.898091528532, 6952.409045231212, 6962.477814164059, 6981.895271571032, 7028.683709233199, 7091.574505529685, 7107.964460177666, 7113.0753002109395, 6651.50113741481, 6701.899471508029, 6711.161104861892, 6780.864382712417, 6902.551334163263, 6755.3244675534015, 6814.557053791487, 6856.760013399507, 6869.072675188985, 6903.309669033057, 5800.7099645018425, 5846.327672317738, 5850.473457388855, 5859.824205273849, 5864.208331875313, 4319.200947824663, 4489.393931819162, 4510.807142111345, 4546.977265113654, 4592.632589392907, 4440.578145076212, 4631.814727411001, 4645.674802763774, 4677.602403795579, 4802.255650374198], "value": 4017.9486897774773, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.52366998307684, 302.7850226339299, 308.487910069285, 308.7021024711156, 308.73757306289656, 4898.5101489850995, 4929.926051109236, 4942.110420124381, 4947.936193718349, 4963.726995015281, 4832.4442689090765, 4834.371531700883, 4883.316335713003, 4892.192788189945, 4904.435989861167, 3647.689946354563, 3743.494628225182, 3767.792771397572, 3853.5761066282685, 3879.7778699709643, 3815.1606646537716, 3833.271678381099, 3850.5752871437608, 3854.6338097880657, 3863.517060367454, 3635.509206257161, 3812.82842272097, 3821.4840996465537, 3828.4065969774592, 3831.712621553381, 3700.5374328208973, 3774.1885494618787, 3775.093977445421, 3791.3554695701796, 3811.9237615247816, 3336.1659585103725, 3340.1482621931495, 3345.1971526833627, 3361.83190840457, 3377.7804442711144, 4706.3411122442885, 4725.621950243973, 4726.314684370781, 4729.527047295243, 4740.857774266743, 4248.617624413787, 4321.157374312011, 4354.934675979863, 4360.389545463638, 4377.627901628365, 5048.772805223737, 5057.544820966131, 5061.240813877911, 5071.898562028776, 5085.491450854922, 3593.3352329819068, 3662.1943172502006, 3684.2631473705364, 3685.723570732179, 3687.8893633191046, 5102.948970510284, 5252.763625636855, 5259.0, 5275.4460781618145, 5301.1783173608155, 3718.535183102112, 3737.8131093445218, 3745.400735882246, 3747.7189210809297, 3752.6622603965775, 3809.8476060957805, 3811.104390468238, 3812.4281357796553, 3812.5043744313616, 3812.942805857914, 4342.1532801103485, 4345.130973805251, 4348.478182618106, 4364.410804541346, 4366.912661746811, 4868.659193856396, 5020.874478138076, 5033.899322013613, 5034.84895453137, 5052.898942021139, 1307.4181989014987, 1330.946762129504, 1332.253937794838, 1335.4791631263815, 1337.498438085718, 870.255931178833, 877.5217506458868, 881.3345923827469, 890.857462805961, 892.2014796756866, 4313.978430107893, 4331.805068771912, 4485.887852803706, 4505.909881802411, 4515.887102822456, 5197.402298735694, 5200.739963001834, 5209.609279304067, 5227.764750586232, 5242.9213561796605, 4244.792707791931, 4460.821567137343, 4481.395011673448, 4495.662825288116, 4500.639948804088, 2743.986280068587, 2756.3109222694325, 2770.4320614274025, 2772.1960631416664, 2784.5683918992186, 4776.328285100187, 4871.415430148405, 4879.950810575743, 4891.413030436299, 4906.656534042654, 5091.694498330112, 5258.342707161605, 5291.312129423227, 5295.4969277918535, 5311.787528498817, 4871.76027941415, 4873.756312184377, 4875.34182885313, 4878.292647566154, 4898.24077268024, 5198.844034678966, 5200.479952004769, 5218.165093585008, 5257.4216836147225, 5261.894762104813, 4994.375703037121, 5117.667351622133, 5139.254808052886, 5144.254083157846, 5167.328247327823, 4835.734034628129, 4857.757112144449, 4858.878528036827, 4865.683730557503, 4876.439209490812, 3401.2857299967286, 3417.8979927345, 3429.3141371725255, 3442.6041005284715, 3442.913927151841, 4508.436445444319, 4674.766261686834, 4690.3433519306645, 4703.788329525248, 4720.268358404453, 4381.67137464685, 4417.271150260171, 4491.191585514639, 4528.660350473661, 4537.682362234612, 4497.4708599076475, 4534.319852022223, 4560.931586026146, 4582.312653101928, 4582.381378513991, 4215.409842621974, 4431.667624928079, 4471.863526238151, 4478.529754375743, 4506.481754598263, 4523.547645235451, 4533.818647254205, 4541.409616749801, 4553.931691024571, 4567.748773817339, 4484.417025786757, 4485.394471746403, 4512.05246898155, 4519.68362214655, 4524.298733696282], "value": 3758.334018165572, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.8449364399365, 684.5653010338434, 734.1703874621677, 753.9095308562974, 761.7460648079267, 12141.57504487343, 12801.295928723917, 12900.870991290085, 12965.546205882796, 13086.803697944539, 13160.868391316084, 13181.340932953339, 13314.866851331484, 13315.733685326288, 13321.933390333059, 612.9325774164838, 737.5906371963558, 772.9381649468057, 793.6825269892032, 799.6561478564215, 799.2207597592359, 799.940004499662, 802.5184889066543, 802.9678812847508, 803.4737247103157, 846.272205902923, 847.1316900177306, 847.2036285891273, 847.267113946435, 847.7795773098977, 643.2184895352146, 663.7610460234312, 664.0703015777901, 665.5806841689705, 837.0666706622134, 572.4790440698944, 592.6444133519897, 597.2981746447949, 597.6563476001317, 597.8744463662636, 13003.154794938298, 13024.153430027018, 13504.662383440493, 13522.661933451644, 13540.187588744708, 12860.228386296852, 13333.866661333457, 13410.93294533531, 13510.0, 13748.862511374859, 12111.45498452563, 12127.575534856325, 12173.939130304381, 12254.44854981519, 12352.62942111738, 4283.678724095706, 4290.828366865321, 4313.28830742927, 4317.11499142675, 4402.669799765031, 57989.0, 58092.99999999958, 58128.0, 58243.0, 58497.0, 10310.896891031143, 10388.532516036796, 10595.94702026485, 10651.786964260671, 10763.461826908624, 19332.613347732968, 19340.4197874064, 19341.51646208856, 19354.8064519353, 19356.806431935784, 6825.419839313615, 6865.9313406866295, 6927.58434493932, 6949.2008419031445, 7004.474664400191, 3614.8192590370377, 3669.541307336583, 3672.1003354178397, 3694.833732482045, 3704.981475092607, 2939.3974235282144, 3038.2708150044273, 3042.969570304313, 3042.984785076105, 3044.0, 490.65408886734764, 494.1426624805959, 496.3200415430883, 507.76388979124886, 507.85272271040986, 521.1817446608787, 529.1401472607014, 539.7867842202387, 554.4427850010713, 568.7184843502492, 6971.616561089088, 7182.0, 7298.927010729824, 7329.0, 7369.22623124575, 522.8588281164065, 525.8869343091254, 531.8829857431359, 543.5352773378704, 585.0755805826783, 3619.113317237294, 3648.1426864686778, 3704.0, 3741.9625803741615, 3744.719146064056, 53754.462455374945, 54077.72961135169, 54775.0, 59158.0, 98470.5076474613, 50515.747421262655, 66757.33242667609, 67955.66022169957, 84209.0, 84697.57651211704, 43894.34158487625, 44560.331595025455, 44880.0, 46547.53452465432, 73686.63156684182, 6946.479014074066, 7044.506884518035, 7122.501424900414, 7160.0, 7225.385842203472, 42358.5764142367, 43906.780466097465, 44618.776906115265, 45302.77348613368, 45524.77237613791, 60656.393436065075, 63865.0, 64683.0, 64818.351816481234, 64998.0, 9550.617975280911, 9846.33151971011, 9984.301098923006, 9987.450690212108, 10032.548535316075, 10093.444860532447, 10283.588656453658, 10310.742231444274, 10328.225383096147, 10669.839952400875, 8198.918010819816, 9442.716718498456, 9520.714378568655, 10029.29794914349, 10359.0, 11755.235909665844, 12152.696182595153, 12165.574204902787, 12249.387530623611, 12331.50673973031, 10355.585776568852, 10659.307145035547, 10776.137908967421, 10805.945970270099, 10842.403667798348, 10054.0, 10133.79732405333, 10314.48427578603, 10421.114205292331, 10658.89341106579, 9892.752681183028, 10219.108482514972, 10224.795504090025, 10287.331323463952, 10340.224483163642], "value": 6277.07971213428, "unit": "score"}], "name": "dromaeo_css", "value": 6335.286410698855, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 114.0, 114.0, 113.0, 115.0, 113.0, 112.0, 111.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 121.0, 125.0, 182.0, 123.0, 125.0, 144.0, 121.0, 162.0, 129.0], "value": 138.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 161.0, 160.0, 161.0, 162.0, 162.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 111.0, 77.0, 76.0, 74.0, 75.0, 108.0, 77.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 126.0, 85.0, 86.0, 86.0, 126.0, 85.0, 85.0, 135.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 147.0, 147.0, 146.0, 148.0, 148.0, 146.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 65.0, 63.0, 65.0, 72.0, 63.0, 63.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 67.0, 65.0, 65.0, 64.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 86.0, 78.0, 79.0, 79.0, 78.0, 81.0, 78.0, 84.0, 78.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 138.0, 133.0, 139.0, 128.0, 129.0, 130.0, 129.0, 174.0, 131.0], "value": 136.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 164.0, 166.0, 165.0, 169.0, 166.0, 170.0, 167.0, 169.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 67.0, 66.0, 66.0, 67.0, 68.0, 66.0, 65.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1530.8, "alertThreshold": 2.0}]} 09:22:50 INFO - Return code: 0 09:22:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:22:50 INFO - # TBPL SUCCESS # 09:22:50 INFO - Running post-action listener: _resource_record_post_action 09:22:50 INFO - ##### 09:22:50 INFO - ##### Finished run-tests step (success) 09:22:50 INFO - ##### 09:22:50 INFO - Running post-run listener: _resource_record_post_run 09:22:50 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 534994944; Read time: 12; Write time: 417312 09:22:50 INFO - TinderboxPrint: CPU usage
13.0% 09:22:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:22:50 INFO - TinderboxPrint: I/O write bytes / time
534,994,944 / 417,312 09:22:50 INFO - TinderboxPrint: CPU idle
8,324.3 (87.0%) 09:22:50 INFO - TinderboxPrint: CPU user
1,205.1 (12.6%) 09:22:50 INFO - TinderboxPrint: Swap in / out
0 / 0 09:22:50 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 245899264; Read time: 0; Write time: 214204 09:22:50 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 288354304; Read time: 0; Write time: 203080 09:22:50 INFO - Running post-run listener: _upload_blobber_files 09:22:50 INFO - Blob upload gear active. 09:22:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:22:50 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 09:22:50 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:22:50 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:22:51 INFO - (blobuploader) - INFO - Open directory for files ... 09:22:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:22:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:22:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:54 INFO - (blobuploader) - INFO - Done attempting. 09:22:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:22:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:22:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:55 INFO - (blobuploader) - INFO - Done attempting. 09:22:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:22:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:22:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:56 INFO - (blobuploader) - INFO - Done attempting. 09:22:56 INFO - (blobuploader) - INFO - Iteration through files over. 09:22:56 INFO - Return code: 0 09:22:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:22:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:22:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4785db2abe8edf31239254b3b698e55fd7b8934f7433072fc2ff9cdc46d94b1ec54f7a95edce6da5a16c2d9346a457a4fbbdb3f28f4ce37b6684d94180672ef6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cbe8d8516f4eae4ab79c3fb351f721ec25d7349e1b76e961ecbb006b7911285fc0c62df0c1c6c8c627895be208ef46ef17a08a63033d942ab66292c9465b9547", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7df256dd36fbcbb1d2b7252824d6882f5287a509c4d6d2313c966e09b91b3f929cce1cb636cd569916a34204698de1cd4a8adc5bf8098b033981ab38b8ff65a4"} 09:22:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:22:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:22:56 INFO - Contents: 09:22:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4785db2abe8edf31239254b3b698e55fd7b8934f7433072fc2ff9cdc46d94b1ec54f7a95edce6da5a16c2d9346a457a4fbbdb3f28f4ce37b6684d94180672ef6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cbe8d8516f4eae4ab79c3fb351f721ec25d7349e1b76e961ecbb006b7911285fc0c62df0c1c6c8c627895be208ef46ef17a08a63033d942ab66292c9465b9547", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7df256dd36fbcbb1d2b7252824d6882f5287a509c4d6d2313c966e09b91b3f929cce1cb636cd569916a34204698de1cd4a8adc5bf8098b033981ab38b8ff65a4"} 09:22:56 INFO - Running post-run listener: copy_logs_to_upload_dir 09:22:56 INFO - Copying logs to upload dir... 09:22:56 INFO - mkdir: /builds/slave/test/build/upload/logs 09:22:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.576529 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-07-07 09:22:56.451821) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 09:22:56.454996) ========= 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=03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808 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-Non-PGO/sha512/4785db2abe8edf31239254b3b698e55fd7b8934f7433072fc2ff9cdc46d94b1ec54f7a95edce6da5a16c2d9346a457a4fbbdb3f28f4ce37b6684d94180672ef6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cbe8d8516f4eae4ab79c3fb351f721ec25d7349e1b76e961ecbb006b7911285fc0c62df0c1c6c8c627895be208ef46ef17a08a63033d942ab66292c9465b9547", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7df256dd36fbcbb1d2b7252824d6882f5287a509c4d6d2313c966e09b91b3f929cce1cb636cd569916a34204698de1cd4a8adc5bf8098b033981ab38b8ff65a4"} build_url:https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011274 build_url: 'https://queue.taskcluster.net/v1/task/D74TtTLcQIuVs_G8Zh0RKQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4785db2abe8edf31239254b3b698e55fd7b8934f7433072fc2ff9cdc46d94b1ec54f7a95edce6da5a16c2d9346a457a4fbbdb3f28f4ce37b6684d94180672ef6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cbe8d8516f4eae4ab79c3fb351f721ec25d7349e1b76e961ecbb006b7911285fc0c62df0c1c6c8c627895be208ef46ef17a08a63033d942ab66292c9465b9547", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7df256dd36fbcbb1d2b7252824d6882f5287a509c4d6d2313c966e09b91b3f929cce1cb636cd569916a34204698de1cd4a8adc5bf8098b033981ab38b8ff65a4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 09:22:56.481086) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:22:56.481425) ========= 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=03627c79f6bb3d1578c4a402000001ba-1467906838.128553-1298732808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004491 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:22:56.563577) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 09:22:56.563903) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 09:22:56.564230) ========= ========= Total master_lag: 0.19 =========