builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-062 starttime: 1468834144.53 results: success (0) buildid: 20160718015953 builduid: 85610e5d602b41c38a2653b307561653 revision: 76bb0b29a7509fe35db3beb571fec162cc781a80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:04.526962) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:04.527547) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:04.527838) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:04.539790) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:04.540057) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-18 02:29:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 114M=0s 2016-07-18 02:29:04 (114 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.078988 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:04.631771) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:04.632152) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020053 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:04.681284) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 02:29:04.681933) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 76bb0b29a7509fe35db3beb571fec162cc781a80 --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 76bb0b29a7509fe35db3beb571fec162cc781a80 --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=a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-18 02:29:04,974 truncating revision to first 12 chars 2016-07-18 02:29:04,975 Setting DEBUG logging. 2016-07-18 02:29:04,975 attempt 1/10 2016-07-18 02:29:04,975 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/76bb0b29a750?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 02:29:06,035 unpacking tar archive at: fx-team-76bb0b29a750/testing/mozharness/ program finished with exit code 0 elapsedTime=1.365638 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 02:29:06.061125) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:06.061458) ========= script_repo_revision: 76bb0b29a7509fe35db3beb571fec162cc781a80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:06.061852) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:06.062110) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 02:29:06.071466) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-18 02:29:06.071753) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:29:06 INFO - MultiFileLogger online at 20160718 02:29:06 in /builds/slave/test 02:29:06 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 02:29:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:29:06 INFO - {'append_to_log': False, 02:29:06 INFO - 'base_work_dir': '/builds/slave/test', 02:29:06 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 02:29:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:29:06 INFO - 'branch': 'Fx-Team-Non-PGO', 02:29:06 INFO - 'buildbot_json_path': 'buildprops.json', 02:29:06 INFO - 'config_files': ('talos/linux_config.py',), 02:29:06 INFO - 'default_actions': ('clobber', 02:29:06 INFO - 'read-buildbot-config', 02:29:06 INFO - 'download-and-extract', 02:29:06 INFO - 'populate-webroot', 02:29:06 INFO - 'create-virtualenv', 02:29:06 INFO - 'install', 02:29:06 INFO - 'run-tests'), 02:29:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:29:06 INFO - 'download_minidump_stackwalk': True, 02:29:06 INFO - 'download_symbols': 'ondemand', 02:29:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:29:06 INFO - 'tooltool.py': '/tools/tooltool.py', 02:29:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:29:06 INFO - '/tools/misc-python/virtualenv.py')}, 02:29:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:29:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:29:06 INFO - 'installer_path': 'installer.exe', 02:29:06 INFO - 'log_level': 'info', 02:29:06 INFO - 'log_name': 'talos', 02:29:06 INFO - 'log_to_console': True, 02:29:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:29:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:29:06 INFO - 'opt_config_files': (), 02:29:06 INFO - 'pip_index': False, 02:29:06 INFO - 'sps_profile': False, 02:29:06 INFO - 'sps_profile_interval': 0, 02:29:06 INFO - 'suite': 'dromaeojs', 02:29:06 INFO - 'system_bits': '32', 02:29:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:29:06 INFO - 'title': 'talos-linux64-ix-062', 02:29:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:29:06 INFO - 'use_talos_json': True, 02:29:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:29:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:29:06 INFO - 'work_dir': 'build'} 02:29:06 INFO - ##### 02:29:06 INFO - ##### Running clobber step. 02:29:06 INFO - ##### 02:29:06 INFO - Running pre-action listener: _resource_record_pre_action 02:29:06 INFO - Running main action method: clobber 02:29:06 INFO - rmtree: /builds/slave/test/build 02:29:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:29:06 INFO - Running post-action listener: _resource_record_post_action 02:29:06 INFO - ##### 02:29:06 INFO - ##### Finished clobber step (success) 02:29:06 INFO - ##### 02:29:06 INFO - ##### 02:29:06 INFO - ##### Running read-buildbot-config step. 02:29:06 INFO - ##### 02:29:06 INFO - Running pre-action listener: _resource_record_pre_action 02:29:06 INFO - Running main action method: read_buildbot_config 02:29:06 INFO - Using buildbot properties: 02:29:06 INFO - { 02:29:06 INFO - "project": "", 02:29:06 INFO - "product": "firefox", 02:29:06 INFO - "script_repo_revision": "production", 02:29:06 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 02:29:06 INFO - "repository": "", 02:29:06 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 02:29:06 INFO - "buildid": "20160718015953", 02:29:06 INFO - "pgo_build": "False", 02:29:06 INFO - "basedir": "/builds/slave/test", 02:29:06 INFO - "buildnumber": 3169, 02:29:06 INFO - "slavename": "talos-linux64-ix-062", 02:29:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:29:06 INFO - "platform": "ubuntu64_hw", 02:29:06 INFO - "scheduler": "tests-fx-team-linux64-talos", 02:29:06 INFO - "branch": "fx-team", 02:29:06 INFO - "repo_path": "integration/fx-team", 02:29:06 INFO - "revision": "76bb0b29a7509fe35db3beb571fec162cc781a80", 02:29:06 INFO - "stage_platform": "linux64", 02:29:06 INFO - "builduid": "85610e5d602b41c38a2653b307561653", 02:29:06 INFO - "slavebuilddir": "test" 02:29:06 INFO - } 02:29:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 02:29:06 INFO - Running post-action listener: _resource_record_post_action 02:29:06 INFO - ##### 02:29:06 INFO - ##### Finished read-buildbot-config step (success) 02:29:06 INFO - ##### 02:29:06 INFO - ##### 02:29:06 INFO - ##### Running download-and-extract step. 02:29:06 INFO - ##### 02:29:06 INFO - Running pre-action listener: _resource_record_pre_action 02:29:06 INFO - Running main action method: download_and_extract 02:29:06 INFO - mkdir: /builds/slave/test/build/tests 02:29:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:29:06 INFO - https://queue.taskcluster.net/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 02:29:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 02:29:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 02:29:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/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 02:29:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/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 02:29:07 INFO - Downloaded 1361 bytes. 02:29:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 02:29:07 INFO - Using the following test package requirements: 02:29:07 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 02:29:07 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:29:07 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:29:07 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:07 INFO - u'jsshell-linux-x86_64.zip'], 02:29:07 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:29:07 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 02:29:07 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:29:07 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:29:07 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:29:07 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:29:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:29:07 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:29: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')]} 02:29:07 INFO - https://queue.taskcluster.net/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:29:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:29:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:29:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/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 02:29:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/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 02:29:08 INFO - Downloaded 22763853 bytes. 02:29:08 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'] 02:29:08 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 02:29:09 INFO - Return code: 0 02:29:09 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 02:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:29:09 INFO - https://queue.taskcluster.net/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:29:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:29:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:29:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/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 02:29:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/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 02:29:11 INFO - Downloaded 22763853 bytes. 02:29: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'] 02:29: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 02:29:12 INFO - Return code: 0 02:29: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')]} 02:29:12 INFO - https://queue.taskcluster.net/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 02:29:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 02:29:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 02:29:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/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 02:29:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/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 02:29:13 INFO - Downloaded 14403317 bytes. 02:29:13 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'] 02:29:13 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 02:29:13 INFO - Return code: 0 02:29:13 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')]} 02:29:13 INFO - https://queue.taskcluster.net/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 02:29:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:29:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:29:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/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 02:29:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ONqcWpO9SYevQzYF6YUImw/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 02:29:16 INFO - Downloaded 57075882 bytes. 02:29:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:29:16 INFO - mkdir: /builds/slave/test/properties 02:29:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:29:16 INFO - Writing to file /builds/slave/test/properties/build_url 02:29:16 INFO - Contents: 02:29:16 INFO - build_url:https://queue.taskcluster.net/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:29:17 INFO - Running post-action listener: _resource_record_post_action 02:29:17 INFO - Running post-action listener: set_extra_try_arguments 02:29:17 INFO - ##### 02:29:17 INFO - ##### Finished download-and-extract step (success) 02:29:17 INFO - ##### 02:29:17 INFO - ##### 02:29:17 INFO - ##### Running populate-webroot step. 02:29:17 INFO - ##### 02:29:17 INFO - Running pre-action listener: _resource_record_pre_action 02:29:17 INFO - Running main action method: populate_webroot 02:29:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:29:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:29:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:29:17 INFO - '--tppagecycles', 02:29:17 INFO - '7'], 02:29:17 INFO - 'tests': ['tsvgm']}}, 02:29:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:29:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:29:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:29:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:29:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:29:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:29:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:17 INFO - 'talos_options': ['--disable-e10s'], 02:29:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:17 INFO - 'talos_options': ['--disable-e10s'], 02:29:17 INFO - 'tests': ['damp', 'tps']}, 02:29:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:17 INFO - 'tests': ['damp', 'tps']}, 02:29:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:29:17 INFO - 'tests': ['dromaeo_dom']}, 02:29:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:29:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:29:17 INFO - 'tests': ['basic_compositor_video']}, 02:29:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:29:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:29:17 INFO - 'tests': ['a11yr', 02:29:17 INFO - 'ts_paint', 02:29:17 INFO - 'tpaint', 02:29:17 INFO - 'sessionrestore', 02:29:17 INFO - 'sessionrestore_no_auto_restore', 02:29:17 INFO - 'tabpaint']}, 02:29:17 INFO - 'other-e10s': {'tests': ['a11yr', 02:29:17 INFO - 'ts_paint', 02:29:17 INFO - 'tpaint', 02:29:17 INFO - 'sessionrestore', 02:29:17 INFO - 'sessionrestore_no_auto_restore', 02:29:17 INFO - 'tabpaint']}, 02:29:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:29:17 INFO - 'ts_paint', 02:29:17 INFO - 'tpaint', 02:29:17 INFO - 'sessionrestore', 02:29:17 INFO - 'sessionrestore_no_auto_restore', 02:29:17 INFO - 'tabpaint']}, 02:29:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:29:17 INFO - 'ts_paint', 02:29:17 INFO - 'tpaint', 02:29:17 INFO - 'sessionrestore', 02:29:17 INFO - 'sessionrestore_no_auto_restore', 02:29:17 INFO - 'tabpaint']}, 02:29:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:29:17 INFO - 'tests': ['a11yr', 02:29:17 INFO - 'ts_paint', 02:29:17 INFO - 'tpaint', 02:29:17 INFO - 'sessionrestore', 02:29:17 INFO - 'sessionrestore_no_auto_restore', 02:29:17 INFO - 'tabpaint']}, 02:29:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:29:17 INFO - 'tests': ['a11yr', 02:29:17 INFO - 'ts_paint', 02:29:17 INFO - 'tpaint', 02:29:17 INFO - 'sessionrestore', 02:29:17 INFO - 'sessionrestore_no_auto_restore', 02:29:17 INFO - 'tabpaint']}, 02:29:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:29:17 INFO - 'tests': ['tsvgx', 02:29:17 INFO - 'tsvgr_opacity', 02:29:17 INFO - 'tart', 02:29:17 INFO - 'tscrollx', 02:29:17 INFO - 'cart']}, 02:29:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:29:17 INFO - 'tsvgr_opacity', 02:29:17 INFO - 'tart', 02:29:17 INFO - 'tscrollx', 02:29:17 INFO - 'cart']}, 02:29:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:17 INFO - 'talos_options': ['--disable-e10s'], 02:29:17 INFO - 'tests': ['tp5o']}, 02:29:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:17 INFO - 'tests': ['tp5o']}, 02:29:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:17 INFO - 'talos_options': ['--disable-e10s', 02:29:17 INFO - '--xperf_path', 02:29:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:17 INFO - 'tests': ['tp5n']}, 02:29:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:29:17 INFO - 'talos_options': ['--xperf_path', 02:29:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:17 INFO - 'tests': ['tp5n']}}, 02:29:17 INFO - 'talos.zip': {'path': '', 02:29:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:29:17 INFO - Running post-action listener: _resource_record_post_action 02:29:17 INFO - ##### 02:29:17 INFO - ##### Finished populate-webroot step (success) 02:29:17 INFO - ##### 02:29:17 INFO - ##### 02:29:17 INFO - ##### Running create-virtualenv step. 02:29:17 INFO - ##### 02:29:17 INFO - Running pre-action listener: _resource_record_pre_action 02:29:17 INFO - Running main action method: create_virtualenv 02:29:17 INFO - Creating virtualenv /builds/slave/test/build/venv 02:29:17 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 02:29:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:29:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:29:17 INFO - Using real prefix '/usr' 02:29:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:29:18 INFO - Installing distribute.............................................................................................................................................................................................done. 02:29:20 INFO - Installing pip.................done. 02:29:20 INFO - Return code: 0 02:29:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x244b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270a3b0>, '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 0x270b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270b740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', '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 02:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:29:20 INFO - Using env: {'DISPLAY': ':0', 02:29:20 INFO - 'HOME': '/home/cltbld', 02:29:20 INFO - 'LANG': 'en_US.UTF-8', 02:29:20 INFO - 'LANGUAGE': 'en_US:en', 02:29:20 INFO - 'LOGNAME': 'cltbld', 02:29:20 INFO - 'MAIL': '/var/mail/cltbld', 02:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:20 INFO - 'MOZ_NO_REMOTE': '1', 02:29:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:20 INFO - 'NO_EM_RESTART': '1', 02:29:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:20 INFO - 'PWD': '/builds/slave/test', 02:29:20 INFO - 'SHELL': '/bin/bash', 02:29:20 INFO - 'SHLVL': '1', 02:29:20 INFO - 'TERM': 'linux', 02:29:20 INFO - 'TMOUT': '86400', 02:29:20 INFO - 'USER': 'cltbld', 02:29:20 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', 02:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:20 INFO - '_': '/tools/buildbot/bin/python'} 02:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:20 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 02:29:20 INFO - Cleaning up... 02:29:20 INFO - Return code: 0 02:29:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x244b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270a3b0>, '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 0x270b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270b740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', '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 02:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:29:20 INFO - Using env: {'DISPLAY': ':0', 02:29:20 INFO - 'HOME': '/home/cltbld', 02:29:20 INFO - 'LANG': 'en_US.UTF-8', 02:29:20 INFO - 'LANGUAGE': 'en_US:en', 02:29:20 INFO - 'LOGNAME': 'cltbld', 02:29:20 INFO - 'MAIL': '/var/mail/cltbld', 02:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:20 INFO - 'MOZ_NO_REMOTE': '1', 02:29:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:20 INFO - 'NO_EM_RESTART': '1', 02:29:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:20 INFO - 'PWD': '/builds/slave/test', 02:29:20 INFO - 'SHELL': '/bin/bash', 02:29:20 INFO - 'SHLVL': '1', 02:29:20 INFO - 'TERM': 'linux', 02:29:20 INFO - 'TMOUT': '86400', 02:29:20 INFO - 'USER': 'cltbld', 02:29:20 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', 02:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:20 INFO - '_': '/tools/buildbot/bin/python'} 02:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:20 INFO - Downloading/unpacking psutil>=3.1.1 02:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:29:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:22 INFO - Installing collected packages: psutil 02:29:22 INFO - Running setup.py install for psutil 02:29:22 INFO - building 'psutil._psutil_linux' extension 02:29:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 02:29:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:29:22 INFO - building 'psutil._psutil_posix' extension 02:29:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 02:29:23 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 02:29:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:23 INFO - Successfully installed psutil 02:29:23 INFO - Cleaning up... 02:29:23 INFO - Return code: 0 02:29:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x244b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270a3b0>, '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 0x270b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270b740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', '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 02:29:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:29:23 INFO - Using env: {'DISPLAY': ':0', 02:29:23 INFO - 'HOME': '/home/cltbld', 02:29:23 INFO - 'LANG': 'en_US.UTF-8', 02:29:23 INFO - 'LANGUAGE': 'en_US:en', 02:29:23 INFO - 'LOGNAME': 'cltbld', 02:29:23 INFO - 'MAIL': '/var/mail/cltbld', 02:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:23 INFO - 'MOZ_NO_REMOTE': '1', 02:29:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:23 INFO - 'NO_EM_RESTART': '1', 02:29:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:23 INFO - 'PWD': '/builds/slave/test', 02:29:23 INFO - 'SHELL': '/bin/bash', 02:29:23 INFO - 'SHLVL': '1', 02:29:23 INFO - 'TERM': 'linux', 02:29:23 INFO - 'TMOUT': '86400', 02:29:23 INFO - 'USER': 'cltbld', 02:29:23 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', 02:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:23 INFO - '_': '/tools/buildbot/bin/python'} 02:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:29:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:29:25 INFO - Installing collected packages: mozsystemmonitor 02:29:25 INFO - Running setup.py install for mozsystemmonitor 02:29:25 INFO - Successfully installed mozsystemmonitor 02:29:25 INFO - Cleaning up... 02:29:25 INFO - Return code: 0 02:29:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x244b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270a3b0>, '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 0x270b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270b740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', '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 02:29:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:29:25 INFO - Using env: {'DISPLAY': ':0', 02:29:25 INFO - 'HOME': '/home/cltbld', 02:29:25 INFO - 'LANG': 'en_US.UTF-8', 02:29:25 INFO - 'LANGUAGE': 'en_US:en', 02:29:25 INFO - 'LOGNAME': 'cltbld', 02:29:25 INFO - 'MAIL': '/var/mail/cltbld', 02:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:25 INFO - 'MOZ_NO_REMOTE': '1', 02:29:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:25 INFO - 'NO_EM_RESTART': '1', 02:29:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:25 INFO - 'PWD': '/builds/slave/test', 02:29:25 INFO - 'SHELL': '/bin/bash', 02:29:25 INFO - 'SHLVL': '1', 02:29:25 INFO - 'TERM': 'linux', 02:29:25 INFO - 'TMOUT': '86400', 02:29:25 INFO - 'USER': 'cltbld', 02:29:25 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', 02:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:25 INFO - '_': '/tools/buildbot/bin/python'} 02:29:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:25 INFO - Downloading/unpacking jsonschema==2.5.1 02:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:29:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:27 INFO - Downloading functools32-3.2.3-2.tar.gz 02:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:29:27 INFO - warning: no files found matching '*.txt' 02:29:27 INFO - no previously-included directories found matching 'build' 02:29:27 INFO - no previously-included directories found matching 'dist' 02:29:27 INFO - no previously-included directories found matching '.git*' 02:29:27 INFO - Installing collected packages: jsonschema, functools32 02:29:28 INFO - Running setup.py install for functools32 02:29:28 INFO - warning: no files found matching '*.txt' 02:29:28 INFO - no previously-included directories found matching 'build' 02:29:28 INFO - no previously-included directories found matching 'dist' 02:29:28 INFO - no previously-included directories found matching '.git*' 02:29:28 INFO - Successfully installed jsonschema functools32 02:29:28 INFO - Cleaning up... 02:29:28 INFO - Return code: 0 02:29:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x244b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270a3b0>, '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 0x270b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270b740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', '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 02:29:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:29:28 INFO - Using env: {'DISPLAY': ':0', 02:29:28 INFO - 'HOME': '/home/cltbld', 02:29:28 INFO - 'LANG': 'en_US.UTF-8', 02:29:28 INFO - 'LANGUAGE': 'en_US:en', 02:29:28 INFO - 'LOGNAME': 'cltbld', 02:29:28 INFO - 'MAIL': '/var/mail/cltbld', 02:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:28 INFO - 'MOZ_NO_REMOTE': '1', 02:29:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:28 INFO - 'NO_EM_RESTART': '1', 02:29:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:28 INFO - 'PWD': '/builds/slave/test', 02:29:28 INFO - 'SHELL': '/bin/bash', 02:29:28 INFO - 'SHLVL': '1', 02:29:28 INFO - 'TERM': 'linux', 02:29:28 INFO - 'TMOUT': '86400', 02:29:28 INFO - 'USER': 'cltbld', 02:29:28 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', 02:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:28 INFO - '_': '/tools/buildbot/bin/python'} 02:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:28 INFO - Downloading/unpacking blobuploader==1.2.4 02:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:30 INFO - Downloading blobuploader-1.2.4.tar.gz 02:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:29:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:29:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:30 INFO - Downloading docopt-0.6.1.tar.gz 02:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:29:31 INFO - Installing collected packages: blobuploader, requests, docopt 02:29:31 INFO - Running setup.py install for blobuploader 02:29:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:29:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:29:31 INFO - Running setup.py install for requests 02:29:31 INFO - Running setup.py install for docopt 02:29:31 INFO - Successfully installed blobuploader requests docopt 02:29:31 INFO - Cleaning up... 02:29:31 INFO - Return code: 0 02:29:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x244b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270a3b0>, '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 0x270b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270b740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', '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 02:29:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:29:31 INFO - Using env: {'DISPLAY': ':0', 02:29:31 INFO - 'HOME': '/home/cltbld', 02:29:31 INFO - 'LANG': 'en_US.UTF-8', 02:29:31 INFO - 'LANGUAGE': 'en_US:en', 02:29:31 INFO - 'LOGNAME': 'cltbld', 02:29:31 INFO - 'MAIL': '/var/mail/cltbld', 02:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:31 INFO - 'MOZ_NO_REMOTE': '1', 02:29:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:31 INFO - 'NO_EM_RESTART': '1', 02:29:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:31 INFO - 'PWD': '/builds/slave/test', 02:29:31 INFO - 'SHELL': '/bin/bash', 02:29:31 INFO - 'SHLVL': '1', 02:29:31 INFO - 'TERM': 'linux', 02:29:31 INFO - 'TMOUT': '86400', 02:29:31 INFO - 'USER': 'cltbld', 02:29:31 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', 02:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:31 INFO - '_': '/tools/buildbot/bin/python'} 02:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:31 INFO - Running setup.py (path:/tmp/pip-YfaL_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:31 INFO - Running setup.py (path:/tmp/pip-4oL0Xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:32 INFO - Running setup.py (path:/tmp/pip-0s3f0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:32 INFO - Running setup.py (path:/tmp/pip-vWB9kS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:32 INFO - Running setup.py (path:/tmp/pip-6rQ1ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:32 INFO - Running setup.py (path:/tmp/pip-xx1MbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:32 INFO - Running setup.py (path:/tmp/pip-QnGRCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:32 INFO - Running setup.py (path:/tmp/pip-JV9Kzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:32 INFO - Running setup.py (path:/tmp/pip-j1m1Gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:32 INFO - Running setup.py (path:/tmp/pip-rqODQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:32 INFO - Running setup.py (path:/tmp/pip-9Jcf61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:32 INFO - Running setup.py (path:/tmp/pip-4TA3sf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:32 INFO - Running setup.py (path:/tmp/pip-5Xzjx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:32 INFO - Running setup.py (path:/tmp/pip-sxnp7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:32 INFO - Running setup.py (path:/tmp/pip-4tH2e5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:33 INFO - Running setup.py (path:/tmp/pip-rRr3Ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:33 INFO - Running setup.py (path:/tmp/pip-40YEzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:29:33 INFO - Running setup.py install for manifestparser 02:29:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:29:33 INFO - Running setup.py install for mozcrash 02:29:33 INFO - Running setup.py install for mozdebug 02:29:33 INFO - Running setup.py install for mozdevice 02:29:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:29:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:29:33 INFO - Running setup.py install for mozfile 02:29:33 INFO - Running setup.py install for mozhttpd 02:29:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:29:33 INFO - Running setup.py install for mozinfo 02:29:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:29:33 INFO - Running setup.py install for mozInstall 02:29:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:29:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:29:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:29:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:29:33 INFO - Running setup.py install for mozleak 02:29:34 INFO - Running setup.py install for mozlog 02:29:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:29:34 INFO - Running setup.py install for moznetwork 02:29:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:29:34 INFO - Running setup.py install for mozprocess 02:29:34 INFO - Running setup.py install for mozprofile 02:29:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:29:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:29:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:29:34 INFO - Running setup.py install for mozrunner 02:29:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:29:34 INFO - Running setup.py install for mozscreenshot 02:29:34 INFO - Running setup.py install for moztest 02:29:34 INFO - Running setup.py install for mozversion 02:29:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:29:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:29:34 INFO - Cleaning up... 02:29:34 INFO - Return code: 0 02:29:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:34 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 0x244b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270a3b0>, '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 0x270b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270b740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', '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 02:29:34 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 02:29:34 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 02:29:34 INFO - Using env: {'DISPLAY': ':0', 02:29:34 INFO - 'HOME': '/home/cltbld', 02:29:34 INFO - 'LANG': 'en_US.UTF-8', 02:29:34 INFO - 'LANGUAGE': 'en_US:en', 02:29:34 INFO - 'LOGNAME': 'cltbld', 02:29:34 INFO - 'MAIL': '/var/mail/cltbld', 02:29:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:34 INFO - 'MOZ_NO_REMOTE': '1', 02:29:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:34 INFO - 'NO_EM_RESTART': '1', 02:29:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:34 INFO - 'PWD': '/builds/slave/test', 02:29:34 INFO - 'SHELL': '/bin/bash', 02:29:34 INFO - 'SHLVL': '1', 02:29:34 INFO - 'TERM': 'linux', 02:29:34 INFO - 'TMOUT': '86400', 02:29:34 INFO - 'USER': 'cltbld', 02:29:34 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', 02:29:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:34 INFO - '_': '/tools/buildbot/bin/python'} 02:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:35 INFO - Running setup.py (path:/tmp/pip-7rtaER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:35 INFO - Running setup.py (path:/tmp/pip-VU5Xy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:35 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)) 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:35 INFO - Running setup.py (path:/tmp/pip-Skya39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:35 INFO - Running setup.py (path:/tmp/pip-h1ydBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:35 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)) 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:35 INFO - Running setup.py (path:/tmp/pip-XBwTqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:35 INFO - Running setup.py (path:/tmp/pip-d51h0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:35 INFO - Running setup.py (path:/tmp/pip-ALoL3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:35 INFO - Running setup.py (path:/tmp/pip-n1TFMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:35 INFO - Running setup.py (path:/tmp/pip-_DsJ9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:35 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)) 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:35 INFO - Running setup.py (path:/tmp/pip-gIgwIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:35 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)) 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:35 INFO - Running setup.py (path:/tmp/pip-fiCts3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:35 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)) 02:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:35 INFO - Running setup.py (path:/tmp/pip-kW9HbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:36 INFO - Running setup.py (path:/tmp/pip-kCUw2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:36 INFO - Running setup.py (path:/tmp/pip-birMZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:36 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)) 02:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:36 INFO - Running setup.py (path:/tmp/pip-Z2SCow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:36 INFO - Running setup.py (path:/tmp/pip-VRFTvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:36 INFO - Running setup.py (path:/tmp/pip-7G1VKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:29:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:38 INFO - Downloading blessings-1.6.tar.gz 02:29:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:38 INFO - Installing collected packages: blessings 02:29:38 INFO - Running setup.py install for blessings 02:29:38 INFO - Successfully installed blessings 02:29:38 INFO - Cleaning up... 02:29:38 INFO - Return code: 0 02:29:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:38 INFO - Reading from file tmpfile_stdout 02:29:38 INFO - Current package versions: 02:29:38 INFO - argparse == 1.2.1 02:29:38 INFO - blessings == 1.6 02:29:38 INFO - blobuploader == 1.2.4 02:29:38 INFO - docopt == 0.6.1 02:29:38 INFO - functools32 == 3.2.3-2 02:29:38 INFO - jsonschema == 2.5.1 02:29:38 INFO - manifestparser == 1.1 02:29:38 INFO - mozInstall == 1.12 02:29:38 INFO - mozcrash == 0.17 02:29:38 INFO - mozdebug == 0.1 02:29:38 INFO - mozdevice == 0.48 02:29:38 INFO - mozfile == 1.2 02:29:38 INFO - mozhttpd == 0.7 02:29:38 INFO - mozinfo == 0.9 02:29:38 INFO - mozleak == 0.1 02:29:38 INFO - mozlog == 3.2 02:29:38 INFO - moznetwork == 0.27 02:29:38 INFO - mozprocess == 0.23 02:29:38 INFO - mozprofile == 0.28 02:29:38 INFO - mozrunner == 6.12 02:29:38 INFO - mozscreenshot == 0.1 02:29:38 INFO - mozsystemmonitor == 0.3 02:29:38 INFO - moztest == 0.7 02:29:38 INFO - mozversion == 1.4 02:29:38 INFO - psutil == 3.1.1 02:29:38 INFO - requests == 1.2.3 02:29:38 INFO - wsgiref == 0.1.2 02:29:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:38 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')]} 02:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:38 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')]} 02:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:38 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 0x244b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270a3b0>, '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 0x270b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270b740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', '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 02:29:38 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 02:29:38 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 02:29:38 INFO - Using env: {'DISPLAY': ':0', 02:29:38 INFO - 'HOME': '/home/cltbld', 02:29:38 INFO - 'LANG': 'en_US.UTF-8', 02:29:38 INFO - 'LANGUAGE': 'en_US:en', 02:29:38 INFO - 'LOGNAME': 'cltbld', 02:29:38 INFO - 'MAIL': '/var/mail/cltbld', 02:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:38 INFO - 'MOZ_NO_REMOTE': '1', 02:29:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:38 INFO - 'NO_EM_RESTART': '1', 02:29:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:38 INFO - 'PWD': '/builds/slave/test', 02:29:38 INFO - 'SHELL': '/bin/bash', 02:29:38 INFO - 'SHLVL': '1', 02:29:38 INFO - 'TERM': 'linux', 02:29:38 INFO - 'TMOUT': '86400', 02:29:38 INFO - 'USER': 'cltbld', 02:29:38 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', 02:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:38 INFO - '_': '/tools/buildbot/bin/python'} 02:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:38 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)) 02:29:38 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)) 02:29:38 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)) 02:29:38 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)) 02:29:38 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)) 02:29:38 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)) 02:29:38 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)) 02:29:38 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)) 02:29:38 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)) 02:29:38 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)) 02:29:38 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)) 02:29:38 INFO - Cleaning up... 02:29:39 INFO - Return code: 0 02:29:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29: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')]} 02:29:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:29:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x244b030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270a3b0>, '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 0x270b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270b740>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', '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 02:29:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:29:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:29:39 INFO - Using env: {'DISPLAY': ':0', 02:29:39 INFO - 'HOME': '/home/cltbld', 02:29:39 INFO - 'LANG': 'en_US.UTF-8', 02:29:39 INFO - 'LANGUAGE': 'en_US:en', 02:29:39 INFO - 'LOGNAME': 'cltbld', 02:29:39 INFO - 'MAIL': '/var/mail/cltbld', 02:29:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:39 INFO - 'MOZ_NO_REMOTE': '1', 02:29:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:39 INFO - 'NO_EM_RESTART': '1', 02:29:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:39 INFO - 'PWD': '/builds/slave/test', 02:29:39 INFO - 'SHELL': '/bin/bash', 02:29:39 INFO - 'SHLVL': '1', 02:29:39 INFO - 'TERM': 'linux', 02:29:39 INFO - 'TMOUT': '86400', 02:29:39 INFO - 'USER': 'cltbld', 02:29:39 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', 02:29:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:39 INFO - '_': '/tools/buildbot/bin/python'} 02:29:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:29:39 INFO - Cleaning up... 02:29:39 INFO - Return code: 0 02:29:39 INFO - Running post-action listener: _resource_record_post_action 02:29:39 INFO - Running post-action listener: _start_resource_monitoring 02:29:39 INFO - Starting resource monitoring. 02:29:39 INFO - ##### 02:29:39 INFO - ##### Finished create-virtualenv step (success) 02:29:39 INFO - ##### 02:29:39 INFO - ##### 02:29:39 INFO - ##### Running install step. 02:29:39 INFO - ##### 02:29:39 INFO - Running pre-action listener: _resource_record_pre_action 02:29:39 INFO - Running main action method: install 02:29:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:39 INFO - Reading from file tmpfile_stdout 02:29:39 INFO - Detecting whether we're running mozinstall >=1.0... 02:29:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:29:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:29:39 INFO - Reading from file tmpfile_stdout 02:29:39 INFO - Output received: 02:29:39 INFO - Usage: mozinstall [options] installer 02:29:39 INFO - Options: 02:29:39 INFO - -h, --help show this help message and exit 02:29:39 INFO - -d DEST, --destination=DEST 02:29:39 INFO - Directory to install application into. [default: 02:29:39 INFO - "/builds/slave/test"] 02:29:39 INFO - --app=APP Application being installed. [default: firefox] 02:29:39 INFO - mkdir: /builds/slave/test/build/application 02:29:39 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'] 02:29:39 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 02:29:55 INFO - Reading from file tmpfile_stdout 02:29:55 INFO - Output received: 02:29:55 INFO - /builds/slave/test/build/application/firefox/firefox 02:29:55 INFO - Running post-action listener: _resource_record_post_action 02:29:55 INFO - ##### 02:29:55 INFO - ##### Finished install step (success) 02:29:55 INFO - ##### 02:29:55 INFO - ##### 02:29:55 INFO - ##### Running run-tests step. 02:29:55 INFO - ##### 02:29:55 INFO - Running pre-action listener: _resource_record_pre_action 02:29:55 INFO - Running main action method: run_tests 02:29:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:55 INFO - Python 2.7.3 02:29:55 INFO - Return code: 0 02:29:55 INFO - grabbing minidump binary from tooltool 02:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:29:55 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270a3b0>, '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 0x270b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:29:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 02:29:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:29:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:56 INFO - Return code: 0 02:29:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:29:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:29:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:56 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/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 02:29:56 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/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 02:29:56 INFO - Using env: {'DISPLAY': ':0', 02:29:56 INFO - 'HOME': '/home/cltbld', 02:29:56 INFO - 'LANG': 'en_US.UTF-8', 02:29:56 INFO - 'LANGUAGE': 'en_US:en', 02:29:56 INFO - 'LOGNAME': 'cltbld', 02:29:56 INFO - 'MAIL': '/var/mail/cltbld', 02:29:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:56 INFO - 'MOZ_NO_REMOTE': '1', 02:29:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:56 INFO - 'NO_EM_RESTART': '1', 02:29:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:56 INFO - 'PWD': '/builds/slave/test', 02:29:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:56 INFO - 'SHELL': '/bin/bash', 02:29:56 INFO - 'SHLVL': '1', 02:29:56 INFO - 'TERM': 'linux', 02:29:56 INFO - 'TMOUT': '86400', 02:29:56 INFO - 'USER': 'cltbld', 02:29:56 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134', 02:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:56 INFO - '_': '/tools/buildbot/bin/python'} 02:29:56 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/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 02:29:56 INFO - mozversion application_buildid: 20160718015953 02:29:56 INFO - mozversion application_changeset: 76bb0b29a7509fe35db3beb571fec162cc781a80 02:29:56 INFO - mozversion application_display_name: Nightly 02:29:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:56 INFO - mozversion application_name: Firefox 02:29:56 INFO - mozversion application_remotingname: firefox 02:29:56 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:29:56 INFO - mozversion application_vendor: Mozilla 02:29:56 INFO - mozversion application_version: 50.0a1 02:29:56 INFO - mozversion platform_buildid: 20160718015953 02:29:56 INFO - mozversion platform_changeset: 76bb0b29a7509fe35db3beb571fec162cc781a80 02:29:56 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:29:56 INFO - mozversion platform_version: 50.0a1 02:29:56 INFO - using testdate: 1468834196 02:29:56 INFO - actual date: 1468834196 02:29:56 INFO - starting webserver on 'localhost:60439' 02:29:56 INFO - SUITE-START | Running 2 tests 02:29:56 INFO - TEST-START | dromaeo_css 02:29:56 INFO - operating with platform_type : linux_ 02:29:56 INFO - Initialising browser for dromaeo_css test... 02:29:56 INFO - TEST-INFO | started process 19971 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbLMhsi/profile http://localhost:60439/getInfo.html) 02:29:59 INFO - PROCESS | 19971 | JavaScript error: , line 0: uncaught exception: undefined 02:30:00 INFO - PROCESS | 19971 | __metrics Screen width/height:1600/1200 02:30:00 INFO - PROCESS | 19971 | colorDepth:24 02:30:00 INFO - PROCESS | 19971 | Browser inner width/height: 1024/697 02:30:00 INFO - PROCESS | 19971 | __metrics 02:30:00 INFO - PROCESS | 19971 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:30:00 INFO - PROCESS | 19971 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:30:01 INFO - TEST-INFO | 19971: exit 0 02:30:01 INFO - Browser initialized. 02:30:01 INFO - Running cycle 1/1 for dromaeo_css test... 02:30:01 INFO - TEST-INFO | started process 20055 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbLMhsi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:30:13 INFO - PROCESS | 20055 | RSS: Main: 159645696 02:30:13 INFO - PROCESS | 20055 | 02:31:05 INFO - PROCESS | 20055 | 1468834265191 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] 02:33:17 INFO - PROCESS | 20055 | Cycle 1(1): loaded http://localhost:60439/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60439/tests/dromaeo/cssquery-ext.html) 02:33:18 INFO - PROCESS | 20055 | RSS: Main: 253136896 02:33:18 INFO - PROCESS | 20055 | 02:36:21 INFO - PROCESS | 20055 | Cycle 1(1): loaded http://localhost:60439/tests/dromaeo/cssquery-ext.html (next: http://localhost:60439/tests/dromaeo/cssquery-jquery.html) 02:36:21 INFO - PROCESS | 20055 | RSS: Main: 251453440 02:36:21 INFO - PROCESS | 20055 | 02:39:24 INFO - PROCESS | 20055 | Cycle 1(1): loaded http://localhost:60439/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60439/tests/dromaeo/cssquery-mootools.html) 02:39:24 INFO - PROCESS | 20055 | RSS: Main: 246702080 02:39:24 INFO - PROCESS | 20055 | 02:42:26 INFO - PROCESS | 20055 | Cycle 1(1): loaded http://localhost:60439/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60439/tests/dromaeo/cssquery-prototype.html) 02:42:27 INFO - PROCESS | 20055 | RSS: Main: 246341632 02:42:27 INFO - PROCESS | 20055 | 02:45:30 INFO - PROCESS | 20055 | Cycle 1(1): loaded http://localhost:60439/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60439/tests/dromaeo/cssquery-yui.html) 02:45:31 INFO - PROCESS | 20055 | RSS: Main: 223195136 02:45:31 INFO - PROCESS | 20055 | 02:48:34 INFO - PROCESS | 20055 | Cycle 1(1): loaded http://localhost:60439/tests/dromaeo/cssquery-yui.html (next: http://localhost:60439/tests/dromaeo/cssquery-dojo.html) 02:48:34 INFO - PROCESS | 20055 | RSS: Main: 281952256 02:48:34 INFO - PROCESS | 20055 | 02:48:34 INFO - PROCESS | 20055 | __start_tp_report 02:48:34 INFO - PROCESS | 20055 | _x_x_mozilla_page_load 02:48:34 INFO - PROCESS | 20055 | _x_x_mozilla_page_load_details 02:48:34 INFO - PROCESS | 20055 | |i|pagename|runs| 02:48:34 INFO - PROCESS | 20055 | |0;dojo.html;2641.141628970585;2655.229983304842;2664.7734942529883;2694.9730502694965;2702.418979919317;4513.413256276679;4586.151561961031;4713.552212539807;4829.589484893784;4843.975780121099;4630.536946305368;4671.602913752336;4676.298555216719;4697.694649847757;4700.435947686274;4268.125034367963;4282.100758840632;4352.368906508555;4360.956390436095;4374.868753937371;103.77878608302893;4390.187815254172;4396.230659634552;4399.5820397062225;4428.247197976351;4301.032267739764;4336.934945975829;4342.088161486092;4364.432623758923;4368.4102646143;4311.28863737483;4326.480822301312;4333.090051089275;4359.891002724926;4380.12397520494;4227.78861056949;4309.612134907842;4313.913721725579;4330.956690433087;4353.064091220403;4598.816047358102;4668.159731248374;4716.363290955712;4737.73942433166;4771.188897887368;5052.8484145475695;5078.958813443235;5122.410922743895;5123.2315152727015;5131.102057140023;4897.118518666639;5125.641205115643;5146.665466744687;5155.072087024334;5165.276861239429;4576.542345765397;4603.792829322689;4611.953880461219;4612.262038073959;4636.907261854812;5093.083245015859;5105.744712764347;5107.438181799999;5114.897702045938;5118.078745825787;4859.319695242669;4905.681130726492;4906.11689895815;4908.8772780680765;4914.262860570943;4810.39870016248;4927.408710954708;4932.728699921467;4981.526754958346;4987.975060124749;4936.259561065869;4939.90120197594;4946.777395017233;4951.405831300267;4982.825601103944;4925.310456536088;4928.6303527235605;4940.851774446773;4949.703017818871;4985.675931064469;3897.512810898638;3981.3231750602467;3984.4620976168426;3992.281389349887;4037.5356833963883;3464.72282217422;3527.470879368064;3535.169235229719;3555.520004799319;3562.4834399011997;4221.493420789525;4280.443542339476;4283.850065247763;4315.568443155659;4329.134173165379;4946.777395017233;4955.554000139932;4964.900701986012;4974.701517908937;5016.974915125401;4252.383404406344;4263.616274535245;4266.722663026893;4272.145570885836;4316.115196384733;4544.522825103382;4588.839390621312;4592.058627981256;4592.081583683277;4602.332661764025;4774.212254977959;4777.116233496814;4780.808767649394;4783.569478746929;4832.613390928717;4879.39007624047;5018.623603229846;5075.137226671401;5076.898462030813;5110.1568241239775;4766.95233047679;4771.260454629538;4786.473487916257;4787.281907713732;4829.73436460998;4993.650444468853;4994.1509943309;5003.524665156804;5006.849794506171;5047.520485553867;4733.053389322218;4952.479989601185;5021.096202683552;5059.114654935445;5120.923186152285;4869.6347773917805;4905.77923993414;4912.459629440687;4939.086269040239;4951.554360107608;4895.657303988686;4896.1921282987905;4900.95099049005;4907.582855457326;4943.8516844494725;4661.091087238092;4746.7389293589185;4783.928241076317;4805.951940480551;4813.4945830687275;4873;4875.268709693574;4885.193942999365;4893.290472881484;4933.235348520985;4742.5731684148595;4746.050789841976;4755.405574303212;4789.736564488987;4835.758212089311 02:48:34 INFO - PROCESS | 20055 | |1;ext.html;568.2272109930494;573;579.472679861326;586.1178925716796;610.6793933185074;26560.203193904188;38297;39646;39750.00624984377;44292.55707442925;39915;39980.80009599948;40193.39709904347;40322;40400;7516.436267279949;7702.6918923243;7750.8449831003345;7752.689892404297;7755.340796032316;7635.427342949273;7709.229077092274;7709.730159444421;7713.190115037924;7727.9227207728045;7679.769606911802;7734.342580880632;7737.883931741028;7748.845023099535;7762.30139287464;7666.00341955545;7688.6155692215725;7690.115636701739;7692.769216923473;7708.03649543807;7712.845743085163;7742.961285193538;7763.146053934062;7768.067831860157;7778.416618753559;38835;39203.41194882079;39244.80377598094;39269.80365098185;39332;2855.129185598392;2960.899669414129;3016.909492715222;3236.6925142111463;3542.716582673406;26768.063117790978;26783.598246026326;27017.594736078976;27327.180184594494;28171.014014509597;3346.380919529895;3356.6978971892404;3366.309906469168;3371.089805752458;3417.043227896193;261127;261902.69048654634;262104.78952104828;262218;263593;42276.57723422788;42630.147397051885;43739.78130109329;52810;53151;51225;56802.14796778052;56866.14700779492;56916.43083569194;56940;12930.094893357564;13160.341982900816;13181.340932953506;13192.868071319355;13207.40566674502;9001.694754260727;9800.754981125529;9875.506224688737;10228.795424091477;10525.052745252964;16081.356745730274;16164.676706465805;16167.434139805051;16172.191390430431;16219.756703649455;5568.52667523257;5576.972115139399;5606.355269144101;5612.97193514038;5641.52047075995;776.8290975985274;833.3749687734199;883.8143989762093;904.742148487691;906.9138431848963;27906.720932790413;28629.713702863122;28638.14085577436;28791.136265912057;28854.305558319684;989.9950500247453;999.3853779925439;1015.5633077776479;1068.3750006246432;1087.5812812067252;14758.335874885655;15243.847561524242;15307.617309567127;15424.228788560527;15518.767218491732;403674;406811;408674;409035;410674;49699.50300496949;49901.25148122781;49996.250056249184;50014.74992625014;50091.49908500941;1083.1930211992117;1090.2150451674781;1154.7632735289246;1179.7050737315672;1191.7497325561383;28956;29497.410051799274;29631;29972.550411743407;30447.543286850272;41642.791786042086;41727.37408938929;42018.57981420147;42060.57939420567;42084.15831683288;299068;299168;301068;302489;302553;24713.25860224196;25385.111521096675;25722.742772572034;26921.730782691924;26942.171072037152;40063;40640.390394144706;40775.71854955187;41013;41526.16947660969;37153.25693486169;37268.813655931546;37431.625683742815;38032.61967380291;38047.239055219296;37108.25783484261;37211.25577488489;37232.25535489221;37503;37992.62007380001;31511.218653761694;34220.31559368849;34234;36151.81924090363;36620.45069323909;29687.703122968494;33886.830565848;34024.14939626529;34243.65756342504;34317.48523772195;25154.874225628755;25750.484990300465;25896.179824605224;25977.870110649324;25987.870060649577 02:48:34 INFO - PROCESS | 20055 | |2;jquery.html;342.44359386587956;360.16082527710427;360.62855259083153;362.8856910073327;375.8177284017251;12973.870261297396;17086.31654733811;17994.28022879085;19359.516012099706;19459.178419129217;16430.096344701036;16656.66686666266;16909.7463538047;16930.576735581617;16964.32142714293;4944.456109827916;5260.973695131538;5262.184361423985;5285.365756109272;5290.285811415448;3780.395136778116;5189.091908915943;5822.563307751935;6140.84647883802;6167.62994220346;6028.5478589106;6196.101565273033;6220.440160385564;6222.844428889269;6225.968870155642;6091.055886337603;6192;6207.751689932397;6215.098810672473;6216.875662486747;5401.810936617238;5419.214213938996;5420.83737487876;6428.132202152697;6822.181338239393;23058.308250752503;23977.160799371937;24008.27975160748;24341.756582434125;24948.750512494826;1986.3941497843152;2026.6453370660076;2043.2746375036945;2057.423921302023;2505.736897625741;13902.16587004783;15140.015898966532;15233.238338083162;15329.156896370696;15352.846471535253;1894.8473622694553;1930.2182616040527;1930.9420717378362;1941.980580194194;2019.3235266185845;81292.21262916904;85766.57116714439;87290;87640;87705;14189.85810141906;14264.42942282318;15702.842971570366;15906.840931590767;16123.178443772293;28756.568651470243;28801.135965921058;29030.564541531894;29093;29236.707632923823;8358.122397148334;8411.915880841114;8418;8490.533020683923;8543.35924805642;6849.315068493211;6851.3491218334175;6862.4853136015;6918.654067296716;6934.271901450275;8230.514369668424;8519.787005324917;8619.224269815746;8620.310375169971;8621.525816080175;3201.5197720342135;3368.292658541685;3372.915677108092;3390.5083762854247;3401.5577974863604;773.1765669561898;811.3205664274894;831.6340810043562;840.4201101240125;877.4121338703085;17072.061036642855;17336.913315433343;17676.116194190497;17683.557911052325;18550.350737724384;810.5217921426387;839.1901814748796;866.696656170348;907.1880666803174;939.1829108675496;8559.529225892637;9957.55191016406;10087.344322619007;10102.292839501311;10229.130523905402;296909;303856;305298;349796;350739;82638.17361826425;83133.58433207916;84034.57982710048;86415.13584864196;88031.55984220166;890.6615486115364;902.2195800632405;926.2173463423396;928.6378312458147;941.7268991992382;16448.26610962507;16456.67086658284;16513.752293715843;16638.001719896845;16787.076710781024;25768.87115564485;35377.1155721109;35977.46033809547;37330.25339493249;37933;51810.481895180565;52612.42162735124;52882.2067668993;53096.4690353107;53287;7051.858962820612;7314.122305323182;7453.888191677238;7547.849043018999;7573.507721998052;9004.684836030969;11028.558857645605;11078.723031924266;11483.48324325424;11561.727107482131;9231.35380523357;9256.25949924018;9834.065763752718;9886.357386769838;9931.602735890772;9765;9791.167750740982;9878.753031174278;9997.850032249667;10053;7834.608269586612;9834.754131146492;10175.949120254352;10303.896961030594;10367.118794902517;6929.792106236821;7147.392471639969;7256.492045556972;7309.122905251404;7613.0483689538805;9622.855657165288;9653.758656033655;9738.951305243429;9866.161376283097;9915.454649994319 02:48:34 INFO - PROCESS | 20055 | |3;mootools.html;1331.1480652382475;1403.5789263221031;1434.655682636167;1634.2237437217316;1686.4603326935378;7516;7910.406719496032;8026.598670066488;8028.23731745484;8031.759047228578;6706.832329191772;7383.556986580809;7597.278258565441;7606.239376062391;7909.090454597723;1969.5371587676848;2017.5460521382718;2019.899005049749;2026.9189232430685;2029.6752519596866;2134.647783115784;2162.351294611618;2162.610730068587;2168.3386567097027;2171.869687818736;1835.8072402397756;2068.88621125838;2143.6141494531057;2144.967825482619;2170.2946542373793;2133.0827744070043;2137.3587923622927;2149.215536329244;2149.8602590831547;2161.3840055584083;2167.674848772681;2178.357384571555;2186.726659167604;2186.9781302186934;2188.245055455865;8019.198080191993;8090.150534193943;8093.595320233965;8097.797555061112;8101.230383113595;4498.595126438636;4658.301254811805;4692.507286734878;4696.906061878777;4712.222483290253;2888.1046875468674;2934.970650293491;3025.7125573070525;3029.7727670424806;3034.767972109696;933.3886304470583;934.0799312677008;937.6952490440634;956.5743244256308;964.9228061755043;10697.83953240702;10718.464076796128;10727.49815025897;10728.302660327054;10777.5688972441;4052.615001574846;4233.809478573472;4336.436263285816;4343.500497442771;4358.76026818528;9093.818123637622;9157.908420915706;9180.44917304964;9277;9282.164605185566;4783.402074740658;4790.089882922233;4794.208955522369;4801.327814106028;4810.831620893252;4254.553271906467;4261.637760790368;4329.718568293051;4331.133773245364;4331.545187755303;3713.0717320669833;3723.739338246351;3761.962380376216;3821;3822.694184465236;1069.753956589973;1163.9010684091777;1179.9203728588418;1186.9821952670545;1193.4689063366718;710.5630037526987;741.4476215219588;799.7240951871595;825.3232349473374;835.302522393808;5049.192129259374;5112.054269960069;5123.590112790967;5308.840734777963;5376.91934620981;806.1334065879204;842.9072801991776;847.6270441005938;853.9018012928593;865.6494119881459;3662.9633703663153;3729.738918275749;3746.737728359043;3750.5311836020496;3812.390017597171;9518.524073796283;9896.25778066648;9990.550425230882;10001.749956251006;10002.649907253211;9587.137157655845;9895.158911492459;9900.257480688977;9913.30606857513;9920.107190352903;7247.804112321408;7296.817579560554;7338.009368735153;7373.741919032808;7387.519811212362;5060.822871199491;5294.258803767399;5356.5982551308025;5436.157395603688;5443.618946673695;9011.594478248364;9091.272698184262;9099.954500227457;9195.678151264674;9200.95399522998;8619.353548483763;8926.107389261022;8937.46375217489;9212.723618291462;9266.02706715812;6863.313668633096;7033.226345102137;7035.929640703732;7040;7040.084788977605;7437.405007599271;7464.290892365217;7519.360854327443;7531.8493630125995;7612.238776122344;6084.452399284086;6124.663143526971;6835.829104272234;6857.622830744157;7122.7507037253445;6979.930200698132;7012.088428504466;7057.082579264663;7080.858382832212;7095.254998225112;6238.563300568917;6369.87260254783;6449.451796597342;6454.935450645434;6456.838579035561;4582.152301824173;4594.954050459586;4627.514111018295;4628.717649213302;4770.0221454602415;4529;4640.095181439587;4660.440747110369;4678.92981605283;4697.741624210701 02:48:34 INFO - PROCESS | 20055 | |4;prototype.html;297.1708932079498;297.7350158359059;299.10269192422726;307.12316336858254;307.22272650195015;4877.829275975341;4924.827631032915;4930.038642464717;4932.087563800699;4976.676516026456;4834.758262086899;4836.27455881618;4848.709077455355;4879.512048795119;4880.38899188934;3614.5843228028784;3712.477884067533;3738.5700644425833;3843.404272337792;3861.1119442528234;3781.508403907488;3789.3368660484443;3822.3502004659276;3823.4456003879422;3855.7108216883844;3601.3253836965937;3758.887233382989;3763.9059023524355;3794.6149655375725;3810.8285127169343;3642.6175251598597;3791.848326066954;3795.9240815183543;3805.8667946621736;3819.4652748615217;3345.280764635615;3353.2060347713855;3356.295178012596;3365.3269346130874;3376.9155771105675;4685.1566717990745;4701.670883038189;4705.788239529229;4718.976405117986;4735.668503204777;4250.851220207278;4343.174796788631;4348.152110338454;4381.583749543788;4392.648588112976;5059;5077.187649976023;5081.79672813087;5094.872628184288;5098.770555325019;3674.2835147146316;3682.3371793077235;3690.5755838078694;3694.612065733086;3695.6858667013066;5178.430372659004;5297.761600727977;5308.867278318035;5311.7344132793205;5322.627416080876;3762.285165818486;3762.8682996095004;3763.021614380281;3772;3784.962150378461;3823.751456155341;3826.6556009959236;3844.519435070616;3848.942265866014;3852.4799152114665;4362.192994296065;4362.687775479001;4374.671899607542;4376.824927002885;4389.6927215095275;4890.853274401774;5036.169032109661;5041.747912604355;5047.470015648378;5051.26756620288;1294.996377807188;1319.808627748971;1322.8412590489204;1323.1928523600595;1323.7749582570987;860.2172023458622;862.66787286895;887.8268737596106;915.6154415145655;916.9908300916906;4409.272470042407;4427.579379958899;4433.179861725591;4444.555544445594;4461.799219035086;5126.4617215191865;5132.101882170605;5155.61332900034;5159.819406320836;5178.559822415062;4224.809883555247;4413.34499562664;4427.092446048552;4429.936815164402;4439.645907998027;2758.820676656011;2762.958555621667;2768.4739899419046;2768.6123942648046;2769.1692492252246;4888.413390393175;4902.362692849978;4911.950880491149;4912.680675756064;4918.1884988977135;5036.438704001771;5192.480751924777;5239.502247286502;5321.787128514893;5337.8131765388025;4826.710397376169;4836.347093142381;4849.248366503198;4851.611871050308;4862;5099.847004589868;5133.717645529532;5137.075326441276;5155.674589889575;5158.535731784157;4920.975395123001;5033.5721463676;5043.142665746906;5046.369203849519;5083.46623604531;4813.614910807197;4822.397200349957;4826;4839.854804355875;4844.806207751792;3727.347714149959;3734.6638802507905;3735.8879233623034;3739.420389839579;3741.2517496500263;4915.557599816034;5119.564836988898;5141;5155.613329000265;5162.767675454538;4906.509349065065;4918.5573298403315;4929.852104436729;4939.333190019302;4944.208926571731;4921.13880070979;4938.777755001105;4951.3510272004305;4954.430240522386;4964.379452568429;4688.249880019181;4723.197056500472;4867.853964381075;4887.560119589254;4903.264510323337;4944.283078953604;5067.898642027212;5071.239314102914;5074.264231686311;5100.48995100487;4949.876253093701;4962.404511458648;4989.650724449254;4993.650444468853;4994.9500504994485 02:48:34 INFO - PROCESS | 20055 | |5;yui.html;596.5317225977611;648.4941745438559;667.0061608203774;690.6063543780045;725.9582349953694;12916.547920822773;13770.449182032708;13802;13852.445902163927;14014.089084209532;12961.740765184713;13085.14946528475;13137.671558211026;13145.342732863366;13309.93345033276;514.7734996601503;525.254139122447;751.2074761127009;754.486948874765;774.6126936531734;768.2394429514768;768.323874990008;776.8873513340562;777.4790890103616;778.0779775965499;834.8121672623672;835.0355339582761;835.1940377535707;836.9246767790897;837.3301358912876;547.0699810322448;571.4085921071671;597.2176448852018;603.815836169968;819.6229734322219;581.8865321262314;599.5383554662928;600.2736688606791;601.1043545117786;605.0561124645567;12991.480340786356;13042.347882605833;13050.23821784066;13058.085933984625;13082.738345233138;12169.2698438094;12697.365131743469;12707.364631768467;12762.106652534327;13018.86981130186;11423.543058277659;11496.770064598744;11674.35791031493;11753.059755219561;12195.634130976085;4054.189162167578;4137.151883863831;4197.034682023152;4243.130158317537;4247.426597409341;58323;58503.70748146232;58675.11987320193;58689;58956;9708.001895120095;9843.409395436296;10058.245631577662;10087.899121008842;10096.394216347042;18577.16402761879;18599;18758.624827503372;18812;18843.528911777314;6478.838029049311;6556.541042127101;6568.270844991598;6742.190937087581;6742.359475849787;3568.7680300780366;3631.0559254593995;3649.9817500912327;3652.2695460907394;3713.350163721338;2791.371941313229;2880.625518682558;2887.5524293734507;2897.840618765988;2898.942021159565;545.841705905291;567.2257368691706;573.4036601934025;581.1428143488321;583.5127668396851;552.9060059789846;559.286909190785;572.3303734630492;582.3710392775791;587.3832475900288;7857.724979625686;8157.1842815717955;8344.165583441607;8399.034111077304;8574.356923230658;587.6121759638618;595.9970200148972;600.1358044416114;606.7481994972126;614.8247749391402;3655.214128962286;3777.131259810204;3805.429185622179;3913.4521167036637;3938.8424463021734;97404;105687;106255.46872265589;109459;109590;89830;95136;95347;95353.52323238339;95519.52240238893;83049.58475207585;84075;84882.5755871229;84958.57520712356;85248.57375713081;7127.8574428512175;7335.77992660221;7402.370798481974;7561.3194812467145;7604.8479030420185;38654;38846.223075538896;39003.82988510349;42060.94847628834;43464.130717386106;62075.37924620696;62710;62903.6854815723;63140;64833;10902.509387077442;11036.11711063097;11107.166962478008;11219.66341009771;11436.085113191091;12341.382930853242;12523.74952500963;12542.623721288375;12594;12829.486820527074;10744.355338679705;11021.44892755375;11241.875812418693;11279.943600281946;11843.170978031456;11289.604863829727;11296.226302492538;11327.716807079889;11512.94243528777;11874;11263.718407039561;11278.943605281922;11463.828042579202;12140.817887731868;12151.453184606891;8740.825183496421;8898.110188981309;9112.316576256675;9166.17504424605;9214.401063930824;9112.037225159731;9536.523173841142;9567.234621230424;9601.855972160283;9651.71044868655 02:48:34 INFO - PROCESS | 20055 | __end_tp_report 02:48:34 INFO - PROCESS | 20055 | __start_cc_report 02:48:34 INFO - PROCESS | 20055 | _x_x_mozilla_cycle_collect,930 02:48:34 INFO - PROCESS | 20055 | __end_cc_report 02:48:34 INFO - PROCESS | 20055 | __startTimestamp1468835314497__endTimestamp 02:48:34 INFO - PROCESS | 20055 | 02:48:34 INFO - PROCESS | 20055 | ------- Summary: start ------- 02:48:34 INFO - PROCESS | 20055 | Number of tests: 6 02:48:34 INFO - PROCESS | 20055 | 02:48:34 INFO - PROCESS | 20055 | [#0] dojo.html Cycles:165 Average:4597.55 Median:4777.12 stddev:609.13 (12.8%) stddev-sans-first:591.35 02:48:34 INFO - PROCESS | 20055 | Values: 2641.1 2655.2 2664.8 2695.0 2702.4 4513.4 4586.2 4713.6 4829.6 4844.0 4630.5 4671.6 4676.3 4697.7 4700.4 4268.1 4282.1 4352.4 4361.0 4374.9 103.8 4390.2 4396.2 4399.6 4428.2 4301.0 4336.9 4342.1 4364.4 4368.4 4311.3 4326.5 4333.1 4359.9 4380.1 4227.8 4309.6 4313.9 4331.0 4353.1 4598.8 4668.2 4716.4 4737.7 4771.2 5052.8 5079.0 5122.4 5123.2 5131.1 4897.1 5125.6 5146.7 5155.1 5165.3 4576.5 4603.8 4612.0 4612.3 4636.9 5093.1 5105.7 5107.4 5114.9 5118.1 4859.3 4905.7 4906.1 4908.9 4914.3 4810.4 4927.4 4932.7 4981.5 4988.0 4936.3 4939.9 4946.8 4951.4 4982.8 4925.3 4928.6 4940.9 4949.7 4985.7 3897.5 3981.3 3984.5 3992.3 4037.5 3464.7 3527.5 3535.2 3555.5 3562.5 4221.5 4280.4 4283.9 4315.6 4329.1 4946.8 4955.6 4964.9 4974.7 5017.0 4252.4 4263.6 4266.7 4272.1 4316.1 4544.5 4588.8 4592.1 4592.1 4602.3 4774.2 4777.1 4780.8 4783.6 4832.6 4879.4 5018.6 5075.1 5076.9 5110.2 4767.0 4771.3 4786.5 4787.3 4829.7 4993.7 4994.2 5003.5 5006.8 5047.5 4733.1 4952.5 5021.1 5059.1 5120.9 4869.6 4905.8 4912.5 4939.1 4951.6 4895.7 4896.2 4901.0 4907.6 4943.9 4661.1 4746.7 4783.9 4806.0 4813.5 4873.0 4875.3 4885.2 4893.3 4933.2 4742.6 4746.1 4755.4 4789.7 4835.8 02:48:34 INFO - PROCESS | 20055 | 02:48:34 INFO - PROCESS | 20055 | [#1] ext.html Cycles:180 Average:47551.70 Median:38172.12 stddev:86847.70 (227.5%) stddev-sans-first:87019.68 02:48:34 INFO - PROCESS | 20055 | Values: 568.2 573.0 579.5 586.1 610.7 26560.2 38297.0 39646.0 39750.0 44292.6 39915.0 39980.8 40193.4 40322.0 40400.0 7516.4 7702.7 7750.8 7752.7 7755.3 7635.4 7709.2 7709.7 7713.2 7727.9 7679.8 7734.3 7737.9 7748.8 7762.3 7666.0 7688.6 7690.1 7692.8 7708.0 7712.8 7743.0 7763.1 7768.1 7778.4 38835.0 39203.4 39244.8 39269.8 39332.0 2855.1 2960.9 3016.9 3236.7 3542.7 26768.1 26783.6 27017.6 27327.2 28171.0 3346.4 3356.7 3366.3 3371.1 3417.0 261127.0 261902.7 262104.8 262218.0 263593.0 42276.6 42630.1 43739.8 52810.0 53151.0 51225.0 56802.1 56866.1 56916.4 56940.0 12930.1 13160.3 13181.3 13192.9 13207.4 9001.7 9800.8 9875.5 10228.8 10525.1 16081.4 16164.7 16167.4 16172.2 16219.8 5568.5 5577.0 5606.4 5613.0 5641.5 776.8 833.4 883.8 904.7 906.9 27906.7 28629.7 28638.1 28791.1 28854.3 990.0 999.4 1015.6 1068.4 1087.6 14758.3 15243.8 15307.6 15424.2 15518.8 403674.0 406811.0 408674.0 409035.0 410674.0 49699.5 49901.3 49996.3 50014.7 50091.5 1083.2 1090.2 1154.8 1179.7 1191.7 28956.0 29497.4 29631.0 29972.6 30447.5 41642.8 41727.4 42018.6 42060.6 42084.2 299068.0 299168.0 301068.0 302489.0 302553.0 24713.3 25385.1 25722.7 26921.7 26942.2 40063.0 40640.4 40775.7 41013.0 41526.2 37153.3 37268.8 37431.6 38032.6 38047.2 37108.3 37211.3 37232.3 37503.0 37992.6 31511.2 34220.3 34234.0 36151.8 36620.5 29687.7 33886.8 34024.1 34243.7 34317.5 25154.9 25750.5 25896.2 25977.9 25987.9 02:48:34 INFO - PROCESS | 20055 | 02:48:34 INFO - PROCESS | 20055 | [#2] jquery.html Cycles:180 Average:24121.31 Median:29193.38 stddev:54308.89 (186.0%) stddev-sans-first:54431.90 02:48:34 INFO - PROCESS | 20055 | Values: 342.4 360.2 360.6 362.9 375.8 12973.9 17086.3 17994.3 19359.5 19459.2 16430.1 16656.7 16909.7 16930.6 16964.3 4944.5 5261.0 5262.2 5285.4 5290.3 3780.4 5189.1 5822.6 6140.8 6167.6 6028.5 6196.1 6220.4 6222.8 6226.0 6091.1 6192.0 6207.8 6215.1 6216.9 5401.8 5419.2 5420.8 6428.1 6822.2 23058.3 23977.2 24008.3 24341.8 24948.8 1986.4 2026.6 2043.3 2057.4 2505.7 13902.2 15140.0 15233.2 15329.2 15352.8 1894.8 1930.2 1930.9 1942.0 2019.3 81292.2 85766.6 87290.0 87640.0 87705.0 14189.9 14264.4 15702.8 15906.8 16123.2 28756.6 28801.1 29030.6 29093.0 29236.7 8358.1 8411.9 8418.0 8490.5 8543.4 6849.3 6851.3 6862.5 6918.7 6934.3 8230.5 8519.8 8619.2 8620.3 8621.5 3201.5 3368.3 3372.9 3390.5 3401.6 773.2 811.3 831.6 840.4 877.4 17072.1 17336.9 17676.1 17683.6 18550.4 810.5 839.2 866.7 907.2 939.2 8559.5 9957.6 10087.3 10102.3 10229.1 296909.0 303856.0 305298.0 349796.0 350739.0 82638.2 83133.6 84034.6 86415.1 88031.6 890.7 902.2 926.2 928.6 941.7 16448.3 16456.7 16513.8 16638.0 16787.1 25768.9 35377.1 35977.5 37330.3 37933.0 51810.5 52612.4 52882.2 53096.5 53287.0 7051.9 7314.1 7453.9 7547.8 7573.5 9004.7 11028.6 11078.7 11483.5 11561.7 9231.4 9256.3 9834.1 9886.4 9931.6 9765.0 9791.2 9878.8 9997.9 10053.0 7834.6 9834.8 10175.9 10303.9 10367.1 6929.8 7147.4 7256.5 7309.1 7613.0 9622.9 9653.8 9739.0 9866.2 9915.5 02:48:34 INFO - PROCESS | 20055 | 02:48:34 INFO - PROCESS | 20055 | [#3] mootools.html Cycles:180 Average:5199.41 Median:5208.92 stddev:2928.90 (56.2%) stddev-sans-first:2922.69 02:48:34 INFO - PROCESS | 20055 | Values: 1331.1 1403.6 1434.7 1634.2 1686.5 7516.0 7910.4 8026.6 8028.2 8031.8 6706.8 7383.6 7597.3 7606.2 7909.1 1969.5 2017.5 2019.9 2026.9 2029.7 2134.6 2162.4 2162.6 2168.3 2171.9 1835.8 2068.9 2143.6 2145.0 2170.3 2133.1 2137.4 2149.2 2149.9 2161.4 2167.7 2178.4 2186.7 2187.0 2188.2 8019.2 8090.2 8093.6 8097.8 8101.2 4498.6 4658.3 4692.5 4696.9 4712.2 2888.1 2935.0 3025.7 3029.8 3034.8 933.4 934.1 937.7 956.6 964.9 10697.8 10718.5 10727.5 10728.3 10777.6 4052.6 4233.8 4336.4 4343.5 4358.8 9093.8 9157.9 9180.4 9277.0 9282.2 4783.4 4790.1 4794.2 4801.3 4810.8 4254.6 4261.6 4329.7 4331.1 4331.5 3713.1 3723.7 3762.0 3821.0 3822.7 1069.8 1163.9 1179.9 1187.0 1193.5 710.6 741.4 799.7 825.3 835.3 5049.2 5112.1 5123.6 5308.8 5376.9 806.1 842.9 847.6 853.9 865.6 3663.0 3729.7 3746.7 3750.5 3812.4 9518.5 9896.3 9990.6 10001.7 10002.6 9587.1 9895.2 9900.3 9913.3 9920.1 7247.8 7296.8 7338.0 7373.7 7387.5 5060.8 5294.3 5356.6 5436.2 5443.6 9011.6 9091.3 9100.0 9195.7 9201.0 8619.4 8926.1 8937.5 9212.7 9266.0 6863.3 7033.2 7035.9 7040.0 7040.1 7437.4 7464.3 7519.4 7531.8 7612.2 6084.5 6124.7 6835.8 6857.6 7122.8 6979.9 7012.1 7057.1 7080.9 7095.3 6238.6 6369.9 6449.5 6454.9 6456.8 4582.2 4595.0 4627.5 4628.7 4770.0 4529.0 4640.1 4660.4 4678.9 4697.7 02:48:34 INFO - PROCESS | 20055 | 02:48:34 INFO - PROCESS | 20055 | [#4] prototype.html Cycles:180 Average:4197.16 Median:4830.73 stddev:1196.46 (24.8%) stddev-sans-first:1163.46 02:48:34 INFO - PROCESS | 20055 | Values: 297.2 297.7 299.1 307.1 307.2 4877.8 4924.8 4930.0 4932.1 4976.7 4834.8 4836.3 4848.7 4879.5 4880.4 3614.6 3712.5 3738.6 3843.4 3861.1 3781.5 3789.3 3822.4 3823.4 3855.7 3601.3 3758.9 3763.9 3794.6 3810.8 3642.6 3791.8 3795.9 3805.9 3819.5 3345.3 3353.2 3356.3 3365.3 3376.9 4685.2 4701.7 4705.8 4719.0 4735.7 4250.9 4343.2 4348.2 4381.6 4392.6 5059.0 5077.2 5081.8 5094.9 5098.8 3674.3 3682.3 3690.6 3694.6 3695.7 5178.4 5297.8 5308.9 5311.7 5322.6 3762.3 3762.9 3763.0 3772.0 3785.0 3823.8 3826.7 3844.5 3848.9 3852.5 4362.2 4362.7 4374.7 4376.8 4389.7 4890.9 5036.2 5041.7 5047.5 5051.3 1295.0 1319.8 1322.8 1323.2 1323.8 860.2 862.7 887.8 915.6 917.0 4409.3 4427.6 4433.2 4444.6 4461.8 5126.5 5132.1 5155.6 5159.8 5178.6 4224.8 4413.3 4427.1 4429.9 4439.6 2758.8 2763.0 2768.5 2768.6 2769.2 4888.4 4902.4 4912.0 4912.7 4918.2 5036.4 5192.5 5239.5 5321.8 5337.8 4826.7 4836.3 4849.2 4851.6 4862.0 5099.8 5133.7 5137.1 5155.7 5158.5 4921.0 5033.6 5043.1 5046.4 5083.5 4813.6 4822.4 4826.0 4839.9 4844.8 3727.3 3734.7 3735.9 3739.4 3741.3 4915.6 5119.6 5141.0 5155.6 5162.8 4906.5 4918.6 4929.9 4939.3 4944.2 4921.1 4938.8 4951.4 4954.4 4964.4 4688.2 4723.2 4867.9 4887.6 4903.3 4944.3 5067.9 5071.2 5074.3 5100.5 4949.9 4962.4 4989.7 4993.7 4995.0 02:48:34 INFO - PROCESS | 20055 | 02:48:34 INFO - PROCESS | 20055 | [#5] yui.html Cycles:180 Average:18297.38 Median:549.99 stddev:27238.56 (4952.6%) stddev-sans-first:27282.54 02:48:34 INFO - PROCESS | 20055 | Values: 596.5 648.5 667.0 690.6 726.0 12916.5 13770.4 13802.0 13852.4 14014.1 12961.7 13085.1 13137.7 13145.3 13309.9 514.8 525.3 751.2 754.5 774.6 768.2 768.3 776.9 777.5 778.1 834.8 835.0 835.2 836.9 837.3 547.1 571.4 597.2 603.8 819.6 581.9 599.5 600.3 601.1 605.1 12991.5 13042.3 13050.2 13058.1 13082.7 12169.3 12697.4 12707.4 12762.1 13018.9 11423.5 11496.8 11674.4 11753.1 12195.6 4054.2 4137.2 4197.0 4243.1 4247.4 58323.0 58503.7 58675.1 58689.0 58956.0 9708.0 9843.4 10058.2 10087.9 10096.4 18577.2 18599.0 18758.6 18812.0 18843.5 6478.8 6556.5 6568.3 6742.2 6742.4 3568.8 3631.1 3650.0 3652.3 3713.4 2791.4 2880.6 2887.6 2897.8 2898.9 545.8 567.2 573.4 581.1 583.5 552.9 559.3 572.3 582.4 587.4 7857.7 8157.2 8344.2 8399.0 8574.4 587.6 596.0 600.1 606.7 614.8 3655.2 3777.1 3805.4 3913.5 3938.8 97404.0 105687.0 106255.5 109459.0 109590.0 89830.0 95136.0 95347.0 95353.5 95519.5 83049.6 84075.0 84882.6 84958.6 85248.6 7127.9 7335.8 7402.4 7561.3 7604.8 38654.0 38846.2 39003.8 42060.9 43464.1 62075.4 62710.0 62903.7 63140.0 64833.0 10902.5 11036.1 11107.2 11219.7 11436.1 12341.4 12523.7 12542.6 12594.0 12829.5 10744.4 11021.4 11241.9 11279.9 11843.2 11289.6 11296.2 11327.7 11512.9 11874.0 11263.7 11278.9 11463.8 12140.8 12151.5 8740.8 8898.1 9112.3 9166.2 9214.4 9112.0 9536.5 9567.2 9601.9 9651.7 02:48:34 INFO - PROCESS | 20055 | -------- Summary: end -------- 02:48:34 INFO - PROCESS | 20055 | 02:48:35 INFO - TEST-INFO | 20055: exit 0 02:48:35 INFO - TEST-OK | dromaeo_css | took 1119112ms 02:48:35 INFO - TEST-START | kraken 02:48:35 INFO - operating with platform_type : linux_ 02:48:35 INFO - Initialising browser for kraken test... 02:48:35 INFO - TEST-INFO | started process 20242 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdUtlfS/profile http://localhost:60439/getInfo.html) 02:48:38 INFO - PROCESS | 20242 | JavaScript error: , line 0: uncaught exception: undefined 02:48:39 INFO - PROCESS | 20242 | __metrics Screen width/height:1600/1200 02:48:39 INFO - PROCESS | 20242 | colorDepth:24 02:48:39 INFO - PROCESS | 20242 | Browser inner width/height: 1024/697 02:48:39 INFO - PROCESS | 20242 | __metrics 02:48:39 INFO - PROCESS | 20242 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:48:39 INFO - PROCESS | 20242 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:48:40 INFO - TEST-INFO | 20242: exit 0 02:48:40 INFO - Browser initialized. 02:48:40 INFO - Running cycle 1/1 for kraken test... 02:48:40 INFO - TEST-INFO | started process 20328 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdUtlfS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:52 INFO - PROCESS | 20328 | RSS: Main: 159633408 02:48:52 INFO - PROCESS | 20328 | 02:48:55 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60439/tests/kraken/driver.html?testName=audio-beat-detection) 02:48:56 INFO - PROCESS | 20328 | RSS: Main: 218890240 02:48:56 INFO - PROCESS | 20328 | 02:48:59 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60439/tests/kraken/driver.html?testName=audio-dft) 02:48:59 INFO - PROCESS | 20328 | RSS: Main: 205475840 02:48:59 INFO - PROCESS | 20328 | 02:49:02 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60439/tests/kraken/driver.html?testName=audio-fft) 02:49:03 INFO - PROCESS | 20328 | RSS: Main: 272859136 02:49:03 INFO - PROCESS | 20328 | 02:49:05 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60439/tests/kraken/driver.html?testName=audio-oscillator) 02:49:06 INFO - PROCESS | 20328 | RSS: Main: 182591488 02:49:06 INFO - PROCESS | 20328 | 02:49:08 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60439/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:49:09 INFO - PROCESS | 20328 | RSS: Main: 185839616 02:49:09 INFO - PROCESS | 20328 | 02:49:12 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60439/tests/kraken/driver.html?testName=imaging-darkroom) 02:49:13 INFO - PROCESS | 20328 | RSS: Main: 222580736 02:49:13 INFO - PROCESS | 20328 | 02:49:16 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60439/tests/kraken/driver.html?testName=imaging-desaturate) 02:49:17 INFO - PROCESS | 20328 | RSS: Main: 218161152 02:49:17 INFO - PROCESS | 20328 | 02:49:20 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60439/tests/kraken/driver.html?testName=json-parse-financial) 02:49:20 INFO - PROCESS | 20328 | RSS: Main: 221126656 02:49:20 INFO - PROCESS | 20328 | 02:49:22 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60439/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:49:22 INFO - PROCESS | 20328 | RSS: Main: 211050496 02:49:23 INFO - PROCESS | 20328 | 02:49:24 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60439/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:49:25 INFO - PROCESS | 20328 | RSS: Main: 214331392 02:49:25 INFO - PROCESS | 20328 | 02:49:27 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60439/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:49:28 INFO - PROCESS | 20328 | RSS: Main: 211361792 02:49:28 INFO - PROCESS | 20328 | 02:49:31 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60439/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:49:31 INFO - PROCESS | 20328 | RSS: Main: 223469568 02:49:31 INFO - PROCESS | 20328 | 02:49:34 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60439/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:49:35 INFO - PROCESS | 20328 | RSS: Main: 224813056 02:49:35 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | Cycle 1(1): loaded http://localhost:60439/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60439/tests/kraken/driver.html?testName=ai-astar) 02:49:37 INFO - PROCESS | 20328 | RSS: Main: 267878400 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | __start_tp_report 02:49:37 INFO - PROCESS | 20328 | _x_x_mozilla_page_load 02:49:37 INFO - PROCESS | 20328 | _x_x_mozilla_page_load_details 02:49:37 INFO - PROCESS | 20328 | |i|pagename|runs| 02:49:37 INFO - PROCESS | 20328 | |0;ai-astar;105;116;114;115;115;117;114;114;114;114 02:49:37 INFO - PROCESS | 20328 | |1;audio-beat-detection;147;122;126;185;125;125;153;124;169;122 02:49:37 INFO - PROCESS | 20328 | |2;audio-dft;164;165;162;163;163;164;163;166;162;161 02:49:37 INFO - PROCESS | 20328 | |3;audio-fft;76;77;78;127;76;77;77;75;99;74 02:49:37 INFO - PROCESS | 20328 | |4;audio-oscillator;86;85;125;85;85;88;123;85;85;128 02:49:37 INFO - PROCESS | 20328 | |5;imaging-gaussian-blur;117;117;117;117;118;119;118;120;117;118 02:49:37 INFO - PROCESS | 20328 | |6;imaging-darkroom;147;147;145;147;146;148;146;147;147;146 02:49:37 INFO - PROCESS | 20328 | |7;imaging-desaturate;95;94;95;95;95;95;94;95;95;94 02:49:37 INFO - PROCESS | 20328 | |8;json-parse-financial;64;62;63;64;63;63;62;63;62;62 02:49:37 INFO - PROCESS | 20328 | |9;json-stringify-tinderbox;67;68;66;65;68;66;67;67;66;66 02:49:37 INFO - PROCESS | 20328 | |10;stanford-crypto-aes;79;79;85;82;86;81;79;82;79;83 02:49:37 INFO - PROCESS | 20328 | |11;stanford-crypto-ccm;130;137;129;137;140;131;132;128;188;131 02:49:37 INFO - PROCESS | 20328 | |12;stanford-crypto-pbkdf2;163;166;170;163;164;164;172;168;169;167 02:49:37 INFO - PROCESS | 20328 | |13;stanford-crypto-sha256-iterative;66;67;67;67;65;65;64;67;65;67 02:49:37 INFO - PROCESS | 20328 | __end_tp_report 02:49:37 INFO - PROCESS | 20328 | __start_cc_report 02:49:37 INFO - PROCESS | 20328 | _x_x_mozilla_cycle_collect,1900 02:49:37 INFO - PROCESS | 20328 | __end_cc_report 02:49:37 INFO - PROCESS | 20328 | __startTimestamp1468835377288__endTimestamp 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | ------- Summary: start ------- 02:49:37 INFO - PROCESS | 20328 | Number of tests: 14 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.50 stddev:3.26 (2.8%) stddev-sans-first:1.09 02:49:37 INFO - PROCESS | 20328 | Values: 105.0 116.0 114.0 115.0 115.0 117.0 114.0 114.0 114.0 114.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:136.50 stddev:22.69 (16.6%) stddev-sans-first:23.92 02:49:37 INFO - PROCESS | 20328 | Values: 147.0 122.0 126.0 185.0 125.0 125.0 153.0 124.0 169.0 122.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.50 stddev:1.49 (0.9%) stddev-sans-first:1.56 02:49:37 INFO - PROCESS | 20328 | Values: 164.0 165.0 162.0 163.0 163.0 164.0 163.0 166.0 162.0 161.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#3] audio-fft Cycles:10 Average:83.60 Median:77.00 stddev:16.88 (21.9%) stddev-sans-first:17.68 02:49:37 INFO - PROCESS | 20328 | Values: 76.0 77.0 78.0 127.0 76.0 77.0 77.0 75.0 99.0 74.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:85.00 stddev:19.27 (22.7%) stddev-sans-first:19.98 02:49:37 INFO - PROCESS | 20328 | Values: 86.0 85.0 125.0 85.0 85.0 88.0 123.0 85.0 85.0 128.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:118.00 stddev:1.03 (0.9%) stddev-sans-first:1.05 02:49:37 INFO - PROCESS | 20328 | Values: 117.0 117.0 117.0 117.0 118.0 119.0 118.0 120.0 117.0 118.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.84 (0.6%) stddev-sans-first:0.88 02:49:37 INFO - PROCESS | 20328 | Values: 147.0 147.0 145.0 147.0 146.0 148.0 146.0 147.0 147.0 146.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 02:49:37 INFO - PROCESS | 20328 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:63.00 stddev:0.79 (1.3%) stddev-sans-first:0.71 02:49:37 INFO - PROCESS | 20328 | Values: 64.0 62.0 63.0 64.0 63.0 63.0 62.0 63.0 62.0 62.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:67.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 02:49:37 INFO - PROCESS | 20328 | Values: 67.0 68.0 66.0 65.0 68.0 66.0 67.0 67.0 66.0 66.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:82.00 stddev:2.59 (3.2%) stddev-sans-first:2.59 02:49:37 INFO - PROCESS | 20328 | Values: 79.0 79.0 85.0 82.0 86.0 81.0 79.0 82.0 79.0 83.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#11] stanford-crypto-ccm Cycles:10 Average:138.30 Median:134.50 stddev:17.90 (13.3%) stddev-sans-first:18.73 02:49:37 INFO - PROCESS | 20328 | Values: 130.0 137.0 129.0 137.0 140.0 131.0 132.0 128.0 188.0 131.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:167.50 stddev:3.13 (1.9%) stddev-sans-first:3.04 02:49:37 INFO - PROCESS | 20328 | Values: 163.0 166.0 170.0 163.0 164.0 164.0 172.0 168.0 169.0 167.0 02:49:37 INFO - PROCESS | 20328 | 02:49:37 INFO - PROCESS | 20328 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:67.00 stddev:1.15 (1.7%) stddev-sans-first:1.22 02:49:37 INFO - PROCESS | 20328 | Values: 66.0 67.0 67.0 67.0 65.0 65.0 64.0 67.0 65.0 67.0 02:49:37 INFO - PROCESS | 20328 | -------- Summary: end -------- 02:49:37 INFO - PROCESS | 20328 | 02:49:38 INFO - TEST-INFO | 20328: exit 0 02:49:38 INFO - TEST-OK | kraken | took 63031ms 02:49:38 INFO - SUITE-END | took 1182s 02:49:38 INFO - Completed test suite (00:19:42) 02:49:38 INFO - javascript benchmark 02:49:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2641.141628970585, 2655.229983304842, 2664.7734942529883, 2694.9730502694965, 2702.418979919317, 4513.413256276679, 4586.151561961031, 4713.552212539807, 4829.589484893784, 4843.975780121099, 4630.536946305368, 4671.602913752336, 4676.298555216719, 4697.694649847757, 4700.435947686274, 4268.125034367963, 4282.100758840632, 4352.368906508555, 4360.956390436095, 4374.868753937371, 103.77878608302893, 4390.187815254172, 4396.230659634552, 4399.5820397062225, 4428.247197976351, 4301.032267739764, 4336.934945975829, 4342.088161486092, 4364.432623758923, 4368.4102646143, 4311.28863737483, 4326.480822301312, 4333.090051089275, 4359.891002724926, 4380.12397520494, 4227.78861056949, 4309.612134907842, 4313.913721725579, 4330.956690433087, 4353.064091220403, 4598.816047358102, 4668.159731248374, 4716.363290955712, 4737.73942433166, 4771.188897887368, 5052.8484145475695, 5078.958813443235, 5122.410922743895, 5123.2315152727015, 5131.102057140023, 4897.118518666639, 5125.641205115643, 5146.665466744687, 5155.072087024334, 5165.276861239429, 4576.542345765397, 4603.792829322689, 4611.953880461219, 4612.262038073959, 4636.907261854812, 5093.083245015859, 5105.744712764347, 5107.438181799999, 5114.897702045938, 5118.078745825787, 4859.319695242669, 4905.681130726492, 4906.11689895815, 4908.8772780680765, 4914.262860570943, 4810.39870016248, 4927.408710954708, 4932.728699921467, 4981.526754958346, 4987.975060124749, 4936.259561065869, 4939.90120197594, 4946.777395017233, 4951.405831300267, 4982.825601103944, 4925.310456536088, 4928.6303527235605, 4940.851774446773, 4949.703017818871, 4985.675931064469, 3897.512810898638, 3981.3231750602467, 3984.4620976168426, 3992.281389349887, 4037.5356833963883, 3464.72282217422, 3527.470879368064, 3535.169235229719, 3555.520004799319, 3562.4834399011997, 4221.493420789525, 4280.443542339476, 4283.850065247763, 4315.568443155659, 4329.134173165379, 4946.777395017233, 4955.554000139932, 4964.900701986012, 4974.701517908937, 5016.974915125401, 4252.383404406344, 4263.616274535245, 4266.722663026893, 4272.145570885836, 4316.115196384733, 4544.522825103382, 4588.839390621312, 4592.058627981256, 4592.081583683277, 4602.332661764025, 4774.212254977959, 4777.116233496814, 4780.808767649394, 4783.569478746929, 4832.613390928717, 4879.39007624047, 5018.623603229846, 5075.137226671401, 5076.898462030813, 5110.1568241239775, 4766.95233047679, 4771.260454629538, 4786.473487916257, 4787.281907713732, 4829.73436460998, 4993.650444468853, 4994.1509943309, 5003.524665156804, 5006.849794506171, 5047.520485553867, 4733.053389322218, 4952.479989601185, 5021.096202683552, 5059.114654935445, 5120.923186152285, 4869.6347773917805, 4905.77923993414, 4912.459629440687, 4939.086269040239, 4951.554360107608, 4895.657303988686, 4896.1921282987905, 4900.95099049005, 4907.582855457326, 4943.8516844494725, 4661.091087238092, 4746.7389293589185, 4783.928241076317, 4805.951940480551, 4813.4945830687275, 4873.0, 4875.268709693574, 4885.193942999365, 4893.290472881484, 4933.235348520985, 4742.5731684148595, 4746.050789841976, 4755.405574303212, 4789.736564488987, 4835.758212089311], "value": 4561.391411377222, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.2272109930494, 573.0, 579.472679861326, 586.1178925716796, 610.6793933185074, 26560.203193904188, 38297.0, 39646.0, 39750.00624984377, 44292.55707442925, 39915.0, 39980.80009599948, 40193.39709904347, 40322.0, 40400.0, 7516.436267279949, 7702.6918923243, 7750.8449831003345, 7752.689892404297, 7755.340796032316, 7635.427342949273, 7709.229077092274, 7709.730159444421, 7713.190115037924, 7727.9227207728045, 7679.769606911802, 7734.342580880632, 7737.883931741028, 7748.845023099535, 7762.30139287464, 7666.00341955545, 7688.6155692215725, 7690.115636701739, 7692.769216923473, 7708.03649543807, 7712.845743085163, 7742.961285193538, 7763.146053934062, 7768.067831860157, 7778.416618753559, 38835.0, 39203.41194882079, 39244.80377598094, 39269.80365098185, 39332.0, 2855.129185598392, 2960.899669414129, 3016.909492715222, 3236.6925142111463, 3542.716582673406, 26768.063117790978, 26783.598246026326, 27017.594736078976, 27327.180184594494, 28171.014014509597, 3346.380919529895, 3356.6978971892404, 3366.309906469168, 3371.089805752458, 3417.043227896193, 261127.0, 261902.69048654634, 262104.78952104828, 262218.0, 263593.0, 42276.57723422788, 42630.147397051885, 43739.78130109329, 52810.0, 53151.0, 51225.0, 56802.14796778052, 56866.14700779492, 56916.43083569194, 56940.0, 12930.094893357564, 13160.341982900816, 13181.340932953506, 13192.868071319355, 13207.40566674502, 9001.694754260727, 9800.754981125529, 9875.506224688737, 10228.795424091477, 10525.052745252964, 16081.356745730274, 16164.676706465805, 16167.434139805051, 16172.191390430431, 16219.756703649455, 5568.52667523257, 5576.972115139399, 5606.355269144101, 5612.97193514038, 5641.52047075995, 776.8290975985274, 833.3749687734199, 883.8143989762093, 904.742148487691, 906.9138431848963, 27906.720932790413, 28629.713702863122, 28638.14085577436, 28791.136265912057, 28854.305558319684, 989.9950500247453, 999.3853779925439, 1015.5633077776479, 1068.3750006246432, 1087.5812812067252, 14758.335874885655, 15243.847561524242, 15307.617309567127, 15424.228788560527, 15518.767218491732, 403674.0, 406811.0, 408674.0, 409035.0, 410674.0, 49699.50300496949, 49901.25148122781, 49996.250056249184, 50014.74992625014, 50091.49908500941, 1083.1930211992117, 1090.2150451674781, 1154.7632735289246, 1179.7050737315672, 1191.7497325561383, 28956.0, 29497.410051799274, 29631.0, 29972.550411743407, 30447.543286850272, 41642.791786042086, 41727.37408938929, 42018.57981420147, 42060.57939420567, 42084.15831683288, 299068.0, 299168.0, 301068.0, 302489.0, 302553.0, 24713.25860224196, 25385.111521096675, 25722.742772572034, 26921.730782691924, 26942.171072037152, 40063.0, 40640.390394144706, 40775.71854955187, 41013.0, 41526.16947660969, 37153.25693486169, 37268.813655931546, 37431.625683742815, 38032.61967380291, 38047.239055219296, 37108.25783484261, 37211.25577488489, 37232.25535489221, 37503.0, 37992.62007380001, 31511.218653761694, 34220.31559368849, 34234.0, 36151.81924090363, 36620.45069323909, 29687.703122968494, 33886.830565848, 34024.14939626529, 34243.65756342504, 34317.48523772195, 25154.874225628755, 25750.484990300465, 25896.179824605224, 25977.870110649324, 25987.870060649577], "value": 17236.21353379063, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.44359386587956, 360.16082527710427, 360.62855259083153, 362.8856910073327, 375.8177284017251, 12973.870261297396, 17086.31654733811, 17994.28022879085, 19359.516012099706, 19459.178419129217, 16430.096344701036, 16656.66686666266, 16909.7463538047, 16930.576735581617, 16964.32142714293, 4944.456109827916, 5260.973695131538, 5262.184361423985, 5285.365756109272, 5290.285811415448, 3780.395136778116, 5189.091908915943, 5822.563307751935, 6140.84647883802, 6167.62994220346, 6028.5478589106, 6196.101565273033, 6220.440160385564, 6222.844428889269, 6225.968870155642, 6091.055886337603, 6192.0, 6207.751689932397, 6215.098810672473, 6216.875662486747, 5401.810936617238, 5419.214213938996, 5420.83737487876, 6428.132202152697, 6822.181338239393, 23058.308250752503, 23977.160799371937, 24008.27975160748, 24341.756582434125, 24948.750512494826, 1986.3941497843152, 2026.6453370660076, 2043.2746375036945, 2057.423921302023, 2505.736897625741, 13902.16587004783, 15140.015898966532, 15233.238338083162, 15329.156896370696, 15352.846471535253, 1894.8473622694553, 1930.2182616040527, 1930.9420717378362, 1941.980580194194, 2019.3235266185845, 81292.21262916904, 85766.57116714439, 87290.0, 87640.0, 87705.0, 14189.85810141906, 14264.42942282318, 15702.842971570366, 15906.840931590767, 16123.178443772293, 28756.568651470243, 28801.135965921058, 29030.564541531894, 29093.0, 29236.707632923823, 8358.122397148334, 8411.915880841114, 8418.0, 8490.533020683923, 8543.35924805642, 6849.315068493211, 6851.3491218334175, 6862.4853136015, 6918.654067296716, 6934.271901450275, 8230.514369668424, 8519.787005324917, 8619.224269815746, 8620.310375169971, 8621.525816080175, 3201.5197720342135, 3368.292658541685, 3372.915677108092, 3390.5083762854247, 3401.5577974863604, 773.1765669561898, 811.3205664274894, 831.6340810043562, 840.4201101240125, 877.4121338703085, 17072.061036642855, 17336.913315433343, 17676.116194190497, 17683.557911052325, 18550.350737724384, 810.5217921426387, 839.1901814748796, 866.696656170348, 907.1880666803174, 939.1829108675496, 8559.529225892637, 9957.55191016406, 10087.344322619007, 10102.292839501311, 10229.130523905402, 296909.0, 303856.0, 305298.0, 349796.0, 350739.0, 82638.17361826425, 83133.58433207916, 84034.57982710048, 86415.13584864196, 88031.55984220166, 890.6615486115364, 902.2195800632405, 926.2173463423396, 928.6378312458147, 941.7268991992382, 16448.26610962507, 16456.67086658284, 16513.752293715843, 16638.001719896845, 16787.076710781024, 25768.87115564485, 35377.1155721109, 35977.46033809547, 37330.25339493249, 37933.0, 51810.481895180565, 52612.42162735124, 52882.2067668993, 53096.4690353107, 53287.0, 7051.858962820612, 7314.122305323182, 7453.888191677238, 7547.849043018999, 7573.507721998052, 9004.684836030969, 11028.558857645605, 11078.723031924266, 11483.48324325424, 11561.727107482131, 9231.35380523357, 9256.25949924018, 9834.065763752718, 9886.357386769838, 9931.602735890772, 9765.0, 9791.167750740982, 9878.753031174278, 9997.850032249667, 10053.0, 7834.608269586612, 9834.754131146492, 10175.949120254352, 10303.896961030594, 10367.118794902517, 6929.792106236821, 7147.392471639969, 7256.492045556972, 7309.122905251404, 7613.0483689538805, 9622.855657165288, 9653.758656033655, 9738.951305243429, 9866.161376283097, 9915.454649994319], "value": 9080.45273483099, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.1480652382475, 1403.5789263221031, 1434.655682636167, 1634.2237437217316, 1686.4603326935378, 7516.0, 7910.406719496032, 8026.598670066488, 8028.23731745484, 8031.759047228578, 6706.832329191772, 7383.556986580809, 7597.278258565441, 7606.239376062391, 7909.090454597723, 1969.5371587676848, 2017.5460521382718, 2019.899005049749, 2026.9189232430685, 2029.6752519596866, 2134.647783115784, 2162.351294611618, 2162.610730068587, 2168.3386567097027, 2171.869687818736, 1835.8072402397756, 2068.88621125838, 2143.6141494531057, 2144.967825482619, 2170.2946542373793, 2133.0827744070043, 2137.3587923622927, 2149.215536329244, 2149.8602590831547, 2161.3840055584083, 2167.674848772681, 2178.357384571555, 2186.726659167604, 2186.9781302186934, 2188.245055455865, 8019.198080191993, 8090.150534193943, 8093.595320233965, 8097.797555061112, 8101.230383113595, 4498.595126438636, 4658.301254811805, 4692.507286734878, 4696.906061878777, 4712.222483290253, 2888.1046875468674, 2934.970650293491, 3025.7125573070525, 3029.7727670424806, 3034.767972109696, 933.3886304470583, 934.0799312677008, 937.6952490440634, 956.5743244256308, 964.9228061755043, 10697.83953240702, 10718.464076796128, 10727.49815025897, 10728.302660327054, 10777.5688972441, 4052.615001574846, 4233.809478573472, 4336.436263285816, 4343.500497442771, 4358.76026818528, 9093.818123637622, 9157.908420915706, 9180.44917304964, 9277.0, 9282.164605185566, 4783.402074740658, 4790.089882922233, 4794.208955522369, 4801.327814106028, 4810.831620893252, 4254.553271906467, 4261.637760790368, 4329.718568293051, 4331.133773245364, 4331.545187755303, 3713.0717320669833, 3723.739338246351, 3761.962380376216, 3821.0, 3822.694184465236, 1069.753956589973, 1163.9010684091777, 1179.9203728588418, 1186.9821952670545, 1193.4689063366718, 710.5630037526987, 741.4476215219588, 799.7240951871595, 825.3232349473374, 835.302522393808, 5049.192129259374, 5112.054269960069, 5123.590112790967, 5308.840734777963, 5376.91934620981, 806.1334065879204, 842.9072801991776, 847.6270441005938, 853.9018012928593, 865.6494119881459, 3662.9633703663153, 3729.738918275749, 3746.737728359043, 3750.5311836020496, 3812.390017597171, 9518.524073796283, 9896.25778066648, 9990.550425230882, 10001.749956251006, 10002.649907253211, 9587.137157655845, 9895.158911492459, 9900.257480688977, 9913.30606857513, 9920.107190352903, 7247.804112321408, 7296.817579560554, 7338.009368735153, 7373.741919032808, 7387.519811212362, 5060.822871199491, 5294.258803767399, 5356.5982551308025, 5436.157395603688, 5443.618946673695, 9011.594478248364, 9091.272698184262, 9099.954500227457, 9195.678151264674, 9200.95399522998, 8619.353548483763, 8926.107389261022, 8937.46375217489, 9212.723618291462, 9266.02706715812, 6863.313668633096, 7033.226345102137, 7035.929640703732, 7040.0, 7040.084788977605, 7437.405007599271, 7464.290892365217, 7519.360854327443, 7531.8493630125995, 7612.238776122344, 6084.452399284086, 6124.663143526971, 6835.829104272234, 6857.622830744157, 7122.7507037253445, 6979.930200698132, 7012.088428504466, 7057.082579264663, 7080.858382832212, 7095.254998225112, 6238.563300568917, 6369.87260254783, 6449.451796597342, 6454.935450645434, 6456.838579035561, 4582.152301824173, 4594.954050459586, 4627.514111018295, 4628.717649213302, 4770.0221454602415, 4529.0, 4640.095181439587, 4660.440747110369, 4678.92981605283, 4697.741624210701], "value": 4164.422111306929, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.1708932079498, 297.7350158359059, 299.10269192422726, 307.12316336858254, 307.22272650195015, 4877.829275975341, 4924.827631032915, 4930.038642464717, 4932.087563800699, 4976.676516026456, 4834.758262086899, 4836.27455881618, 4848.709077455355, 4879.512048795119, 4880.38899188934, 3614.5843228028784, 3712.477884067533, 3738.5700644425833, 3843.404272337792, 3861.1119442528234, 3781.508403907488, 3789.3368660484443, 3822.3502004659276, 3823.4456003879422, 3855.7108216883844, 3601.3253836965937, 3758.887233382989, 3763.9059023524355, 3794.6149655375725, 3810.8285127169343, 3642.6175251598597, 3791.848326066954, 3795.9240815183543, 3805.8667946621736, 3819.4652748615217, 3345.280764635615, 3353.2060347713855, 3356.295178012596, 3365.3269346130874, 3376.9155771105675, 4685.1566717990745, 4701.670883038189, 4705.788239529229, 4718.976405117986, 4735.668503204777, 4250.851220207278, 4343.174796788631, 4348.152110338454, 4381.583749543788, 4392.648588112976, 5059.0, 5077.187649976023, 5081.79672813087, 5094.872628184288, 5098.770555325019, 3674.2835147146316, 3682.3371793077235, 3690.5755838078694, 3694.612065733086, 3695.6858667013066, 5178.430372659004, 5297.761600727977, 5308.867278318035, 5311.7344132793205, 5322.627416080876, 3762.285165818486, 3762.8682996095004, 3763.021614380281, 3772.0, 3784.962150378461, 3823.751456155341, 3826.6556009959236, 3844.519435070616, 3848.942265866014, 3852.4799152114665, 4362.192994296065, 4362.687775479001, 4374.671899607542, 4376.824927002885, 4389.6927215095275, 4890.853274401774, 5036.169032109661, 5041.747912604355, 5047.470015648378, 5051.26756620288, 1294.996377807188, 1319.808627748971, 1322.8412590489204, 1323.1928523600595, 1323.7749582570987, 860.2172023458622, 862.66787286895, 887.8268737596106, 915.6154415145655, 916.9908300916906, 4409.272470042407, 4427.579379958899, 4433.179861725591, 4444.555544445594, 4461.799219035086, 5126.4617215191865, 5132.101882170605, 5155.61332900034, 5159.819406320836, 5178.559822415062, 4224.809883555247, 4413.34499562664, 4427.092446048552, 4429.936815164402, 4439.645907998027, 2758.820676656011, 2762.958555621667, 2768.4739899419046, 2768.6123942648046, 2769.1692492252246, 4888.413390393175, 4902.362692849978, 4911.950880491149, 4912.680675756064, 4918.1884988977135, 5036.438704001771, 5192.480751924777, 5239.502247286502, 5321.787128514893, 5337.8131765388025, 4826.710397376169, 4836.347093142381, 4849.248366503198, 4851.611871050308, 4862.0, 5099.847004589868, 5133.717645529532, 5137.075326441276, 5155.674589889575, 5158.535731784157, 4920.975395123001, 5033.5721463676, 5043.142665746906, 5046.369203849519, 5083.46623604531, 4813.614910807197, 4822.397200349957, 4826.0, 4839.854804355875, 4844.806207751792, 3727.347714149959, 3734.6638802507905, 3735.8879233623034, 3739.420389839579, 3741.2517496500263, 4915.557599816034, 5119.564836988898, 5141.0, 5155.613329000265, 5162.767675454538, 4906.509349065065, 4918.5573298403315, 4929.852104436729, 4939.333190019302, 4944.208926571731, 4921.13880070979, 4938.777755001105, 4951.3510272004305, 4954.430240522386, 4964.379452568429, 4688.249880019181, 4723.197056500472, 4867.853964381075, 4887.560119589254, 4903.264510323337, 4944.283078953604, 5067.898642027212, 5071.239314102914, 5074.264231686311, 5100.48995100487, 4949.876253093701, 4962.404511458648, 4989.650724449254, 4993.650444468853, 4994.9500504994485], "value": 3819.1727327513227, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.5317225977611, 648.4941745438559, 667.0061608203774, 690.6063543780045, 725.9582349953694, 12916.547920822773, 13770.449182032708, 13802.0, 13852.445902163927, 14014.089084209532, 12961.740765184713, 13085.14946528475, 13137.671558211026, 13145.342732863366, 13309.93345033276, 514.7734996601503, 525.254139122447, 751.2074761127009, 754.486948874765, 774.6126936531734, 768.2394429514768, 768.323874990008, 776.8873513340562, 777.4790890103616, 778.0779775965499, 834.8121672623672, 835.0355339582761, 835.1940377535707, 836.9246767790897, 837.3301358912876, 547.0699810322448, 571.4085921071671, 597.2176448852018, 603.815836169968, 819.6229734322219, 581.8865321262314, 599.5383554662928, 600.2736688606791, 601.1043545117786, 605.0561124645567, 12991.480340786356, 13042.347882605833, 13050.23821784066, 13058.085933984625, 13082.738345233138, 12169.2698438094, 12697.365131743469, 12707.364631768467, 12762.106652534327, 13018.86981130186, 11423.543058277659, 11496.770064598744, 11674.35791031493, 11753.059755219561, 12195.634130976085, 4054.189162167578, 4137.151883863831, 4197.034682023152, 4243.130158317537, 4247.426597409341, 58323.0, 58503.70748146232, 58675.11987320193, 58689.0, 58956.0, 9708.001895120095, 9843.409395436296, 10058.245631577662, 10087.899121008842, 10096.394216347042, 18577.16402761879, 18599.0, 18758.624827503372, 18812.0, 18843.528911777314, 6478.838029049311, 6556.541042127101, 6568.270844991598, 6742.190937087581, 6742.359475849787, 3568.7680300780366, 3631.0559254593995, 3649.9817500912327, 3652.2695460907394, 3713.350163721338, 2791.371941313229, 2880.625518682558, 2887.5524293734507, 2897.840618765988, 2898.942021159565, 545.841705905291, 567.2257368691706, 573.4036601934025, 581.1428143488321, 583.5127668396851, 552.9060059789846, 559.286909190785, 572.3303734630492, 582.3710392775791, 587.3832475900288, 7857.724979625686, 8157.1842815717955, 8344.165583441607, 8399.034111077304, 8574.356923230658, 587.6121759638618, 595.9970200148972, 600.1358044416114, 606.7481994972126, 614.8247749391402, 3655.214128962286, 3777.131259810204, 3805.429185622179, 3913.4521167036637, 3938.8424463021734, 97404.0, 105687.0, 106255.46872265589, 109459.0, 109590.0, 89830.0, 95136.0, 95347.0, 95353.52323238339, 95519.52240238893, 83049.58475207585, 84075.0, 84882.5755871229, 84958.57520712356, 85248.57375713081, 7127.8574428512175, 7335.77992660221, 7402.370798481974, 7561.3194812467145, 7604.8479030420185, 38654.0, 38846.223075538896, 39003.82988510349, 42060.94847628834, 43464.130717386106, 62075.37924620696, 62710.0, 62903.6854815723, 63140.0, 64833.0, 10902.509387077442, 11036.11711063097, 11107.166962478008, 11219.66341009771, 11436.085113191091, 12341.382930853242, 12523.74952500963, 12542.623721288375, 12594.0, 12829.486820527074, 10744.355338679705, 11021.44892755375, 11241.875812418693, 11279.943600281946, 11843.170978031456, 11289.604863829727, 11296.226302492538, 11327.716807079889, 11512.94243528777, 11874.0, 11263.718407039561, 11278.943605281922, 11463.828042579202, 12140.817887731868, 12151.453184606891, 8740.825183496421, 8898.110188981309, 9112.316576256675, 9166.17504424605, 9214.401063930824, 9112.037225159731, 9536.523173841142, 9567.234621230424, 9601.855972160283, 9651.71044868655], "value": 6524.039409907263, "unit": "score"}], "name": "dromaeo_css", "value": 6480.716242894036, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 114.0, 115.0, 115.0, 117.0, 114.0, 114.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 126.0, 185.0, 125.0, 125.0, 153.0, 124.0, 169.0, 122.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 162.0, 163.0, 163.0, 164.0, 163.0, 166.0, 162.0, 161.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 127.0, 76.0, 77.0, 77.0, 75.0, 99.0, 74.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 125.0, 85.0, 85.0, 88.0, 123.0, 85.0, 85.0, 128.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 119.0, 118.0, 120.0, 117.0, 118.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 145.0, 147.0, 146.0, 148.0, 146.0, 147.0, 147.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 64.0, 63.0, 63.0, 62.0, 63.0, 62.0, 62.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 65.0, 68.0, 66.0, 67.0, 67.0, 66.0, 66.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 85.0, 82.0, 86.0, 81.0, 79.0, 82.0, 79.0, 83.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 137.0, 129.0, 137.0, 140.0, 131.0, 132.0, 128.0, 188.0, 131.0], "value": 138.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 170.0, 163.0, 164.0, 164.0, 172.0, 168.0, 169.0, 167.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 65.0, 65.0, 64.0, 67.0, 65.0, 67.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1538.8999999999999, "alertThreshold": 2.0}]} 02:49:38 INFO - Return code: 0 02:49:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:49:38 INFO - # TBPL SUCCESS # 02:49:38 INFO - Running post-action listener: _resource_record_post_action 02:49:38 INFO - ##### 02:49:38 INFO - ##### Finished run-tests step (success) 02:49:38 INFO - ##### 02:49:38 INFO - Running post-run listener: _resource_record_post_run 02:49:38 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 12288; Write bytes: 536174592; Read time: 132; Write time: 454648 02:49:38 INFO - TinderboxPrint: CPU usage
13.0% 02:49:38 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 132 02:49:38 INFO - TinderboxPrint: I/O write bytes / time
536,174,592 / 454,648 02:49:38 INFO - TinderboxPrint: CPU idle
8,323.7 (87.0%) 02:49:38 INFO - TinderboxPrint: CPU user
1,202.2 (12.6%) 02:49:38 INFO - TinderboxPrint: Swap in / out
0 / 0 02:49:38 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246337536; Read time: 0; Write time: 232044 02:49:38 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 12288; Write bytes: 289837056; Read time: 132; Write time: 222604 02:49:39 INFO - Running post-run listener: _upload_blobber_files 02:49:39 INFO - Blob upload gear active. 02:49:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:49:39 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 02:49:39 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'] 02:49:39 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 02:49:39 INFO - (blobuploader) - INFO - Open directory for files ... 02:49:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:49:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:49:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:46 INFO - (blobuploader) - INFO - Done attempting. 02:49:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:49:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:49:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:47 INFO - (blobuploader) - INFO - Done attempting. 02:49:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:49:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:49:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:48 INFO - (blobuploader) - INFO - Done attempting. 02:49:48 INFO - (blobuploader) - INFO - Iteration through files over. 02:49:48 INFO - Return code: 0 02:49:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:49:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:49:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7a87a9efd2f96afd675ce500a2244b26565c3be470de4890bb1f9cb59aac6047b144a2f26bb3cd6b40f97aa8961b88530fbdb3189f373cab87c5bd558e37af3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c4ff49d8e2f0d5544655d6ac172c5a609b0365cf4ea18e800e503a84a222a5b713862b923a5cf949313076a20ce5d452fc09df7fbaf666e305102209d89789a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a7642a5a20ed72bd556ad4f5119af972b48c220095c8ea508d6d103231f31029cae182b5803c15fc3e1bd96935485b24b5d90bda398c37fda17206853722fcae"} 02:49:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:49:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:49:48 INFO - Contents: 02:49:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7a87a9efd2f96afd675ce500a2244b26565c3be470de4890bb1f9cb59aac6047b144a2f26bb3cd6b40f97aa8961b88530fbdb3189f373cab87c5bd558e37af3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c4ff49d8e2f0d5544655d6ac172c5a609b0365cf4ea18e800e503a84a222a5b713862b923a5cf949313076a20ce5d452fc09df7fbaf666e305102209d89789a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a7642a5a20ed72bd556ad4f5119af972b48c220095c8ea508d6d103231f31029cae182b5803c15fc3e1bd96935485b24b5d90bda398c37fda17206853722fcae"} 02:49:48 INFO - Running post-run listener: copy_logs_to_upload_dir 02:49:48 INFO - Copying logs to upload dir... 02:49:48 INFO - mkdir: /builds/slave/test/build/upload/logs 02:49:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.020825 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-07-18 02:49:48.104899) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 02:49:48.108786) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134 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/7a87a9efd2f96afd675ce500a2244b26565c3be470de4890bb1f9cb59aac6047b144a2f26bb3cd6b40f97aa8961b88530fbdb3189f373cab87c5bd558e37af3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c4ff49d8e2f0d5544655d6ac172c5a609b0365cf4ea18e800e503a84a222a5b713862b923a5cf949313076a20ce5d452fc09df7fbaf666e305102209d89789a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a7642a5a20ed72bd556ad4f5119af972b48c220095c8ea508d6d103231f31029cae182b5803c15fc3e1bd96935485b24b5d90bda398c37fda17206853722fcae"} build_url:https://queue.taskcluster.net/v1/task/ONqcWpO9SYevQzYF6YUImw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011342 build_url: 'https://queue.taskcluster.net/v1/task/ONqcWpO9SYevQzYF6YUImw/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/7a87a9efd2f96afd675ce500a2244b26565c3be470de4890bb1f9cb59aac6047b144a2f26bb3cd6b40f97aa8961b88530fbdb3189f373cab87c5bd558e37af3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c4ff49d8e2f0d5544655d6ac172c5a609b0365cf4ea18e800e503a84a222a5b713862b923a5cf949313076a20ce5d452fc09df7fbaf666e305102209d89789a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a7642a5a20ed72bd556ad4f5119af972b48c220095c8ea508d6d103231f31029cae182b5803c15fc3e1bd96935485b24b5d90bda398c37fda17206853722fcae"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 02:49:48.134346) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:49:48.134669) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1468834115.98010-745822134 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 02:49:48.218173) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 02:49:48.218504) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 02:49:48.218795) ========= ========= Total master_lag: 0.16 =========