builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-034 starttime: 1468338261.43 results: success (0) buildid: 20160712072641 builduid: f1671b1e731d494fbcad432be7cc2726 revision: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:21.429555) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:21.429980) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:21.430278) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:21.444779) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:21.445072) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-12 08:44:21-- 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% 222M=0s 2016-07-12 08:44:21 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142219 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:21.601380) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:21.601712) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017449 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:21.651323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:21.651692) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb --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=d6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-12 08:44:21,798 truncating revision to first 12 chars 2016-07-12 08:44:21,798 Setting DEBUG logging. 2016-07-12 08:44:21,798 attempt 1/10 2016-07-12 08:44:21,798 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fcfa26398425?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 08:44:22,124 unpacking tar archive at: mozilla-central-fcfa26398425/testing/mozharness/ program finished with exit code 0 elapsedTime=0.631923 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:22.297405) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:22.297693) ========= script_repo_revision: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:22.298148) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:22.298483) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 08:44:22.321351) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-07-12 08:44:22.321755) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=d6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:44:22 INFO - MultiFileLogger online at 20160712 08:44:22 in /builds/slave/test 08:44:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:44:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:44:22 INFO - {'append_to_log': False, 08:44:22 INFO - 'base_work_dir': '/builds/slave/test', 08:44:22 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:44:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:44:22 INFO - 'branch': 'Firefox-Non-PGO', 08:44:22 INFO - 'buildbot_json_path': 'buildprops.json', 08:44:22 INFO - 'config_files': ('talos/linux_config.py',), 08:44:22 INFO - 'default_actions': ('clobber', 08:44:22 INFO - 'read-buildbot-config', 08:44:22 INFO - 'download-and-extract', 08:44:22 INFO - 'populate-webroot', 08:44:22 INFO - 'create-virtualenv', 08:44:22 INFO - 'install', 08:44:22 INFO - 'run-tests'), 08:44:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:44:22 INFO - 'download_minidump_stackwalk': True, 08:44:22 INFO - 'download_symbols': 'ondemand', 08:44:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:44:22 INFO - 'tooltool.py': '/tools/tooltool.py', 08:44:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:44:22 INFO - '/tools/misc-python/virtualenv.py')}, 08:44:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:44:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:44:22 INFO - 'installer_path': 'installer.exe', 08:44:22 INFO - 'log_level': 'info', 08:44:22 INFO - 'log_name': 'talos', 08:44:22 INFO - 'log_to_console': True, 08:44:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:44:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:44:22 INFO - 'opt_config_files': (), 08:44:22 INFO - 'pip_index': False, 08:44:22 INFO - 'sps_profile': False, 08:44:22 INFO - 'sps_profile_interval': 0, 08:44:22 INFO - 'suite': 'dromaeojs', 08:44:22 INFO - 'system_bits': '32', 08:44:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:44:22 INFO - 'title': 'talos-linux64-ix-034', 08:44:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:44:22 INFO - 'use_talos_json': True, 08:44:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:44:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:44:22 INFO - 'work_dir': 'build'} 08:44:22 INFO - ##### 08:44:22 INFO - ##### Running clobber step. 08:44:22 INFO - ##### 08:44:22 INFO - Running pre-action listener: _resource_record_pre_action 08:44:22 INFO - Running main action method: clobber 08:44:22 INFO - rmtree: /builds/slave/test/build 08:44:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:44:23 INFO - Running post-action listener: _resource_record_post_action 08:44:23 INFO - ##### 08:44:23 INFO - ##### Finished clobber step (success) 08:44:23 INFO - ##### 08:44:23 INFO - ##### 08:44:23 INFO - ##### Running read-buildbot-config step. 08:44:23 INFO - ##### 08:44:23 INFO - Running pre-action listener: _resource_record_pre_action 08:44:23 INFO - Running main action method: read_buildbot_config 08:44:23 INFO - Using buildbot properties: 08:44:23 INFO - { 08:44:23 INFO - "project": "", 08:44:23 INFO - "product": "firefox", 08:44:23 INFO - "script_repo_revision": "production", 08:44:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:44:23 INFO - "repository": "", 08:44:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 08:44:23 INFO - "buildid": "20160712072641", 08:44:23 INFO - "pgo_build": "False", 08:44:23 INFO - "basedir": "/builds/slave/test", 08:44:23 INFO - "buildnumber": 554, 08:44:23 INFO - "slavename": "talos-linux64-ix-034", 08:44:23 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:44:23 INFO - "platform": "ubuntu64_hw", 08:44:23 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 08:44:23 INFO - "branch": "mozilla-central", 08:44:23 INFO - "repo_path": "mozilla-central", 08:44:23 INFO - "revision": "fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb", 08:44:23 INFO - "stage_platform": "linux64", 08:44:23 INFO - "builduid": "f1671b1e731d494fbcad432be7cc2726", 08:44:23 INFO - "slavebuilddir": "test" 08:44:23 INFO - } 08:44:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 08:44:23 INFO - Running post-action listener: _resource_record_post_action 08:44:23 INFO - ##### 08:44:23 INFO - ##### Finished read-buildbot-config step (success) 08:44:23 INFO - ##### 08:44:23 INFO - ##### 08:44:23 INFO - ##### Running download-and-extract step. 08:44:23 INFO - ##### 08:44:23 INFO - Running pre-action listener: _resource_record_pre_action 08:44:23 INFO - Running main action method: download_and_extract 08:44:23 INFO - mkdir: /builds/slave/test/build/tests 08:44: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')]} 08:44:23 INFO - https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:44:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:44:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:44:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/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 08:44:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/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 08:44:25 INFO - Downloaded 1361 bytes. 08:44:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:44:25 INFO - Using the following test package requirements: 08:44:25 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:44:25 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:44:25 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:44:25 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:25 INFO - u'jsshell-linux-x86_64.zip'], 08:44:25 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:44:25 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:44:25 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:44:25 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:44:25 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:44:25 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:25 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:44:25 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:44: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')]} 08:44:25 INFO - https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:44:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:44:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:44:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/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 08:44:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/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 08:44:35 INFO - Downloaded 22700490 bytes. 08:44:35 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'] 08:44:35 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 08:44:36 INFO - Return code: 0 08:44:36 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 08:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:36 INFO - https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:44:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:44:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:44:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/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 08:44:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/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 08:44:41 INFO - Downloaded 22700490 bytes. 08:44:41 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'] 08:44:41 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 08:44:42 INFO - Return code: 0 08:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:42 INFO - https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:44:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:44:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:44:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/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 08:44:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/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 08:44:43 INFO - Downloaded 14403317 bytes. 08:44:43 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'] 08:44:43 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 08:44:43 INFO - Return code: 0 08:44:43 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')]} 08:44:43 INFO - https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:44:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:44:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:44:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/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 08:44:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fp27pcFoQnChnyYoj2T5og/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 08:44:45 INFO - Downloaded 56653328 bytes. 08:44:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:44:45 INFO - mkdir: /builds/slave/test/properties 08:44:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:44:45 INFO - Writing to file /builds/slave/test/properties/build_url 08:44:45 INFO - Contents: 08:44:45 INFO - build_url:https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:44:46 INFO - Running post-action listener: _resource_record_post_action 08:44:46 INFO - Running post-action listener: set_extra_try_arguments 08:44:46 INFO - ##### 08:44:46 INFO - ##### Finished download-and-extract step (success) 08:44:46 INFO - ##### 08:44:46 INFO - ##### 08:44:46 INFO - ##### Running populate-webroot step. 08:44:46 INFO - ##### 08:44:46 INFO - Running pre-action listener: _resource_record_pre_action 08:44:46 INFO - Running main action method: populate_webroot 08:44:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:44:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:44:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:44:46 INFO - '--tppagecycles', 08:44:46 INFO - '7'], 08:44:46 INFO - 'tests': ['tsvgm']}}, 08:44:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:44:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:44:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:44:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['damp', 'tps']}, 08:44:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'tests': ['damp', 'tps']}, 08:44:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['dromaeo_dom']}, 08:44:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['basic_compositor_video']}, 08:44:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:44:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'other-e10s': {'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['a11yr', 08:44:46 INFO - 'ts_paint', 08:44:46 INFO - 'tpaint', 08:44:46 INFO - 'sessionrestore', 08:44:46 INFO - 'sessionrestore_no_auto_restore', 08:44:46 INFO - 'tabpaint']}, 08:44:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['tsvgx', 08:44:46 INFO - 'tsvgr_opacity', 08:44:46 INFO - 'tart', 08:44:46 INFO - 'tscrollx', 08:44:46 INFO - 'cart']}, 08:44:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:44:46 INFO - 'tsvgr_opacity', 08:44:46 INFO - 'tart', 08:44:46 INFO - 'tscrollx', 08:44:46 INFO - 'cart']}, 08:44:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'talos_options': ['--disable-e10s'], 08:44:46 INFO - 'tests': ['tp5o']}, 08:44:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'tests': ['tp5o']}, 08:44:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'talos_options': ['--disable-e10s', 08:44:46 INFO - '--xperf_path', 08:44:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:46 INFO - 'tests': ['tp5n']}, 08:44:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:46 INFO - 'talos_options': ['--xperf_path', 08:44:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:46 INFO - 'tests': ['tp5n']}}, 08:44:46 INFO - 'talos.zip': {'path': '', 08:44:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:44:46 INFO - Running post-action listener: _resource_record_post_action 08:44:46 INFO - ##### 08:44:46 INFO - ##### Finished populate-webroot step (success) 08:44:46 INFO - ##### 08:44:46 INFO - ##### 08:44:46 INFO - ##### Running create-virtualenv step. 08:44:46 INFO - ##### 08:44:46 INFO - Running pre-action listener: _resource_record_pre_action 08:44:46 INFO - Running main action method: create_virtualenv 08:44:46 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44:46 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 08:44:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:44:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:46 INFO - Using real prefix '/usr' 08:44:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:47 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:49 INFO - Installing pip.................done. 08:44:49 INFO - Return code: 0 08:44:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:49 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 0xff2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b13b0>, '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 0x12b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b2740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', '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 08:44:49 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 08:44:49 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 08:44:49 INFO - Using env: {'DISPLAY': ':0', 08:44:49 INFO - 'HOME': '/home/cltbld', 08:44:49 INFO - 'LANG': 'en_US.UTF-8', 08:44:49 INFO - 'LANGUAGE': 'en_US:en', 08:44:49 INFO - 'LOGNAME': 'cltbld', 08:44:49 INFO - 'MAIL': '/var/mail/cltbld', 08:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:49 INFO - 'MOZ_NO_REMOTE': '1', 08:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:49 INFO - 'NO_EM_RESTART': '1', 08:44:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:49 INFO - 'PWD': '/builds/slave/test', 08:44:49 INFO - 'SHELL': '/bin/bash', 08:44:49 INFO - 'SHLVL': '1', 08:44:49 INFO - 'TERM': 'linux', 08:44:49 INFO - 'TMOUT': '86400', 08:44:49 INFO - 'USER': 'cltbld', 08:44:49 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', 08:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:49 INFO - '_': '/tools/buildbot/bin/python'} 08:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:49 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 08:44:49 INFO - Cleaning up... 08:44:49 INFO - Return code: 0 08:44:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:49 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 0xff2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b13b0>, '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 0x12b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b2740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', '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 08:44:49 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 08:44:49 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 08:44:49 INFO - Using env: {'DISPLAY': ':0', 08:44:49 INFO - 'HOME': '/home/cltbld', 08:44:49 INFO - 'LANG': 'en_US.UTF-8', 08:44:49 INFO - 'LANGUAGE': 'en_US:en', 08:44:49 INFO - 'LOGNAME': 'cltbld', 08:44:49 INFO - 'MAIL': '/var/mail/cltbld', 08:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:49 INFO - 'MOZ_NO_REMOTE': '1', 08:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:49 INFO - 'NO_EM_RESTART': '1', 08:44:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:49 INFO - 'PWD': '/builds/slave/test', 08:44:49 INFO - 'SHELL': '/bin/bash', 08:44:49 INFO - 'SHLVL': '1', 08:44:49 INFO - 'TERM': 'linux', 08:44:49 INFO - 'TMOUT': '86400', 08:44:49 INFO - 'USER': 'cltbld', 08:44:49 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', 08:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:49 INFO - '_': '/tools/buildbot/bin/python'} 08:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:49 INFO - Downloading/unpacking psutil>=3.1.1 08:44:49 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 08:44:49 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 08:44:49 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 08:44:49 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 08:44:49 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 08:44:49 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 08:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:44:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:51 INFO - Installing collected packages: psutil 08:44:51 INFO - Running setup.py install for psutil 08:44:51 INFO - building 'psutil._psutil_linux' extension 08:44:51 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 08:44:51 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 08:44:51 INFO - building 'psutil._psutil_posix' extension 08:44:51 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 08:44:51 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 08:44:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:52 INFO - Successfully installed psutil 08:44:52 INFO - Cleaning up... 08:44:52 INFO - Return code: 0 08:44:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:44:52 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')]} 08:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:52 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')]} 08:44:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:52 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 0xff2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b13b0>, '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 0x12b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b2740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', '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 08:44:52 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 08:44:52 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 08:44:52 INFO - Using env: {'DISPLAY': ':0', 08:44:52 INFO - 'HOME': '/home/cltbld', 08:44:52 INFO - 'LANG': 'en_US.UTF-8', 08:44:52 INFO - 'LANGUAGE': 'en_US:en', 08:44:52 INFO - 'LOGNAME': 'cltbld', 08:44:52 INFO - 'MAIL': '/var/mail/cltbld', 08:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:52 INFO - 'MOZ_NO_REMOTE': '1', 08:44:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:52 INFO - 'NO_EM_RESTART': '1', 08:44:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:52 INFO - 'PWD': '/builds/slave/test', 08:44:52 INFO - 'SHELL': '/bin/bash', 08:44:52 INFO - 'SHLVL': '1', 08:44:52 INFO - 'TERM': 'linux', 08:44:52 INFO - 'TMOUT': '86400', 08:44:52 INFO - 'USER': 'cltbld', 08:44:52 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', 08:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:52 INFO - '_': '/tools/buildbot/bin/python'} 08:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:44:52 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 08:44:52 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 08:44:52 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 08:44:52 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 08:44:52 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 08:44:52 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 08:44:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:44:54 INFO - Installing collected packages: mozsystemmonitor 08:44:54 INFO - Running setup.py install for mozsystemmonitor 08:44:54 INFO - Successfully installed mozsystemmonitor 08:44:54 INFO - Cleaning up... 08:44:54 INFO - Return code: 0 08:44:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:44:54 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')]} 08:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:54 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')]} 08:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:54 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 0xff2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b13b0>, '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 0x12b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b2740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', '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 08:44:54 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 08:44:54 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 08:44:54 INFO - Using env: {'DISPLAY': ':0', 08:44:54 INFO - 'HOME': '/home/cltbld', 08:44:54 INFO - 'LANG': 'en_US.UTF-8', 08:44:54 INFO - 'LANGUAGE': 'en_US:en', 08:44:54 INFO - 'LOGNAME': 'cltbld', 08:44:54 INFO - 'MAIL': '/var/mail/cltbld', 08:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:54 INFO - 'MOZ_NO_REMOTE': '1', 08:44:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:54 INFO - 'NO_EM_RESTART': '1', 08:44:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:54 INFO - 'PWD': '/builds/slave/test', 08:44:54 INFO - 'SHELL': '/bin/bash', 08:44:54 INFO - 'SHLVL': '1', 08:44:54 INFO - 'TERM': 'linux', 08:44:54 INFO - 'TMOUT': '86400', 08:44:54 INFO - 'USER': 'cltbld', 08:44:54 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', 08:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:54 INFO - '_': '/tools/buildbot/bin/python'} 08:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:54 INFO - Downloading/unpacking jsonschema==2.5.1 08:44:54 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 08:44:54 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 08:44:54 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 08:44:54 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 08:44:54 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 08:44:54 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 08:44:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:44:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:44:56 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 08:44:56 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 08:44:56 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 08:44:56 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 08:44:56 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 08:44:56 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 08:44:56 INFO - Downloading functools32-3.2.3-2.tar.gz 08:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:44:56 INFO - warning: no files found matching '*.txt' 08:44:56 INFO - no previously-included directories found matching 'build' 08:44:56 INFO - no previously-included directories found matching 'dist' 08:44:56 INFO - no previously-included directories found matching '.git*' 08:44:56 INFO - Installing collected packages: jsonschema, functools32 08:44:56 INFO - Running setup.py install for functools32 08:44:56 INFO - warning: no files found matching '*.txt' 08:44:56 INFO - no previously-included directories found matching 'build' 08:44:56 INFO - no previously-included directories found matching 'dist' 08:44:56 INFO - no previously-included directories found matching '.git*' 08:44:56 INFO - Successfully installed jsonschema functools32 08:44:56 INFO - Cleaning up... 08:44:56 INFO - Return code: 0 08:44:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44:56 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')]} 08:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:56 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')]} 08:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:56 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 0xff2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b13b0>, '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 0x12b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b2740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', '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 08:44:56 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 08:44:56 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 08:44:56 INFO - Using env: {'DISPLAY': ':0', 08:44:56 INFO - 'HOME': '/home/cltbld', 08:44:56 INFO - 'LANG': 'en_US.UTF-8', 08:44:56 INFO - 'LANGUAGE': 'en_US:en', 08:44:56 INFO - 'LOGNAME': 'cltbld', 08:44:56 INFO - 'MAIL': '/var/mail/cltbld', 08:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:56 INFO - 'MOZ_NO_REMOTE': '1', 08:44:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:56 INFO - 'NO_EM_RESTART': '1', 08:44:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:56 INFO - 'PWD': '/builds/slave/test', 08:44:56 INFO - 'SHELL': '/bin/bash', 08:44:56 INFO - 'SHLVL': '1', 08:44:56 INFO - 'TERM': 'linux', 08:44:56 INFO - 'TMOUT': '86400', 08:44:56 INFO - 'USER': 'cltbld', 08:44:56 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', 08:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:56 INFO - '_': '/tools/buildbot/bin/python'} 08:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:57 INFO - Downloading/unpacking blobuploader==1.2.4 08:44:57 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 08:44:57 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 08:44:57 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 08:44:57 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 08:44:57 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 08:44:57 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 08:44:59 INFO - Downloading blobuploader-1.2.4.tar.gz 08:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:44:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:44:59 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 08:44:59 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 08:44:59 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 08:44:59 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 08:44:59 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 08:44:59 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 08:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:44:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:44:59 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 08:44:59 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 08:44:59 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 08:44:59 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 08:44:59 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 08:44:59 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 08:44:59 INFO - Downloading docopt-0.6.1.tar.gz 08:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:44:59 INFO - Installing collected packages: blobuploader, requests, docopt 08:44:59 INFO - Running setup.py install for blobuploader 08:44:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:44:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:44:59 INFO - Running setup.py install for requests 08:45:00 INFO - Running setup.py install for docopt 08:45:00 INFO - Successfully installed blobuploader requests docopt 08:45:00 INFO - Cleaning up... 08:45:00 INFO - Return code: 0 08:45:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:00 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')]} 08:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:00 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')]} 08:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:00 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 0xff2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b13b0>, '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 0x12b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b2740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', '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 08:45:00 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 08:45:00 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 08:45:00 INFO - Using env: {'DISPLAY': ':0', 08:45:00 INFO - 'HOME': '/home/cltbld', 08:45:00 INFO - 'LANG': 'en_US.UTF-8', 08:45:00 INFO - 'LANGUAGE': 'en_US:en', 08:45:00 INFO - 'LOGNAME': 'cltbld', 08:45:00 INFO - 'MAIL': '/var/mail/cltbld', 08:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:00 INFO - 'MOZ_NO_REMOTE': '1', 08:45:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:00 INFO - 'NO_EM_RESTART': '1', 08:45:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:00 INFO - 'PWD': '/builds/slave/test', 08:45:00 INFO - 'SHELL': '/bin/bash', 08:45:00 INFO - 'SHLVL': '1', 08:45:00 INFO - 'TERM': 'linux', 08:45:00 INFO - 'TMOUT': '86400', 08:45:00 INFO - 'USER': 'cltbld', 08:45:00 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', 08:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:00 INFO - '_': '/tools/buildbot/bin/python'} 08:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:00 INFO - Running setup.py (path:/tmp/pip-T9BzqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:00 INFO - Running setup.py (path:/tmp/pip-mn5eya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:00 INFO - Running setup.py (path:/tmp/pip-30rSqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:00 INFO - Running setup.py (path:/tmp/pip-BPhLbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:00 INFO - Running setup.py (path:/tmp/pip-6JqNca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:01 INFO - Running setup.py (path:/tmp/pip-QpE7N7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:01 INFO - Running setup.py (path:/tmp/pip-9D8fQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:01 INFO - Running setup.py (path:/tmp/pip-I02KE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:01 INFO - Running setup.py (path:/tmp/pip-t8pm18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:01 INFO - Running setup.py (path:/tmp/pip-4RFILX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:01 INFO - Running setup.py (path:/tmp/pip-bGeQo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:01 INFO - Running setup.py (path:/tmp/pip-Znyh2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:01 INFO - Running setup.py (path:/tmp/pip-YbLaSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:01 INFO - Running setup.py (path:/tmp/pip-eucUvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:01 INFO - Running setup.py (path:/tmp/pip-WzinXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:01 INFO - Running setup.py (path:/tmp/pip-kEftvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:01 INFO - Running setup.py (path:/tmp/pip-M0cTqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:45:01 INFO - Running setup.py install for manifestparser 08:45:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:45:01 INFO - Running setup.py install for mozcrash 08:45:02 INFO - Running setup.py install for mozdebug 08:45:02 INFO - Running setup.py install for mozdevice 08:45:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Running setup.py install for mozfile 08:45:02 INFO - Running setup.py install for mozhttpd 08:45:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Running setup.py install for mozinfo 08:45:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Running setup.py install for mozInstall 08:45:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Running setup.py install for mozleak 08:45:02 INFO - Running setup.py install for mozlog 08:45:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:45:02 INFO - Running setup.py install for moznetwork 08:45:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:45:03 INFO - Running setup.py install for mozprocess 08:45:03 INFO - Running setup.py install for mozprofile 08:45:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:45:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:45:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:45:03 INFO - Running setup.py install for mozrunner 08:45:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:45:03 INFO - Running setup.py install for mozscreenshot 08:45:03 INFO - Running setup.py install for moztest 08:45:03 INFO - Running setup.py install for mozversion 08:45:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:45:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:45:03 INFO - Cleaning up... 08:45:03 INFO - Return code: 0 08:45:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:45:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:03 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 0xff2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b13b0>, '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 0x12b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b2740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', '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 08:45:03 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 08:45:03 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 08:45:03 INFO - Using env: {'DISPLAY': ':0', 08:45:03 INFO - 'HOME': '/home/cltbld', 08:45:03 INFO - 'LANG': 'en_US.UTF-8', 08:45:03 INFO - 'LANGUAGE': 'en_US:en', 08:45:03 INFO - 'LOGNAME': 'cltbld', 08:45:03 INFO - 'MAIL': '/var/mail/cltbld', 08:45:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:03 INFO - 'MOZ_NO_REMOTE': '1', 08:45:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:03 INFO - 'NO_EM_RESTART': '1', 08:45:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:03 INFO - 'PWD': '/builds/slave/test', 08:45:03 INFO - 'SHELL': '/bin/bash', 08:45:03 INFO - 'SHLVL': '1', 08:45:03 INFO - 'TERM': 'linux', 08:45:03 INFO - 'TMOUT': '86400', 08:45:03 INFO - 'USER': 'cltbld', 08:45:03 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', 08:45:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:03 INFO - '_': '/tools/buildbot/bin/python'} 08:45:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:03 INFO - Running setup.py (path:/tmp/pip-eiRELK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:03 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)) 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:03 INFO - Running setup.py (path:/tmp/pip-62m2BJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:04 INFO - Running setup.py (path:/tmp/pip-20dPQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:04 INFO - Running setup.py (path:/tmp/pip-Q8wJGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:04 INFO - Running setup.py (path:/tmp/pip-N3eB8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:04 INFO - Running setup.py (path:/tmp/pip-4SWw0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:04 INFO - Running setup.py (path:/tmp/pip-fdWDws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:04 INFO - Running setup.py (path:/tmp/pip-01_S4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:04 INFO - Running setup.py (path:/tmp/pip-IcZ8ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:04 INFO - Running setup.py (path:/tmp/pip-F4CQSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:04 INFO - Running setup.py (path:/tmp/pip-XaZo8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:04 INFO - Running setup.py (path:/tmp/pip-uCGaJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:04 INFO - Running setup.py (path:/tmp/pip-lQy3g6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:04 INFO - Running setup.py (path:/tmp/pip-m5ar2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:04 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)) 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:04 INFO - Running setup.py (path:/tmp/pip-hYWOah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:05 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)) 08:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:05 INFO - Running setup.py (path:/tmp/pip-ugPjZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:05 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)) 08:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:05 INFO - Running setup.py (path:/tmp/pip-DKlUAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 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)) 08:45:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:45:05 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 08:45:05 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 08:45:05 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 08:45:05 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 08:45:05 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 08:45:05 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 08:45:07 INFO - Downloading blessings-1.6.tar.gz 08:45:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:45:07 INFO - Installing collected packages: blessings 08:45:07 INFO - Running setup.py install for blessings 08:45:07 INFO - Successfully installed blessings 08:45:07 INFO - Cleaning up... 08:45:07 INFO - Return code: 0 08:45:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:45:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:07 INFO - Reading from file tmpfile_stdout 08:45:07 INFO - Current package versions: 08:45:07 INFO - argparse == 1.2.1 08:45:07 INFO - blessings == 1.6 08:45:07 INFO - blobuploader == 1.2.4 08:45:07 INFO - docopt == 0.6.1 08:45:07 INFO - functools32 == 3.2.3-2 08:45:07 INFO - jsonschema == 2.5.1 08:45:07 INFO - manifestparser == 1.1 08:45:07 INFO - mozInstall == 1.12 08:45:07 INFO - mozcrash == 0.17 08:45:07 INFO - mozdebug == 0.1 08:45:07 INFO - mozdevice == 0.48 08:45:07 INFO - mozfile == 1.2 08:45:07 INFO - mozhttpd == 0.7 08:45:07 INFO - mozinfo == 0.9 08:45:07 INFO - mozleak == 0.1 08:45:07 INFO - mozlog == 3.2 08:45:07 INFO - moznetwork == 0.27 08:45:07 INFO - mozprocess == 0.23 08:45:07 INFO - mozprofile == 0.28 08:45:07 INFO - mozrunner == 6.12 08:45:07 INFO - mozscreenshot == 0.1 08:45:07 INFO - mozsystemmonitor == 0.3 08:45:07 INFO - moztest == 0.7 08:45:07 INFO - mozversion == 1.4 08:45:07 INFO - psutil == 3.1.1 08:45:07 INFO - requests == 1.2.3 08:45:07 INFO - wsgiref == 0.1.2 08:45:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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')]} 08:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:07 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 0xff2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b13b0>, '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 0x12b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b2740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', '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 08:45:07 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 08:45:07 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 08:45:07 INFO - Using env: {'DISPLAY': ':0', 08:45:07 INFO - 'HOME': '/home/cltbld', 08:45:07 INFO - 'LANG': 'en_US.UTF-8', 08:45:07 INFO - 'LANGUAGE': 'en_US:en', 08:45:07 INFO - 'LOGNAME': 'cltbld', 08:45:07 INFO - 'MAIL': '/var/mail/cltbld', 08:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:07 INFO - 'MOZ_NO_REMOTE': '1', 08:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:07 INFO - 'NO_EM_RESTART': '1', 08:45:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:07 INFO - 'PWD': '/builds/slave/test', 08:45:07 INFO - 'SHELL': '/bin/bash', 08:45:07 INFO - 'SHLVL': '1', 08:45:07 INFO - 'TERM': 'linux', 08:45:07 INFO - 'TMOUT': '86400', 08:45:07 INFO - 'USER': 'cltbld', 08:45:07 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', 08:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:07 INFO - '_': '/tools/buildbot/bin/python'} 08:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 INFO - Cleaning up... 08:45:07 INFO - Return code: 0 08:45:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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')]} 08:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:07 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 0xff2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b13b0>, '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 0x12b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b2740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', '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 08:45:07 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 08:45:07 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 08:45:07 INFO - Using env: {'DISPLAY': ':0', 08:45:07 INFO - 'HOME': '/home/cltbld', 08:45:07 INFO - 'LANG': 'en_US.UTF-8', 08:45:07 INFO - 'LANGUAGE': 'en_US:en', 08:45:07 INFO - 'LOGNAME': 'cltbld', 08:45:07 INFO - 'MAIL': '/var/mail/cltbld', 08:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:07 INFO - 'MOZ_NO_REMOTE': '1', 08:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:07 INFO - 'NO_EM_RESTART': '1', 08:45:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:07 INFO - 'PWD': '/builds/slave/test', 08:45:07 INFO - 'SHELL': '/bin/bash', 08:45:07 INFO - 'SHLVL': '1', 08:45:07 INFO - 'TERM': 'linux', 08:45:07 INFO - 'TMOUT': '86400', 08:45:07 INFO - 'USER': 'cltbld', 08:45:07 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', 08:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:07 INFO - '_': '/tools/buildbot/bin/python'} 08:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:45:07 INFO - Cleaning up... 08:45:07 INFO - Return code: 0 08:45:07 INFO - Running post-action listener: _resource_record_post_action 08:45:07 INFO - Running post-action listener: _start_resource_monitoring 08:45:08 INFO - Starting resource monitoring. 08:45:08 INFO - ##### 08:45:08 INFO - ##### Finished create-virtualenv step (success) 08:45:08 INFO - ##### 08:45:08 INFO - ##### 08:45:08 INFO - ##### Running install step. 08:45:08 INFO - ##### 08:45:08 INFO - Running pre-action listener: _resource_record_pre_action 08:45:08 INFO - Running main action method: install 08:45:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:08 INFO - Reading from file tmpfile_stdout 08:45:08 INFO - Detecting whether we're running mozinstall >=1.0... 08:45:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:45:08 INFO - Reading from file tmpfile_stdout 08:45:08 INFO - Output received: 08:45:08 INFO - Usage: mozinstall [options] installer 08:45:08 INFO - Options: 08:45:08 INFO - -h, --help show this help message and exit 08:45:08 INFO - -d DEST, --destination=DEST 08:45:08 INFO - Directory to install application into. [default: 08:45:08 INFO - "/builds/slave/test"] 08:45:08 INFO - --app=APP Application being installed. [default: firefox] 08:45:08 INFO - mkdir: /builds/slave/test/build/application 08:45:08 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'] 08:45:08 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 08:45:24 INFO - Reading from file tmpfile_stdout 08:45:24 INFO - Output received: 08:45:24 INFO - /builds/slave/test/build/application/firefox/firefox 08:45:24 INFO - Running post-action listener: _resource_record_post_action 08:45:24 INFO - ##### 08:45:24 INFO - ##### Finished install step (success) 08:45:24 INFO - ##### 08:45:24 INFO - ##### 08:45:24 INFO - ##### Running run-tests step. 08:45:24 INFO - ##### 08:45:24 INFO - Running pre-action listener: _resource_record_pre_action 08:45:24 INFO - Running main action method: run_tests 08:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:45:24 INFO - Python 2.7.3 08:45:24 INFO - Return code: 0 08:45:24 INFO - grabbing minidump binary from tooltool 08:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:45:24 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 0x12b13b0>, '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 0x12b22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12b2740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:45:24 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 08:45:24 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 08:45:24 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 08:45:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:45:24 INFO - Return code: 0 08:45:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:45:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:45:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:45:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:45:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 08:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 08:45:24 INFO - Using env: {'DISPLAY': ':0', 08:45:24 INFO - 'HOME': '/home/cltbld', 08:45:24 INFO - 'LANG': 'en_US.UTF-8', 08:45:24 INFO - 'LANGUAGE': 'en_US:en', 08:45:24 INFO - 'LOGNAME': 'cltbld', 08:45:24 INFO - 'MAIL': '/var/mail/cltbld', 08:45:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:45:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:24 INFO - 'MOZ_NO_REMOTE': '1', 08:45:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:45:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:24 INFO - 'NO_EM_RESTART': '1', 08:45:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:24 INFO - 'PWD': '/builds/slave/test', 08:45:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:45:24 INFO - 'SHELL': '/bin/bash', 08:45:24 INFO - 'SHLVL': '1', 08:45:24 INFO - 'TERM': 'linux', 08:45:24 INFO - 'TMOUT': '86400', 08:45:24 INFO - 'USER': 'cltbld', 08:45:24 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394', 08:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:24 INFO - '_': '/tools/buildbot/bin/python'} 08:45:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 08:45:24 INFO - mozversion application_buildid: 20160712072641 08:45:24 INFO - mozversion application_changeset: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb 08:45:24 INFO - mozversion application_display_name: Nightly 08:45:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:45:24 INFO - mozversion application_name: Firefox 08:45:24 INFO - mozversion application_remotingname: firefox 08:45:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:45:24 INFO - mozversion application_vendor: Mozilla 08:45:24 INFO - mozversion application_version: 50.0a1 08:45:24 INFO - mozversion platform_buildid: 20160712072641 08:45:24 INFO - mozversion platform_changeset: fcfa26398425779f0dc87fc4658f2ebcc6f6f8cb 08:45:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:45:24 INFO - mozversion platform_version: 50.0a1 08:45:24 INFO - using testdate: 1468338324 08:45:24 INFO - actual date: 1468338324 08:45:24 INFO - starting webserver on 'localhost:50183' 08:45:24 INFO - SUITE-START | Running 2 tests 08:45:24 INFO - TEST-START | dromaeo_css 08:45:24 INFO - operating with platform_type : linux_ 08:45:24 INFO - Initialising browser for dromaeo_css test... 08:45:24 INFO - TEST-INFO | started process 25173 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjuPXVd/profile http://localhost:50183/getInfo.html) 08:45:29 INFO - PROCESS | 25173 | __metrics Screen width/height:1600/1200 08:45:29 INFO - PROCESS | 25173 | colorDepth:24 08:45:29 INFO - PROCESS | 25173 | Browser inner width/height: 1024/697 08:45:29 INFO - PROCESS | 25173 | __metrics 08:45:30 INFO - PROCESS | 25173 | 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" 08:45:30 INFO - PROCESS | 25173 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:45:30 INFO - TEST-INFO | 25173: exit 0 08:45:30 INFO - Browser initialized. 08:45:30 INFO - Running cycle 1/1 for dromaeo_css test... 08:45:30 INFO - TEST-INFO | started process 25257 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjuPXVd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:42 INFO - PROCESS | 25257 | RSS: Main: 159182848 08:45:42 INFO - PROCESS | 25257 | 08:46:33 INFO - PROCESS | 25257 | 1468338393748 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] 08:48:46 INFO - PROCESS | 25257 | Cycle 1(1): loaded http://localhost:50183/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50183/tests/dromaeo/cssquery-ext.html) 08:48:47 INFO - PROCESS | 25257 | RSS: Main: 235139072 08:48:47 INFO - PROCESS | 25257 | 08:51:50 INFO - PROCESS | 25257 | Cycle 1(1): loaded http://localhost:50183/tests/dromaeo/cssquery-ext.html (next: http://localhost:50183/tests/dromaeo/cssquery-jquery.html) 08:51:50 INFO - PROCESS | 25257 | RSS: Main: 242937856 08:51:50 INFO - PROCESS | 25257 | 08:54:53 INFO - PROCESS | 25257 | Cycle 1(1): loaded http://localhost:50183/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50183/tests/dromaeo/cssquery-mootools.html) 08:54:53 INFO - PROCESS | 25257 | RSS: Main: 241246208 08:54:53 INFO - PROCESS | 25257 | 08:57:55 INFO - PROCESS | 25257 | Cycle 1(1): loaded http://localhost:50183/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50183/tests/dromaeo/cssquery-prototype.html) 08:57:56 INFO - PROCESS | 25257 | RSS: Main: 249159680 08:57:56 INFO - PROCESS | 25257 | 09:00:59 INFO - PROCESS | 25257 | Cycle 1(1): loaded http://localhost:50183/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50183/tests/dromaeo/cssquery-yui.html) 09:01:00 INFO - PROCESS | 25257 | RSS: Main: 208166912 09:01:00 INFO - PROCESS | 25257 | 09:04:02 INFO - PROCESS | 25257 | Cycle 1(1): loaded http://localhost:50183/tests/dromaeo/cssquery-yui.html (next: http://localhost:50183/tests/dromaeo/cssquery-dojo.html) 09:04:03 INFO - PROCESS | 25257 | RSS: Main: 299786240 09:04:03 INFO - PROCESS | 25257 | 09:04:03 INFO - PROCESS | 25257 | __start_tp_report 09:04:03 INFO - PROCESS | 25257 | _x_x_mozilla_page_load 09:04:03 INFO - PROCESS | 25257 | _x_x_mozilla_page_load_details 09:04:03 INFO - PROCESS | 25257 | |i|pagename|runs| 09:04:03 INFO - PROCESS | 25257 | |0;dojo.html;2609.660744103266;2616.725243849396;2650.1387049209;2662.1880326500414;2680.8525531095793;4558.794854231559;4637.420322459692;4734.053189362128;4767.833125840596;4791.425028996517;4653.790579423926;4681.133990211813;4681.414823147106;4716.292556116584;4730.692504987173;4082.1835632873394;4270.017895883949;4343.630791382736;4371.27873900806;4463.062756821071;4359.084592235635;4396.450443694538;4413.3379993001145;4417.558244175573;4448.755318457484;4118.835246590148;4162.708610397273;4304.375865499516;4368.322909948963;4374.03771170342;4270.380794784739;4275.615194632498;4277.978610106929;4312.8490502832565;4338.089001309729;4286.87139385819;4303.91392172158;4305.397244385788;4333.458317710286;4350.390945267665;4428.269335559628;4555.385023021883;4677.391939047902;4688.015516741489;4720.362751028636;5053.418856831475;5082.695038297714;5095.719735414551;5099.617528685363;5102.69383836971;5099.668521546125;5125.666831655931;5135.794568217267;5155.871103222449;5158.174692049254;4558.339040839093;4678.625709943196;4749.24012158053;4760;4812.230043193073;5238.685678859204;5270.7101109439345;5272.578193744491;5274.393444753902;5275.683458992473;4798.832040878552;5010.048090862797;5051.898962020739;5054.418741844662;5067.315912351859;4904.828331008398;4973.925391119136;4980.90038199234;4987.077390682663;4996.4254110777;4931.852044438672;4944.90110197794;4963.975180124148;4967.379077615298;5011.874703132451;4795.064962332312;4850.223964165716;4939.456659767423;4958.157113290677;5011.899762004739;4284.228838808982;4328.307470804656;4332.935005974913;4344.283193273138;4348.717333373321;3726.9068273293383;3741.56971948224;3762.830672619739;3775.962240377561;3776.7167462440425;4084.305668036441;4210.0948296116485;4296.978515107405;4302.935455968163;4332.610065094157;5076.6192535559985;5095.745212739348;5099.949000509948;5104.132297509433;5150.896982060338;4284.357346398003;4311.267084595626;4312.935305970413;4356.4336636237085;4358.3244597087505;4344.847930322424;4442.355858400514;4461.063176732858;4559.179347717442;4566.063956888896;4707.129181101507;4724.929126063181;4725.503822098768;4767.904641907143;4775.2359622460235;5012.724300163381;5017.297578339108;5022.246663000579;5022.246663000579;5064.797408103635;4561.2017896867255;4619.884502887455;4663.370444990019;4713.128071306819;4756.262779269219;4840;4861.683990540603;4862.878428039327;4915.287283343967;4926.113299606105;4932.679375840559;4952.925706114339;4963.900721985612;4968.577670898014;4968.975155124202;4622.884427889329;4706.976465117653;4753.83361582343;4757.167495688172;4770.045990801784;4756.310335001474;4763.761811909321;4770.642201834946;4777.928331075106;4819.542143496362;4626.444826620827;4631.930521042254;4644.512326205835;4680.578747912704;4705.647076469347;4607.2398054320665;4695.976520117378;4792.7603619819565;4808.9519104808505;4828;4686.789094490688;4704.341392205026;4706.694064885908;4712.151812673752;4760.166970780169 09:04:03 INFO - PROCESS | 25257 | |1;ext.html;570.0793218951394;578.4158000419575;582.8047604052642;592.718458732102;596.5823923253723;26805.73194268059;40156.19687606246;41144.79427602865;41852.790736046314;43107.568924310784;41713;42059;42060.36909446353;42137;42160.57839421605;7366.74216402426;7801.531908085519;7918.4457088003865;7934.166912474194;7937.960310198469;9941.552630131662;10191.592336306576;10192.59229630814;10546.472676366151;10550.630727924525;10262.179025677966;10368.118709909666;10373.792524149512;10375.584976600965;10376.22178336624;10242.334248273875;10297.124744396735;10297.897021029768;10307.020833020888;10315.381077135396;10060.195184385231;10107.292489525737;10128.493575321278;10132.696019119438;10136.442495662735;39160.80419597884;39361.60638393636;39414.40878386827;39499.605003950164;39515;3171.8255495947715;3174.1112488503254;3191.569138166341;3211.74306055515;3527.3650742866275;30231.395372092655;30443;30986.380272394425;31024.224394390094;31465;3492.196794737199;3506.4564992426212;3528.5060091587197;3535.4343305071066;3589.120665436985;255274;255308;257082;257089;257650;53220;53633.73183134059;53716;53744.462555374725;53768.731156343965;54754.45245547573;55109;55285.44714552803;55393.72303138459;55401;14147.22190279524;14729.779053314209;14734.263286835616;14735.189564574051;14746.262686865613;10399.63601273967;10729.35623862555;10857.891421085846;11019.889801101886;11443.54225830974;15858;15875.285612147482;15898.205089745697;15899.251082380928;15981.840181598267;5963.671998040063;5983.940160598426;6021.126936594257;6038.245219347582;6179.578696899738;717.8836908607062;780.7111368793583;840.5397253907356;902.7788191893028;958.6884262614539;28058.71941280561;28931.855340723163;29011.854940725163;29485;29533.26166845846;939.769756409684;950.8193443245899;979.2655508368723;1009.3035805294323;1014.974625634365;14949.70100597982;15333.156676382683;16196.676066478605;16377.099259540839;16637.91681041587;378789;380034;381323;382659;382879;49163;49340.25989610159;49374;49432.50567494351;49551.256731149064;1086.1365214654368;1175.976480470403;1196.7008247938015;1201.885820847018;1203.4283715235367;27473.587896181973;28648.283792905346;29010;29155.271118221364;29472.705272947853;40665.39001915033;41843.16313673648;41881.1623767529;41932.161356772085;41947.58052419437;254504;255612;256323;256763;257151;21744.89127554352;24841.25476235716;25854.87072564625;26336;26464.603030954935;41200.587994119676;41563;42146.57853421426;42590.148197036506;42640;35037.47443788294;36103.27793444169;36285.0928726784;36806;36979.260414792094;35526.28947421089;35837.283254334245;36436;36766.816165919;37045.62954370422;35813.46279805857;35851.64148358483;35916.4612530807;36000;36441.27117457689;30956.0713178605;31662;31799.68200317967;31962.68037319597;31975.520367194047;25188;25306.240812775646;25368;25418.916495646485;25555.87222063878 09:04:03 INFO - PROCESS | 25257 | |2;jquery.html;343.46591050915816;364.6244368300651;367.3809630772148;371.77507607897223;391.3581725969409;13044.413001414936;16336.101514416718;16695.248713807876;16961.067141307223;17745;16540.586485337873;17127.22927468264;17878.821211787912;17898.64202715945;17900.104994750276;5012.448630650634;5254.21186821976;5547.611667183299;5553.750081246333;5628.549716022709;3463.7748546344533;5589.580781441408;5680.971595142039;5994.8801023979495;6002.579819412643;6044.697765111749;6061.12113743507;6069;6069.726862291185;6072;5414.296141501599;5563.833085007456;5569.10894256921;5574.024545704486;5579.107342825148;3418.9829050854837;4784.42586889572;5100.285959965608;5106.336176297094;5390.595705322077;13022.348882555929;13143.934280328536;13240.602781916557;15433.768493472608;21372.679409808865;1936.5255512399413;2048.5902819436023;2232.48652809853;2486.3659766759542;2496.875156242199;9403.529823508798;9429.952850235773;9561.091696288911;9840.606375744961;9851.704448866545;1727.6717423689583;1809.6471188118192;1855.1002763659615;1892.4038927737718;1930.4401723500168;61352.69323653353;65061.674691626715;68859;72469.27530724677;73960.26039739588;8488.915110848813;9226.769330766785;9514.143727064597;9674.613015479303;10312.226583006304;13030.869691303154;13432.395542200624;13494.190348579117;13495.59513214605;13505.527306544223;7047.400970917427;7191.17301510333;7193.604351760599;7314.487985841047;7325.157606875171;6624.90062649051;6635.4359879410795;6696.363845434676;6819.7272109116075;6840.555363901331;4313.568643135662;4331.6534677225745;4357.302831546937;4367.497737760134;4412.889677758018;1765.920533575992;1829.752983347231;1844.1609067874085;1875.2780179630668;1883.5761953560338;784.9646765895549;830.5099991005336;853.4751128056325;858.411987788367;882.6513527156866;16632.168391580373;17084.18685985511;17317.220725067404;17725.911370443064;17847.643047138983;819.7663665855202;839.9580020999048;840.3445312656138;846.7629063862129;852.4075767341762;9375.48434836077;9454.14912657864;9688.273379496566;9768.658096966572;9864;406600;410122.9877012335;411103;420131;420285;81036;81762.59118704368;81786;83617.58191209006;85325.14674853296;862.6635612111281;876.9517676527724;880.3925291548811;883.8099808541194;939.6570251858022;12272.509099635914;15767.132807695229;15843.207839608203;16178.838211617733;17000.70490483769;36641.45037824381;38959.41560876646;39813.40279895746;39961.600383995785;40241.1951760969;44739;57083;57395;57713;58015.419845801;5652.208690783212;6373.93626063752;6894.965525172342;7221.31397517235;7422.777316680508;8956.238719708897;11296.209265351676;12124.878751212374;12155.574554890536;12470.5011799527;10527.105196058421;10753.1935104869;10817.729556761144;10874.456277186267;10984.340939543654;10421.218408619232;10584.89415105839;10596.15230781551;10851.348919064882;11006.449677515931;10294.330868493524;10610.151187905103;10651.254412191072;10938.671839844817;11262.662120136409;7897.447178697436;7991.24083212094;8048.356131509582;8125.634346454542;8165.836683266421;4972.229304457815;5068.113080211022;9489.288303377412;9882.555285012335;10697.679069627924 09:04:03 INFO - PROCESS | 25257 | |3;mootools.html;1352.337354696199;1374.0450386981047;1451.7314296855077;1667.624784423504;1690.611159433331;7137.643117844106;7508.09902811662;7521.210272921347;7581.241875812423;7590.051243594551;6208.2239720034995;6367.7771278005275;6384.138141350916;6492.448141907944;7509.474336796413;2090.4251330883976;2176.3906106290183;2181.825453963687;2188.73735151782;2190.8575942563684;2186.7922547357975;2188.73735151782;2198.263581700124;2199.8790066546335;2208.182972300243;1840.7330937014126;2079.6880467929855;2153.364757396572;2181.901814418355;2195.659672750718;2219.622664147099;2236.966445503319;2241.551689662058;2241.641337386027;2244.51742875281;2105.421009222476;2106.3470324199548;2154.138344662132;2154.246013895124;2157.611629906616;7448.143463501712;7549.0563679540055;7557.584332861746;7558.09302883652;7559.470837041355;4408.757518336491;4524;4536.705114167602;4538.863834084983;4541.364209010741;2655.137080448862;2792.343799207205;2831.8017738758294;2877.8992735254164;2909.2435966648823;872.5244741615771;875.1991927386434;896.551724137931;903.8237543678986;909.7043460875242;10011.699649010541;10017;10018.148457381132;10021.248406369552;10033.046860548237;4000.879973600797;4029.2545879012478;4154.688398370074;4155.729877557949;4239.385289133058;8434.873476897852;8493.787655308543;8495.490270583785;8504.872426913602;8510.319174466027;4491.326301054803;4495.280755079171;4498.460184777782;4516.841910533116;4524.389207457017;3531.611522732497;3738.27103714773;4146.834126634901;4190.769507677046;4201.642860356843;3389.9491507627404;3488.1454043758936;3534.575850897909;3536.3457760314423;3546.3261980224192;1136.7158210447387;1156.595191682921;1167.8890505401973;1177.8468799056238;1182.2433642468827;758.203885919782;772.9593228916115;797.960101994898;857.8713193020972;868.1362044765499;5079.771410286546;5187.870303242374;5277.472252774692;5347.3583170019065;5388.703621300788;865.4634126841273;875.5403413208116;878.8462019146624;895.583553647557;907.3013779389833;3457.8761902381325;3527.276908233806;3762.247550489913;3791.109089363997;3795.2030073684837;8866.689665861664;9187.08129187076;9324.533773311307;9334.299927505328;9341.906580934103;9010.594523246338;9329.673461428818;9339.813203735886;9345.439273643604;9346.11211934865;6995.860082798417;7038.3665470107935;7142.178649455275;7158.06945097134;7163.283671632795;5037.760728841963;5054.09026375256;5105.897882042264;5199.324087868553;5238.292830467838;8362.581870906308;8589.312854971713;8648.048714641258;8683.478991260545;8701.17338852808;8662.610182541926;8694.17405346491;8868.911310886808;8869.73390798277;8879.33404994615;6127.785527506516;6186.783462578788;6242.313345531897;6251.499880009497;6255.093011513396;7054.506184567236;7104.467164962752;7151.141862976268;7176.533525320838;7181.533200341961;6631.171103612049;6650.700718467778;6720.932790672031;6761.121054262914;6778.694958726771;6291.465225455704;6492.48060155177;6498.480121590356;6522.804315870531;6644.568103073285;5922.230110085834;5971.611845230046;6003.429674180946;6150.876982460415;6160.383961603804;4476.664250181315;4533.025399539115;4581.358609794698;4589.724616523019;4701.177293973609;4537.251353526631;4614.400127983341;4616.192166370939;4659.2312268475325;4779.259214821708 09:04:03 INFO - PROCESS | 25257 | |4;prototype.html;304.22574547775895;307.24571177758605;315.4873330837389;315.7789547316878;318.4029943855271;4744.14605371033;4792.448868380133;4798.83204087857;4817.518248175181;4819.096456899522;4769.236922092466;4794.760261986904;4801.423829140508;4826.7586620669;4842.97578512107;3872.9612703872954;3879.72841901067;3886.300465916134;3889.436031775391;3898.4542164097047;3879.398693202544;3892.9026774330728;3894.6105389460968;3898.7660740355527;3901.804909754515;3761.9435708464393;3832.945939866545;3841.116543195053;3843.615638436148;3874.128321127752;3809.5428548574073;3811.4092315691114;3819.7135214858995;3832.6358995895343;3833.9616603833883;3409.8295085245886;3414.0611331883683;3414.3781703690547;3420.811855347955;3423.4522476403904;4657.138429390574;4742.857714268543;4745.311929770164;4751.667383283171;4760.833370832004;4372.89067773305;4376.518582955872;4384.517703052662;4397.340398940152;4417.911641767147;4980.501949805027;5026.522480364396;5035.974820125913;5069.188929771219;5074.619403544749;3711.9443208351895;3719.776813391205;3737.364648009845;3744.3821769408014;3745.5130832991535;4968.354113965198;5061;5107.770150343206;5244.711540865251;5319.654222475527;3602.603713591503;3610.8194590270905;3611.8013509257244;3612.2414292998765;3709.517762690833;3776.2258736958856;3785.6782173515007;3787.6895093992644;3788.6171547385006;3791.7156213284115;4292.59220374064;4297.140571885635;4304.741715497017;4311.159323931866;4315.503717072514;4791.353167322367;4854;4919.188333924933;4947.158982972904;4960.479149689303;1298.9283840831351;1317.5388613985028;1319.4788058716754;1333.7265860498574;1335.4457899971596;826.6817275348914;865.0959747064333;866.1857853617579;867.9671191282354;878.6573236437795;3962.841486340514;3971.5631280559114;3982.78094704794;3993.660538854172;3999.6600288975183;4560.498345182027;4599.862004139881;4601.3558101865765;4607.493175750665;4618.445786505574;3958.1687845552756;3965.7422267552515;3978;4010.598940106024;4017.357222844331;2318.36245032614;2441.718097998565;2463.942607922405;2464.876756162185;2477.2692055843568;4367.432233809584;4381.627561657231;4397.252467080604;4405.361222622766;4436.157130145262;4714.57568818808;4726.480087190406;4747.83382581608;4756.858353995016;4767.809287628456;4366.3013917773005;4368.628666563314;4544.295634176708;4906.786055133893;4979.377577802775;5178.766955487087;5203.219517072469;5214.452482489284;5218.791248350023;5225.817096401608;5133.845984620468;5136.768845402041;5180.456052114474;5181.430042695225;5185.533302002837;4692.882677933079;4708;4790.7365094920115;4943.110240156806;4945.4312754033745;3670.5778835434267;3721.9255614888093;3734.9626503735703;3742.326381251401;3742.9812850935573;5226.686398816083;5230.607704422107;5236.869078272921;5240.633155679066;5245.423003469692;4865.270209468608;4935.481774413634;4951.653384263067;4954.9752251238515;4991.401031876198;4866.48901865299;4885.316055752127;4887.36273348418;4897.314375987294;4930.383702037246;4739.1706451371565;4750.501197374226;4772.093302272557;4825.324454576432;4844.588210002048;4758.785854636619;4932.766808297925;4955.380577427822;4964.230544265644;4974.925376119433;4775.379200703886;4795.736234507136;4799.66402351832;4800.903981920412;4815.6388270880525 09:04:03 INFO - PROCESS | 25257 | |5;yui.html;665.3878431842704;678.2369833936821;710.1584622222666;725.6589402980599;741.1883987034203;12838.743225135491;13845.515406960758;13852.515161969333;13853.445862165514;13856.237906915116;13411.530596429127;13470.595882123553;13498;13511.729765404685;13566;636.2746469025315;637.155768606597;760.9086909570832;764.8087978005499;793.928546430824;779.0729032451364;781.4920301803816;785.5522352259214;785.7524879662888;785.8978332816725;665.8302132956089;832.1927730101792;834.7203686764941;835.2274146414572;835.5488036460304;682.6825526130349;682.8839097353462;685.0956737106995;806.6652339279192;835.064727505192;609.4606273447986;610.1092405088576;610.1275176497606;610.1671218786369;610.3164455809477;13722.45110195591;13828.516001939885;13857.514986975508;13862.792058119136;13886.4445422183;12631.242125472409;12762.298073605947;13041.739165216643;13087.541936032194;13437.899106002582;11432.085433165326;11434.113371293726;11484.196106272564;11556.653300401002;11710.238834475816;4138;4175.206710724983;4175.853845115436;4182.790860456996;4185.874423767262;61262.38737612611;61616.38383616151;61719.691401543154;61767.6911615439;62466.68766656183;9826.213902887896;9900.900990989998;10145.28982971185;10304.175665946706;10530.57877684884;19532.218711251677;19540.51148721265;19555.12001959915;19658;19668.70496942547;6767.492438067165;6839.1451068616425;6872.209695884937;6873.450123990069;7048.295170482911;3625.093726568358;3661.707063434919;3706.4810926470313;3755.661990420875;3763.360228761117;2810.437912417525;2840.1479556133077;2888.8555572221303;2908.75275601572;2914.0383673387732;479.4677907522647;481.48481125196406;486.0303694130162;501.44840675257655;502.3519659639098;528.5612941258823;590.6397097770357;608.0301918440081;622.6295354264206;631.4380201620561;8798.252148567315;8843.646254149891;8919.241864441465;9034.954825225832;9049.819003620023;626.7179769103922;628.9654069026155;639.7600899662626;642.2678146912524;648.6821457485798;3821.3503704370323;3937.350337194388;3954.387070004154;3990.7605543667473;4150.937735933963;95876.52061739647;97216;97654;98595.50702246587;99196;49561.75219123881;54208.45791542113;83803.5809820947;84562.26954323378;85208;70847.29152708407;73564.26435735574;75117.6244118776;76642.23357766352;77155.44178744758;7531;7560.508566943131;7562.168161502121;7668.386529077548;7906.881396778937;43741.34387984119;44024.55975440205;44303.33544996892;44620.77689611531;45006.77496612496;57702.422975771384;63511;63876.3612363889;63893.36106638874;63985;8493.532855692996;10407.739806504898;10455;10542.8418573723;10613.787724245627;10225.84661230097;10255.435951022766;10354.792904141725;10362.689119326433;11089.722756930818;9173.44959302444;9220.354575179674;9394.812103758024;9412.247020238501;9730.902690973282;12368;12640.367981601066;13085;13314.667133321744;13743.862561374259;12170.513179473077;12600.873991260336;13264.270465124511;13268.734625307632;13637.931810341282;9989.250806189419;10410.219233557362;11012.96260191867;11338.14963877729;11475.942620286845;10040.347377420445;11048.226624136234;11058.447077646246;11176.664700059011;11356.205065645325 09:04:03 INFO - PROCESS | 25257 | __end_tp_report 09:04:03 INFO - PROCESS | 25257 | __start_cc_report 09:04:03 INFO - PROCESS | 25257 | _x_x_mozilla_cycle_collect,1019 09:04:03 INFO - PROCESS | 25257 | __end_cc_report 09:04:03 INFO - PROCESS | 25257 | __startTimestamp1468339443188__endTimestamp 09:04:03 INFO - PROCESS | 25257 | 09:04:03 INFO - PROCESS | 25257 | ------- Summary: start ------- 09:04:03 INFO - PROCESS | 25257 | Number of tests: 6 09:04:03 INFO - PROCESS | 25257 | 09:04:03 INFO - PROCESS | 25257 | [#0] dojo.html Cycles:165 Average:4618.20 Median:4712.15 stddev:481.80 (10.2%) stddev-sans-first:456.79 09:04:03 INFO - PROCESS | 25257 | Values: 2609.7 2616.7 2650.1 2662.2 2680.9 4558.8 4637.4 4734.1 4767.8 4791.4 4653.8 4681.1 4681.4 4716.3 4730.7 4082.2 4270.0 4343.6 4371.3 4463.1 4359.1 4396.5 4413.3 4417.6 4448.8 4118.8 4162.7 4304.4 4368.3 4374.0 4270.4 4275.6 4278.0 4312.8 4338.1 4286.9 4303.9 4305.4 4333.5 4350.4 4428.3 4555.4 4677.4 4688.0 4720.4 5053.4 5082.7 5095.7 5099.6 5102.7 5099.7 5125.7 5135.8 5155.9 5158.2 4558.3 4678.6 4749.2 4760.0 4812.2 5238.7 5270.7 5272.6 5274.4 5275.7 4798.8 5010.0 5051.9 5054.4 5067.3 4904.8 4973.9 4980.9 4987.1 4996.4 4931.9 4944.9 4964.0 4967.4 5011.9 4795.1 4850.2 4939.5 4958.2 5011.9 4284.2 4328.3 4332.9 4344.3 4348.7 3726.9 3741.6 3762.8 3776.0 3776.7 4084.3 4210.1 4297.0 4302.9 4332.6 5076.6 5095.7 5099.9 5104.1 5150.9 4284.4 4311.3 4312.9 4356.4 4358.3 4344.8 4442.4 4461.1 4559.2 4566.1 4707.1 4724.9 4725.5 4767.9 4775.2 5012.7 5017.3 5022.2 5022.2 5064.8 4561.2 4619.9 4663.4 4713.1 4756.3 4840.0 4861.7 4862.9 4915.3 4926.1 4932.7 4952.9 4963.9 4968.6 4969.0 4622.9 4707.0 4753.8 4757.2 4770.0 4756.3 4763.8 4770.6 4777.9 4819.5 4626.4 4631.9 4644.5 4680.6 4705.6 4607.2 4696.0 4792.8 4809.0 4828.0 4686.8 4704.3 4706.7 4712.2 4760.2 09:04:03 INFO - PROCESS | 25257 | 09:04:03 INFO - PROCESS | 25257 | [#1] ext.html Cycles:180 Average:46037.80 Median:3351.97 stddev:79670.68 (2376.8%) stddev-sans-first:79821.04 09:04:03 INFO - PROCESS | 25257 | Values: 570.1 578.4 582.8 592.7 596.6 26805.7 40156.2 41144.8 41852.8 43107.6 41713.0 42059.0 42060.4 42137.0 42160.6 7366.7 7801.5 7918.4 7934.2 7938.0 9941.6 10191.6 10192.6 10546.5 10550.6 10262.2 10368.1 10373.8 10375.6 10376.2 10242.3 10297.1 10297.9 10307.0 10315.4 10060.2 10107.3 10128.5 10132.7 10136.4 39160.8 39361.6 39414.4 39499.6 39515.0 3171.8 3174.1 3191.6 3211.7 3527.4 30231.4 30443.0 30986.4 31024.2 31465.0 3492.2 3506.5 3528.5 3535.4 3589.1 255274.0 255308.0 257082.0 257089.0 257650.0 53220.0 53633.7 53716.0 53744.5 53768.7 54754.5 55109.0 55285.4 55393.7 55401.0 14147.2 14729.8 14734.3 14735.2 14746.3 10399.6 10729.4 10857.9 11019.9 11443.5 15858.0 15875.3 15898.2 15899.3 15981.8 5963.7 5983.9 6021.1 6038.2 6179.6 717.9 780.7 840.5 902.8 958.7 28058.7 28931.9 29011.9 29485.0 29533.3 939.8 950.8 979.3 1009.3 1015.0 14949.7 15333.2 16196.7 16377.1 16637.9 378789.0 380034.0 381323.0 382659.0 382879.0 49163.0 49340.3 49374.0 49432.5 49551.3 1086.1 1176.0 1196.7 1201.9 1203.4 27473.6 28648.3 29010.0 29155.3 29472.7 40665.4 41843.2 41881.2 41932.2 41947.6 254504.0 255612.0 256323.0 256763.0 257151.0 21744.9 24841.3 25854.9 26336.0 26464.6 41200.6 41563.0 42146.6 42590.1 42640.0 35037.5 36103.3 36285.1 36806.0 36979.3 35526.3 35837.3 36436.0 36766.8 37045.6 35813.5 35851.6 35916.5 36000.0 36441.3 30956.1 31662.0 31799.7 31962.7 31975.5 25188.0 25306.2 25368.0 25418.9 25555.9 09:04:03 INFO - PROCESS | 25257 | 09:04:03 INFO - PROCESS | 25257 | [#2] jquery.html Cycles:180 Average:25088.12 Median:4992.34 stddev:68368.72 (1369.5%) stddev-sans-first:68535.26 09:04:03 INFO - PROCESS | 25257 | Values: 343.5 364.6 367.4 371.8 391.4 13044.4 16336.1 16695.2 16961.1 17745.0 16540.6 17127.2 17878.8 17898.6 17900.1 5012.4 5254.2 5547.6 5553.8 5628.5 3463.8 5589.6 5681.0 5994.9 6002.6 6044.7 6061.1 6069.0 6069.7 6072.0 5414.3 5563.8 5569.1 5574.0 5579.1 3419.0 4784.4 5100.3 5106.3 5390.6 13022.3 13143.9 13240.6 15433.8 21372.7 1936.5 2048.6 2232.5 2486.4 2496.9 9403.5 9430.0 9561.1 9840.6 9851.7 1727.7 1809.6 1855.1 1892.4 1930.4 61352.7 65061.7 68859.0 72469.3 73960.3 8488.9 9226.8 9514.1 9674.6 10312.2 13030.9 13432.4 13494.2 13495.6 13505.5 7047.4 7191.2 7193.6 7314.5 7325.2 6624.9 6635.4 6696.4 6819.7 6840.6 4313.6 4331.7 4357.3 4367.5 4412.9 1765.9 1829.8 1844.2 1875.3 1883.6 785.0 830.5 853.5 858.4 882.7 16632.2 17084.2 17317.2 17725.9 17847.6 819.8 840.0 840.3 846.8 852.4 9375.5 9454.1 9688.3 9768.7 9864.0 406600.0 410123.0 411103.0 420131.0 420285.0 81036.0 81762.6 81786.0 83617.6 85325.1 862.7 877.0 880.4 883.8 939.7 12272.5 15767.1 15843.2 16178.8 17000.7 36641.5 38959.4 39813.4 39961.6 40241.2 44739.0 57083.0 57395.0 57713.0 58015.4 5652.2 6373.9 6895.0 7221.3 7422.8 8956.2 11296.2 12124.9 12155.6 12470.5 10527.1 10753.2 10817.7 10874.5 10984.3 10421.2 10584.9 10596.2 10851.3 11006.4 10294.3 10610.2 10651.3 10938.7 11262.7 7897.4 7991.2 8048.4 8125.6 8165.8 4972.2 5068.1 9489.3 9882.6 10697.7 09:04:03 INFO - PROCESS | 25257 | 09:04:03 INFO - PROCESS | 25257 | [#3] mootools.html Cycles:180 Average:4956.17 Median:5218.81 stddev:2709.99 (51.9%) stddev-sans-first:2704.06 09:04:03 INFO - PROCESS | 25257 | Values: 1352.3 1374.0 1451.7 1667.6 1690.6 7137.6 7508.1 7521.2 7581.2 7590.1 6208.2 6367.8 6384.1 6492.4 7509.5 2090.4 2176.4 2181.8 2188.7 2190.9 2186.8 2188.7 2198.3 2199.9 2208.2 1840.7 2079.7 2153.4 2181.9 2195.7 2219.6 2237.0 2241.6 2241.6 2244.5 2105.4 2106.3 2154.1 2154.2 2157.6 7448.1 7549.1 7557.6 7558.1 7559.5 4408.8 4524.0 4536.7 4538.9 4541.4 2655.1 2792.3 2831.8 2877.9 2909.2 872.5 875.2 896.6 903.8 909.7 10011.7 10017.0 10018.1 10021.2 10033.0 4000.9 4029.3 4154.7 4155.7 4239.4 8434.9 8493.8 8495.5 8504.9 8510.3 4491.3 4495.3 4498.5 4516.8 4524.4 3531.6 3738.3 4146.8 4190.8 4201.6 3389.9 3488.1 3534.6 3536.3 3546.3 1136.7 1156.6 1167.9 1177.8 1182.2 758.2 773.0 798.0 857.9 868.1 5079.8 5187.9 5277.5 5347.4 5388.7 865.5 875.5 878.8 895.6 907.3 3457.9 3527.3 3762.2 3791.1 3795.2 8866.7 9187.1 9324.5 9334.3 9341.9 9010.6 9329.7 9339.8 9345.4 9346.1 6995.9 7038.4 7142.2 7158.1 7163.3 5037.8 5054.1 5105.9 5199.3 5238.3 8362.6 8589.3 8648.0 8683.5 8701.2 8662.6 8694.2 8868.9 8869.7 8879.3 6127.8 6186.8 6242.3 6251.5 6255.1 7054.5 7104.5 7151.1 7176.5 7181.5 6631.2 6650.7 6720.9 6761.1 6778.7 6291.5 6492.5 6498.5 6522.8 6644.6 5922.2 5971.6 6003.4 6150.9 6160.4 4476.7 4533.0 4581.4 4589.7 4701.2 4537.3 4614.4 4616.2 4659.2 4779.3 09:04:03 INFO - PROCESS | 25257 | 09:04:03 INFO - PROCESS | 25257 | [#4] prototype.html Cycles:180 Average:4106.69 Median:4604.42 stddev:1170.52 (25.4%) stddev-sans-first:1138.48 09:04:03 INFO - PROCESS | 25257 | Values: 304.2 307.2 315.5 315.8 318.4 4744.1 4792.4 4798.8 4817.5 4819.1 4769.2 4794.8 4801.4 4826.8 4843.0 3873.0 3879.7 3886.3 3889.4 3898.5 3879.4 3892.9 3894.6 3898.8 3901.8 3761.9 3832.9 3841.1 3843.6 3874.1 3809.5 3811.4 3819.7 3832.6 3834.0 3409.8 3414.1 3414.4 3420.8 3423.5 4657.1 4742.9 4745.3 4751.7 4760.8 4372.9 4376.5 4384.5 4397.3 4417.9 4980.5 5026.5 5036.0 5069.2 5074.6 3711.9 3719.8 3737.4 3744.4 3745.5 4968.4 5061.0 5107.8 5244.7 5319.7 3602.6 3610.8 3611.8 3612.2 3709.5 3776.2 3785.7 3787.7 3788.6 3791.7 4292.6 4297.1 4304.7 4311.2 4315.5 4791.4 4854.0 4919.2 4947.2 4960.5 1298.9 1317.5 1319.5 1333.7 1335.4 826.7 865.1 866.2 868.0 878.7 3962.8 3971.6 3982.8 3993.7 3999.7 4560.5 4599.9 4601.4 4607.5 4618.4 3958.2 3965.7 3978.0 4010.6 4017.4 2318.4 2441.7 2463.9 2464.9 2477.3 4367.4 4381.6 4397.3 4405.4 4436.2 4714.6 4726.5 4747.8 4756.9 4767.8 4366.3 4368.6 4544.3 4906.8 4979.4 5178.8 5203.2 5214.5 5218.8 5225.8 5133.8 5136.8 5180.5 5181.4 5185.5 4692.9 4708.0 4790.7 4943.1 4945.4 3670.6 3721.9 3735.0 3742.3 3743.0 5226.7 5230.6 5236.9 5240.6 5245.4 4865.3 4935.5 4951.7 4955.0 4991.4 4866.5 4885.3 4887.4 4897.3 4930.4 4739.2 4750.5 4772.1 4825.3 4844.6 4758.8 4932.8 4955.4 4964.2 4974.9 4775.4 4795.7 4799.7 4800.9 4815.6 09:04:03 INFO - PROCESS | 25257 | 09:04:03 INFO - PROCESS | 25257 | [#5] yui.html Cycles:180 Average:17493.34 Median:501.90 stddev:24593.37 (4900.1%) stddev-sans-first:24629.90 09:04:03 INFO - PROCESS | 25257 | Values: 665.4 678.2 710.2 725.7 741.2 12838.7 13845.5 13852.5 13853.4 13856.2 13411.5 13470.6 13498.0 13511.7 13566.0 636.3 637.2 760.9 764.8 793.9 779.1 781.5 785.6 785.8 785.9 665.8 832.2 834.7 835.2 835.5 682.7 682.9 685.1 806.7 835.1 609.5 610.1 610.1 610.2 610.3 13722.5 13828.5 13857.5 13862.8 13886.4 12631.2 12762.3 13041.7 13087.5 13437.9 11432.1 11434.1 11484.2 11556.7 11710.2 4138.0 4175.2 4175.9 4182.8 4185.9 61262.4 61616.4 61719.7 61767.7 62466.7 9826.2 9900.9 10145.3 10304.2 10530.6 19532.2 19540.5 19555.1 19658.0 19668.7 6767.5 6839.1 6872.2 6873.5 7048.3 3625.1 3661.7 3706.5 3755.7 3763.4 2810.4 2840.1 2888.9 2908.8 2914.0 479.5 481.5 486.0 501.4 502.4 528.6 590.6 608.0 622.6 631.4 8798.3 8843.6 8919.2 9035.0 9049.8 626.7 629.0 639.8 642.3 648.7 3821.4 3937.4 3954.4 3990.8 4150.9 95876.5 97216.0 97654.0 98595.5 99196.0 49561.8 54208.5 83803.6 84562.3 85208.0 70847.3 73564.3 75117.6 76642.2 77155.4 7531.0 7560.5 7562.2 7668.4 7906.9 43741.3 44024.6 44303.3 44620.8 45006.8 57702.4 63511.0 63876.4 63893.4 63985.0 8493.5 10407.7 10455.0 10542.8 10613.8 10225.8 10255.4 10354.8 10362.7 11089.7 9173.4 9220.4 9394.8 9412.2 9730.9 12368.0 12640.4 13085.0 13314.7 13743.9 12170.5 12600.9 13264.3 13268.7 13637.9 9989.3 10410.2 11013.0 11338.1 11475.9 10040.3 11048.2 11058.4 11176.7 11356.2 09:04:03 INFO - PROCESS | 25257 | -------- Summary: end -------- 09:04:03 INFO - PROCESS | 25257 | 09:04:03 INFO - TEST-INFO | 25257: exit 0 09:04:03 INFO - TEST-OK | dromaeo_css | took 1119070ms 09:04:03 INFO - TEST-START | kraken 09:04:03 INFO - operating with platform_type : linux_ 09:04:03 INFO - Initialising browser for kraken test... 09:04:04 INFO - TEST-INFO | started process 25444 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFCIxro/profile http://localhost:50183/getInfo.html) 09:04:08 INFO - PROCESS | 25444 | __metrics Screen width/height:1600/1200 09:04:08 INFO - PROCESS | 25444 | colorDepth:24 09:04:08 INFO - PROCESS | 25444 | Browser inner width/height: 1024/697 09:04:08 INFO - PROCESS | 25444 | __metrics 09:04:09 INFO - PROCESS | 25444 | 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" 09:04:09 INFO - PROCESS | 25444 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:04:10 INFO - TEST-INFO | 25444: exit 0 09:04:10 INFO - Browser initialized. 09:04:10 INFO - Running cycle 1/1 for kraken test... 09:04:10 INFO - TEST-INFO | started process 25529 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFCIxro/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:22 INFO - PROCESS | 25529 | RSS: Main: 158724096 09:04:22 INFO - PROCESS | 25529 | 09:04:24 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50183/tests/kraken/driver.html?testName=audio-beat-detection) 09:04:25 INFO - PROCESS | 25529 | RSS: Main: 216834048 09:04:25 INFO - PROCESS | 25529 | 09:04:28 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50183/tests/kraken/driver.html?testName=audio-dft) 09:04:29 INFO - PROCESS | 25529 | RSS: Main: 203907072 09:04:29 INFO - PROCESS | 25529 | 09:04:32 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50183/tests/kraken/driver.html?testName=audio-fft) 09:04:32 INFO - PROCESS | 25529 | RSS: Main: 271613952 09:04:32 INFO - PROCESS | 25529 | 09:04:35 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50183/tests/kraken/driver.html?testName=audio-oscillator) 09:04:35 INFO - PROCESS | 25529 | RSS: Main: 181846016 09:04:35 INFO - PROCESS | 25529 | 09:04:37 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50183/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:04:38 INFO - PROCESS | 25529 | RSS: Main: 186216448 09:04:38 INFO - PROCESS | 25529 | 09:04:41 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50183/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:42 INFO - PROCESS | 25529 | RSS: Main: 221802496 09:04:42 INFO - PROCESS | 25529 | 09:04:45 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50183/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:46 INFO - PROCESS | 25529 | RSS: Main: 217739264 09:04:46 INFO - PROCESS | 25529 | 09:04:49 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50183/tests/kraken/driver.html?testName=json-parse-financial) 09:04:49 INFO - PROCESS | 25529 | RSS: Main: 221249536 09:04:49 INFO - PROCESS | 25529 | 09:04:51 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50183/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:04:52 INFO - PROCESS | 25529 | RSS: Main: 207122432 09:04:52 INFO - PROCESS | 25529 | 09:04:54 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50183/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:04:54 INFO - PROCESS | 25529 | RSS: Main: 212267008 09:04:54 INFO - PROCESS | 25529 | 09:04:56 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50183/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:04:57 INFO - PROCESS | 25529 | RSS: Main: 209317888 09:04:57 INFO - PROCESS | 25529 | 09:05:00 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50183/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:05:00 INFO - PROCESS | 25529 | RSS: Main: 226222080 09:05:00 INFO - PROCESS | 25529 | 09:05:03 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50183/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:05:04 INFO - PROCESS | 25529 | RSS: Main: 225550336 09:05:04 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | Cycle 1(1): loaded http://localhost:50183/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50183/tests/kraken/driver.html?testName=ai-astar) 09:05:06 INFO - PROCESS | 25529 | RSS: Main: 268021760 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | __start_tp_report 09:05:06 INFO - PROCESS | 25529 | _x_x_mozilla_page_load 09:05:06 INFO - PROCESS | 25529 | _x_x_mozilla_page_load_details 09:05:06 INFO - PROCESS | 25529 | |i|pagename|runs| 09:05:06 INFO - PROCESS | 25529 | |0;ai-astar;104;116;113;116;114;114;115;112;115;113 09:05:06 INFO - PROCESS | 25529 | |1;audio-beat-detection;149;122;124;180;128;126;152;128;183;121 09:05:06 INFO - PROCESS | 25529 | |2;audio-dft;166;161;161;160;161;161;161;163;161;159 09:05:06 INFO - PROCESS | 25529 | |3;audio-fft;75;76;78;120;75;77;76;77;102;74 09:05:06 INFO - PROCESS | 25529 | |4;audio-oscillator;86;86;122;84;84;85;117;86;85;134 09:05:06 INFO - PROCESS | 25529 | |5;imaging-gaussian-blur;117;116;116;117;117;117;116;118;116;117 09:05:06 INFO - PROCESS | 25529 | |6;imaging-darkroom;146;146;146;147;147;145;146;147;146;146 09:05:06 INFO - PROCESS | 25529 | |7;imaging-desaturate;95;95;94;95;95;94;94;95;94;95 09:05:06 INFO - PROCESS | 25529 | |8;json-parse-financial;64;61;62;62;61;63;62;62;62;67 09:05:06 INFO - PROCESS | 25529 | |9;json-stringify-tinderbox;64;64;64;65;66;64;64;64;69;64 09:05:06 INFO - PROCESS | 25529 | |10;stanford-crypto-aes;78;81;80;78;80;80;79;79;78;82 09:05:06 INFO - PROCESS | 25529 | |11;stanford-crypto-ccm;126;127;126;129;127;126;127;128;171;135 09:05:06 INFO - PROCESS | 25529 | |12;stanford-crypto-pbkdf2;175;164;168;167;169;167;172;162;164;164 09:05:06 INFO - PROCESS | 25529 | |13;stanford-crypto-sha256-iterative;71;67;68;68;66;69;69;65;65;65 09:05:06 INFO - PROCESS | 25529 | __end_tp_report 09:05:06 INFO - PROCESS | 25529 | __start_cc_report 09:05:06 INFO - PROCESS | 25529 | _x_x_mozilla_cycle_collect,1941 09:05:06 INFO - PROCESS | 25529 | __end_cc_report 09:05:06 INFO - PROCESS | 25529 | __startTimestamp1468339506261__endTimestamp 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | ------- Summary: start ------- 09:05:06 INFO - PROCESS | 25529 | Number of tests: 14 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.50 stddev:3.49 (3.0%) stddev-sans-first:1.39 09:05:06 INFO - PROCESS | 25529 | Values: 104.0 116.0 113.0 116.0 114.0 114.0 115.0 112.0 115.0 113.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:138.50 stddev:23.76 (17.2%) stddev-sans-first:25.04 09:05:06 INFO - PROCESS | 25529 | Values: 149.0 122.0 124.0 180.0 128.0 126.0 152.0 128.0 183.0 121.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.00 stddev:1.90 (1.2%) stddev-sans-first:1.05 09:05:06 INFO - PROCESS | 25529 | Values: 166.0 161.0 161.0 160.0 161.0 161.0 161.0 163.0 161.0 159.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#3] audio-fft Cycles:10 Average:83.00 Median:76.00 stddev:15.40 (20.3%) stddev-sans-first:16.06 09:05:06 INFO - PROCESS | 25529 | Values: 75.0 76.0 78.0 120.0 75.0 77.0 76.0 77.0 102.0 74.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:85.00 stddev:19.39 (22.8%) stddev-sans-first:20.16 09:05:06 INFO - PROCESS | 25529 | Values: 86.0 86.0 122.0 84.0 84.0 85.0 117.0 86.0 85.0 134.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 09:05:06 INFO - PROCESS | 25529 | Values: 117.0 116.0 116.0 117.0 117.0 117.0 116.0 118.0 116.0 117.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 09:05:06 INFO - PROCESS | 25529 | Values: 146.0 146.0 146.0 147.0 147.0 145.0 146.0 147.0 146.0 146.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:05:06 INFO - PROCESS | 25529 | Values: 95.0 95.0 94.0 95.0 95.0 94.0 94.0 95.0 94.0 95.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:62.00 stddev:1.78 (2.9%) stddev-sans-first:1.81 09:05:06 INFO - PROCESS | 25529 | Values: 64.0 61.0 62.0 62.0 61.0 63.0 62.0 62.0 62.0 67.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:64.00 stddev:1.62 (2.5%) stddev-sans-first:1.69 09:05:06 INFO - PROCESS | 25529 | Values: 64.0 64.0 64.0 65.0 66.0 64.0 64.0 64.0 69.0 64.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:80.00 stddev:1.35 (1.7%) stddev-sans-first:1.32 09:05:06 INFO - PROCESS | 25529 | Values: 78.0 81.0 80.0 78.0 80.0 80.0 79.0 79.0 78.0 82.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#11] stanford-crypto-ccm Cycles:10 Average:132.20 Median:127.50 stddev:13.89 (10.9%) stddev-sans-first:14.56 09:05:06 INFO - PROCESS | 25529 | Values: 126.0 127.0 126.0 129.0 127.0 126.0 127.0 128.0 171.0 135.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:167.50 stddev:4.02 (2.4%) stddev-sans-first:3.12 09:05:06 INFO - PROCESS | 25529 | Values: 175.0 164.0 168.0 167.0 169.0 167.0 172.0 162.0 164.0 164.0 09:05:06 INFO - PROCESS | 25529 | 09:05:06 INFO - PROCESS | 25529 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:2.06 (3.0%) stddev-sans-first:1.69 09:05:06 INFO - PROCESS | 25529 | Values: 71.0 67.0 68.0 68.0 66.0 69.0 69.0 65.0 65.0 65.0 09:05:06 INFO - PROCESS | 25529 | -------- Summary: end -------- 09:05:06 INFO - PROCESS | 25529 | 09:05:07 INFO - TEST-INFO | 25529: exit 0 09:05:07 INFO - TEST-OK | kraken | took 63341ms 09:05:07 INFO - SUITE-END | took 1182s 09:05:07 INFO - Completed test suite (00:19:42) 09:05:07 INFO - javascript benchmark 09:05:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2609.660744103266, 2616.725243849396, 2650.1387049209, 2662.1880326500414, 2680.8525531095793, 4558.794854231559, 4637.420322459692, 4734.053189362128, 4767.833125840596, 4791.425028996517, 4653.790579423926, 4681.133990211813, 4681.414823147106, 4716.292556116584, 4730.692504987173, 4082.1835632873394, 4270.017895883949, 4343.630791382736, 4371.27873900806, 4463.062756821071, 4359.084592235635, 4396.450443694538, 4413.3379993001145, 4417.558244175573, 4448.755318457484, 4118.835246590148, 4162.708610397273, 4304.375865499516, 4368.322909948963, 4374.03771170342, 4270.380794784739, 4275.615194632498, 4277.978610106929, 4312.8490502832565, 4338.089001309729, 4286.87139385819, 4303.91392172158, 4305.397244385788, 4333.458317710286, 4350.390945267665, 4428.269335559628, 4555.385023021883, 4677.391939047902, 4688.015516741489, 4720.362751028636, 5053.418856831475, 5082.695038297714, 5095.719735414551, 5099.617528685363, 5102.69383836971, 5099.668521546125, 5125.666831655931, 5135.794568217267, 5155.871103222449, 5158.174692049254, 4558.339040839093, 4678.625709943196, 4749.24012158053, 4760.0, 4812.230043193073, 5238.685678859204, 5270.7101109439345, 5272.578193744491, 5274.393444753902, 5275.683458992473, 4798.832040878552, 5010.048090862797, 5051.898962020739, 5054.418741844662, 5067.315912351859, 4904.828331008398, 4973.925391119136, 4980.90038199234, 4987.077390682663, 4996.4254110777, 4931.852044438672, 4944.90110197794, 4963.975180124148, 4967.379077615298, 5011.874703132451, 4795.064962332312, 4850.223964165716, 4939.456659767423, 4958.157113290677, 5011.899762004739, 4284.228838808982, 4328.307470804656, 4332.935005974913, 4344.283193273138, 4348.717333373321, 3726.9068273293383, 3741.56971948224, 3762.830672619739, 3775.962240377561, 3776.7167462440425, 4084.305668036441, 4210.0948296116485, 4296.978515107405, 4302.935455968163, 4332.610065094157, 5076.6192535559985, 5095.745212739348, 5099.949000509948, 5104.132297509433, 5150.896982060338, 4284.357346398003, 4311.267084595626, 4312.935305970413, 4356.4336636237085, 4358.3244597087505, 4344.847930322424, 4442.355858400514, 4461.063176732858, 4559.179347717442, 4566.063956888896, 4707.129181101507, 4724.929126063181, 4725.503822098768, 4767.904641907143, 4775.2359622460235, 5012.724300163381, 5017.297578339108, 5022.246663000579, 5022.246663000579, 5064.797408103635, 4561.2017896867255, 4619.884502887455, 4663.370444990019, 4713.128071306819, 4756.262779269219, 4840.0, 4861.683990540603, 4862.878428039327, 4915.287283343967, 4926.113299606105, 4932.679375840559, 4952.925706114339, 4963.900721985612, 4968.577670898014, 4968.975155124202, 4622.884427889329, 4706.976465117653, 4753.83361582343, 4757.167495688172, 4770.045990801784, 4756.310335001474, 4763.761811909321, 4770.642201834946, 4777.928331075106, 4819.542143496362, 4626.444826620827, 4631.930521042254, 4644.512326205835, 4680.578747912704, 4705.647076469347, 4607.2398054320665, 4695.976520117378, 4792.7603619819565, 4808.9519104808505, 4828.0, 4686.789094490688, 4704.341392205026, 4706.694064885908, 4712.151812673752, 4760.166970780169], "value": 4588.186436345868, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.0793218951394, 578.4158000419575, 582.8047604052642, 592.718458732102, 596.5823923253723, 26805.73194268059, 40156.19687606246, 41144.79427602865, 41852.790736046314, 43107.568924310784, 41713.0, 42059.0, 42060.36909446353, 42137.0, 42160.57839421605, 7366.74216402426, 7801.531908085519, 7918.4457088003865, 7934.166912474194, 7937.960310198469, 9941.552630131662, 10191.592336306576, 10192.59229630814, 10546.472676366151, 10550.630727924525, 10262.179025677966, 10368.118709909666, 10373.792524149512, 10375.584976600965, 10376.22178336624, 10242.334248273875, 10297.124744396735, 10297.897021029768, 10307.020833020888, 10315.381077135396, 10060.195184385231, 10107.292489525737, 10128.493575321278, 10132.696019119438, 10136.442495662735, 39160.80419597884, 39361.60638393636, 39414.40878386827, 39499.605003950164, 39515.0, 3171.8255495947715, 3174.1112488503254, 3191.569138166341, 3211.74306055515, 3527.3650742866275, 30231.395372092655, 30443.0, 30986.380272394425, 31024.224394390094, 31465.0, 3492.196794737199, 3506.4564992426212, 3528.5060091587197, 3535.4343305071066, 3589.120665436985, 255274.0, 255308.0, 257082.0, 257089.0, 257650.0, 53220.0, 53633.73183134059, 53716.0, 53744.462555374725, 53768.731156343965, 54754.45245547573, 55109.0, 55285.44714552803, 55393.72303138459, 55401.0, 14147.22190279524, 14729.779053314209, 14734.263286835616, 14735.189564574051, 14746.262686865613, 10399.63601273967, 10729.35623862555, 10857.891421085846, 11019.889801101886, 11443.54225830974, 15858.0, 15875.285612147482, 15898.205089745697, 15899.251082380928, 15981.840181598267, 5963.671998040063, 5983.940160598426, 6021.126936594257, 6038.245219347582, 6179.578696899738, 717.8836908607062, 780.7111368793583, 840.5397253907356, 902.7788191893028, 958.6884262614539, 28058.71941280561, 28931.855340723163, 29011.854940725163, 29485.0, 29533.26166845846, 939.769756409684, 950.8193443245899, 979.2655508368723, 1009.3035805294323, 1014.974625634365, 14949.70100597982, 15333.156676382683, 16196.676066478605, 16377.099259540839, 16637.91681041587, 378789.0, 380034.0, 381323.0, 382659.0, 382879.0, 49163.0, 49340.25989610159, 49374.0, 49432.50567494351, 49551.256731149064, 1086.1365214654368, 1175.976480470403, 1196.7008247938015, 1201.885820847018, 1203.4283715235367, 27473.587896181973, 28648.283792905346, 29010.0, 29155.271118221364, 29472.705272947853, 40665.39001915033, 41843.16313673648, 41881.1623767529, 41932.161356772085, 41947.58052419437, 254504.0, 255612.0, 256323.0, 256763.0, 257151.0, 21744.89127554352, 24841.25476235716, 25854.87072564625, 26336.0, 26464.603030954935, 41200.587994119676, 41563.0, 42146.57853421426, 42590.148197036506, 42640.0, 35037.47443788294, 36103.27793444169, 36285.0928726784, 36806.0, 36979.260414792094, 35526.28947421089, 35837.283254334245, 36436.0, 36766.816165919, 37045.62954370422, 35813.46279805857, 35851.64148358483, 35916.4612530807, 36000.0, 36441.27117457689, 30956.0713178605, 31662.0, 31799.68200317967, 31962.68037319597, 31975.520367194047, 25188.0, 25306.240812775646, 25368.0, 25418.916495646485, 25555.87222063878], "value": 17938.867399103496, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.46591050915816, 364.6244368300651, 367.3809630772148, 371.77507607897223, 391.3581725969409, 13044.413001414936, 16336.101514416718, 16695.248713807876, 16961.067141307223, 17745.0, 16540.586485337873, 17127.22927468264, 17878.821211787912, 17898.64202715945, 17900.104994750276, 5012.448630650634, 5254.21186821976, 5547.611667183299, 5553.750081246333, 5628.549716022709, 3463.7748546344533, 5589.580781441408, 5680.971595142039, 5994.8801023979495, 6002.579819412643, 6044.697765111749, 6061.12113743507, 6069.0, 6069.726862291185, 6072.0, 5414.296141501599, 5563.833085007456, 5569.10894256921, 5574.024545704486, 5579.107342825148, 3418.9829050854837, 4784.42586889572, 5100.285959965608, 5106.336176297094, 5390.595705322077, 13022.348882555929, 13143.934280328536, 13240.602781916557, 15433.768493472608, 21372.679409808865, 1936.5255512399413, 2048.5902819436023, 2232.48652809853, 2486.3659766759542, 2496.875156242199, 9403.529823508798, 9429.952850235773, 9561.091696288911, 9840.606375744961, 9851.704448866545, 1727.6717423689583, 1809.6471188118192, 1855.1002763659615, 1892.4038927737718, 1930.4401723500168, 61352.69323653353, 65061.674691626715, 68859.0, 72469.27530724677, 73960.26039739588, 8488.915110848813, 9226.769330766785, 9514.143727064597, 9674.613015479303, 10312.226583006304, 13030.869691303154, 13432.395542200624, 13494.190348579117, 13495.59513214605, 13505.527306544223, 7047.400970917427, 7191.17301510333, 7193.604351760599, 7314.487985841047, 7325.157606875171, 6624.90062649051, 6635.4359879410795, 6696.363845434676, 6819.7272109116075, 6840.555363901331, 4313.568643135662, 4331.6534677225745, 4357.302831546937, 4367.497737760134, 4412.889677758018, 1765.920533575992, 1829.752983347231, 1844.1609067874085, 1875.2780179630668, 1883.5761953560338, 784.9646765895549, 830.5099991005336, 853.4751128056325, 858.411987788367, 882.6513527156866, 16632.168391580373, 17084.18685985511, 17317.220725067404, 17725.911370443064, 17847.643047138983, 819.7663665855202, 839.9580020999048, 840.3445312656138, 846.7629063862129, 852.4075767341762, 9375.48434836077, 9454.14912657864, 9688.273379496566, 9768.658096966572, 9864.0, 406600.0, 410122.9877012335, 411103.0, 420131.0, 420285.0, 81036.0, 81762.59118704368, 81786.0, 83617.58191209006, 85325.14674853296, 862.6635612111281, 876.9517676527724, 880.3925291548811, 883.8099808541194, 939.6570251858022, 12272.509099635914, 15767.132807695229, 15843.207839608203, 16178.838211617733, 17000.70490483769, 36641.45037824381, 38959.41560876646, 39813.40279895746, 39961.600383995785, 40241.1951760969, 44739.0, 57083.0, 57395.0, 57713.0, 58015.419845801, 5652.208690783212, 6373.93626063752, 6894.965525172342, 7221.31397517235, 7422.777316680508, 8956.238719708897, 11296.209265351676, 12124.878751212374, 12155.574554890536, 12470.5011799527, 10527.105196058421, 10753.1935104869, 10817.729556761144, 10874.456277186267, 10984.340939543654, 10421.218408619232, 10584.89415105839, 10596.15230781551, 10851.348919064882, 11006.449677515931, 10294.330868493524, 10610.151187905103, 10651.254412191072, 10938.671839844817, 11262.662120136409, 7897.447178697436, 7991.24083212094, 8048.356131509582, 8125.634346454542, 8165.836683266421, 4972.229304457815, 5068.113080211022, 9489.288303377412, 9882.555285012335, 10697.679069627924], "value": 8177.277201648595, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1352.337354696199, 1374.0450386981047, 1451.7314296855077, 1667.624784423504, 1690.611159433331, 7137.643117844106, 7508.09902811662, 7521.210272921347, 7581.241875812423, 7590.051243594551, 6208.2239720034995, 6367.7771278005275, 6384.138141350916, 6492.448141907944, 7509.474336796413, 2090.4251330883976, 2176.3906106290183, 2181.825453963687, 2188.73735151782, 2190.8575942563684, 2186.7922547357975, 2188.73735151782, 2198.263581700124, 2199.8790066546335, 2208.182972300243, 1840.7330937014126, 2079.6880467929855, 2153.364757396572, 2181.901814418355, 2195.659672750718, 2219.622664147099, 2236.966445503319, 2241.551689662058, 2241.641337386027, 2244.51742875281, 2105.421009222476, 2106.3470324199548, 2154.138344662132, 2154.246013895124, 2157.611629906616, 7448.143463501712, 7549.0563679540055, 7557.584332861746, 7558.09302883652, 7559.470837041355, 4408.757518336491, 4524.0, 4536.705114167602, 4538.863834084983, 4541.364209010741, 2655.137080448862, 2792.343799207205, 2831.8017738758294, 2877.8992735254164, 2909.2435966648823, 872.5244741615771, 875.1991927386434, 896.551724137931, 903.8237543678986, 909.7043460875242, 10011.699649010541, 10017.0, 10018.148457381132, 10021.248406369552, 10033.046860548237, 4000.879973600797, 4029.2545879012478, 4154.688398370074, 4155.729877557949, 4239.385289133058, 8434.873476897852, 8493.787655308543, 8495.490270583785, 8504.872426913602, 8510.319174466027, 4491.326301054803, 4495.280755079171, 4498.460184777782, 4516.841910533116, 4524.389207457017, 3531.611522732497, 3738.27103714773, 4146.834126634901, 4190.769507677046, 4201.642860356843, 3389.9491507627404, 3488.1454043758936, 3534.575850897909, 3536.3457760314423, 3546.3261980224192, 1136.7158210447387, 1156.595191682921, 1167.8890505401973, 1177.8468799056238, 1182.2433642468827, 758.203885919782, 772.9593228916115, 797.960101994898, 857.8713193020972, 868.1362044765499, 5079.771410286546, 5187.870303242374, 5277.472252774692, 5347.3583170019065, 5388.703621300788, 865.4634126841273, 875.5403413208116, 878.8462019146624, 895.583553647557, 907.3013779389833, 3457.8761902381325, 3527.276908233806, 3762.247550489913, 3791.109089363997, 3795.2030073684837, 8866.689665861664, 9187.08129187076, 9324.533773311307, 9334.299927505328, 9341.906580934103, 9010.594523246338, 9329.673461428818, 9339.813203735886, 9345.439273643604, 9346.11211934865, 6995.860082798417, 7038.3665470107935, 7142.178649455275, 7158.06945097134, 7163.283671632795, 5037.760728841963, 5054.09026375256, 5105.897882042264, 5199.324087868553, 5238.292830467838, 8362.581870906308, 8589.312854971713, 8648.048714641258, 8683.478991260545, 8701.17338852808, 8662.610182541926, 8694.17405346491, 8868.911310886808, 8869.73390798277, 8879.33404994615, 6127.785527506516, 6186.783462578788, 6242.313345531897, 6251.499880009497, 6255.093011513396, 7054.506184567236, 7104.467164962752, 7151.141862976268, 7176.533525320838, 7181.533200341961, 6631.171103612049, 6650.700718467778, 6720.932790672031, 6761.121054262914, 6778.694958726771, 6291.465225455704, 6492.48060155177, 6498.480121590356, 6522.804315870531, 6644.568103073285, 5922.230110085834, 5971.611845230046, 6003.429674180946, 6150.876982460415, 6160.383961603804, 4476.664250181315, 4533.025399539115, 4581.358609794698, 4589.724616523019, 4701.177293973609, 4537.251353526631, 4614.400127983341, 4616.192166370939, 4659.2312268475325, 4779.259214821708], "value": 4029.8744137145877, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.22574547775895, 307.24571177758605, 315.4873330837389, 315.7789547316878, 318.4029943855271, 4744.14605371033, 4792.448868380133, 4798.83204087857, 4817.518248175181, 4819.096456899522, 4769.236922092466, 4794.760261986904, 4801.423829140508, 4826.7586620669, 4842.97578512107, 3872.9612703872954, 3879.72841901067, 3886.300465916134, 3889.436031775391, 3898.4542164097047, 3879.398693202544, 3892.9026774330728, 3894.6105389460968, 3898.7660740355527, 3901.804909754515, 3761.9435708464393, 3832.945939866545, 3841.116543195053, 3843.615638436148, 3874.128321127752, 3809.5428548574073, 3811.4092315691114, 3819.7135214858995, 3832.6358995895343, 3833.9616603833883, 3409.8295085245886, 3414.0611331883683, 3414.3781703690547, 3420.811855347955, 3423.4522476403904, 4657.138429390574, 4742.857714268543, 4745.311929770164, 4751.667383283171, 4760.833370832004, 4372.89067773305, 4376.518582955872, 4384.517703052662, 4397.340398940152, 4417.911641767147, 4980.501949805027, 5026.522480364396, 5035.974820125913, 5069.188929771219, 5074.619403544749, 3711.9443208351895, 3719.776813391205, 3737.364648009845, 3744.3821769408014, 3745.5130832991535, 4968.354113965198, 5061.0, 5107.770150343206, 5244.711540865251, 5319.654222475527, 3602.603713591503, 3610.8194590270905, 3611.8013509257244, 3612.2414292998765, 3709.517762690833, 3776.2258736958856, 3785.6782173515007, 3787.6895093992644, 3788.6171547385006, 3791.7156213284115, 4292.59220374064, 4297.140571885635, 4304.741715497017, 4311.159323931866, 4315.503717072514, 4791.353167322367, 4854.0, 4919.188333924933, 4947.158982972904, 4960.479149689303, 1298.9283840831351, 1317.5388613985028, 1319.4788058716754, 1333.7265860498574, 1335.4457899971596, 826.6817275348914, 865.0959747064333, 866.1857853617579, 867.9671191282354, 878.6573236437795, 3962.841486340514, 3971.5631280559114, 3982.78094704794, 3993.660538854172, 3999.6600288975183, 4560.498345182027, 4599.862004139881, 4601.3558101865765, 4607.493175750665, 4618.445786505574, 3958.1687845552756, 3965.7422267552515, 3978.0, 4010.598940106024, 4017.357222844331, 2318.36245032614, 2441.718097998565, 2463.942607922405, 2464.876756162185, 2477.2692055843568, 4367.432233809584, 4381.627561657231, 4397.252467080604, 4405.361222622766, 4436.157130145262, 4714.57568818808, 4726.480087190406, 4747.83382581608, 4756.858353995016, 4767.809287628456, 4366.3013917773005, 4368.628666563314, 4544.295634176708, 4906.786055133893, 4979.377577802775, 5178.766955487087, 5203.219517072469, 5214.452482489284, 5218.791248350023, 5225.817096401608, 5133.845984620468, 5136.768845402041, 5180.456052114474, 5181.430042695225, 5185.533302002837, 4692.882677933079, 4708.0, 4790.7365094920115, 4943.110240156806, 4945.4312754033745, 3670.5778835434267, 3721.9255614888093, 3734.9626503735703, 3742.326381251401, 3742.9812850935573, 5226.686398816083, 5230.607704422107, 5236.869078272921, 5240.633155679066, 5245.423003469692, 4865.270209468608, 4935.481774413634, 4951.653384263067, 4954.9752251238515, 4991.401031876198, 4866.48901865299, 4885.316055752127, 4887.36273348418, 4897.314375987294, 4930.383702037246, 4739.1706451371565, 4750.501197374226, 4772.093302272557, 4825.324454576432, 4844.588210002048, 4758.785854636619, 4932.766808297925, 4955.380577427822, 4964.230544265644, 4974.925376119433, 4775.379200703886, 4795.736234507136, 4799.66402351832, 4800.903981920412, 4815.6388270880525], "value": 3741.6003391892086, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.3878431842704, 678.2369833936821, 710.1584622222666, 725.6589402980599, 741.1883987034203, 12838.743225135491, 13845.515406960758, 13852.515161969333, 13853.445862165514, 13856.237906915116, 13411.530596429127, 13470.595882123553, 13498.0, 13511.729765404685, 13566.0, 636.2746469025315, 637.155768606597, 760.9086909570832, 764.8087978005499, 793.928546430824, 779.0729032451364, 781.4920301803816, 785.5522352259214, 785.7524879662888, 785.8978332816725, 665.8302132956089, 832.1927730101792, 834.7203686764941, 835.2274146414572, 835.5488036460304, 682.6825526130349, 682.8839097353462, 685.0956737106995, 806.6652339279192, 835.064727505192, 609.4606273447986, 610.1092405088576, 610.1275176497606, 610.1671218786369, 610.3164455809477, 13722.45110195591, 13828.516001939885, 13857.514986975508, 13862.792058119136, 13886.4445422183, 12631.242125472409, 12762.298073605947, 13041.739165216643, 13087.541936032194, 13437.899106002582, 11432.085433165326, 11434.113371293726, 11484.196106272564, 11556.653300401002, 11710.238834475816, 4138.0, 4175.206710724983, 4175.853845115436, 4182.790860456996, 4185.874423767262, 61262.38737612611, 61616.38383616151, 61719.691401543154, 61767.6911615439, 62466.68766656183, 9826.213902887896, 9900.900990989998, 10145.28982971185, 10304.175665946706, 10530.57877684884, 19532.218711251677, 19540.51148721265, 19555.12001959915, 19658.0, 19668.70496942547, 6767.492438067165, 6839.1451068616425, 6872.209695884937, 6873.450123990069, 7048.295170482911, 3625.093726568358, 3661.707063434919, 3706.4810926470313, 3755.661990420875, 3763.360228761117, 2810.437912417525, 2840.1479556133077, 2888.8555572221303, 2908.75275601572, 2914.0383673387732, 479.4677907522647, 481.48481125196406, 486.0303694130162, 501.44840675257655, 502.3519659639098, 528.5612941258823, 590.6397097770357, 608.0301918440081, 622.6295354264206, 631.4380201620561, 8798.252148567315, 8843.646254149891, 8919.241864441465, 9034.954825225832, 9049.819003620023, 626.7179769103922, 628.9654069026155, 639.7600899662626, 642.2678146912524, 648.6821457485798, 3821.3503704370323, 3937.350337194388, 3954.387070004154, 3990.7605543667473, 4150.937735933963, 95876.52061739647, 97216.0, 97654.0, 98595.50702246587, 99196.0, 49561.75219123881, 54208.45791542113, 83803.5809820947, 84562.26954323378, 85208.0, 70847.29152708407, 73564.26435735574, 75117.6244118776, 76642.23357766352, 77155.44178744758, 7531.0, 7560.508566943131, 7562.168161502121, 7668.386529077548, 7906.881396778937, 43741.34387984119, 44024.55975440205, 44303.33544996892, 44620.77689611531, 45006.77496612496, 57702.422975771384, 63511.0, 63876.3612363889, 63893.36106638874, 63985.0, 8493.532855692996, 10407.739806504898, 10455.0, 10542.8418573723, 10613.787724245627, 10225.84661230097, 10255.435951022766, 10354.792904141725, 10362.689119326433, 11089.722756930818, 9173.44959302444, 9220.354575179674, 9394.812103758024, 9412.247020238501, 9730.902690973282, 12368.0, 12640.367981601066, 13085.0, 13314.667133321744, 13743.862561374259, 12170.513179473077, 12600.873991260336, 13264.270465124511, 13268.734625307632, 13637.931810341282, 9989.250806189419, 10410.219233557362, 11012.96260191867, 11338.14963877729, 11475.942620286845, 10040.347377420445, 11048.226624136234, 11058.447077646246, 11176.664700059011, 11356.205065645325], "value": 6589.065904151919, "unit": "score"}], "name": "dromaeo_css", "value": 6371.06023378597, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 113.0, 116.0, 114.0, 114.0, 115.0, 112.0, 115.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 124.0, 180.0, 128.0, 126.0, 152.0, 128.0, 183.0, 121.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 161.0, 161.0, 160.0, 161.0, 161.0, 161.0, 163.0, 161.0, 159.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 78.0, 120.0, 75.0, 77.0, 76.0, 77.0, 102.0, 74.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 122.0, 84.0, 84.0, 85.0, 117.0, 86.0, 85.0, 134.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 118.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 145.0, 146.0, 147.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 62.0, 62.0, 61.0, 63.0, 62.0, 62.0, 62.0, 67.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 66.0, 64.0, 64.0, 64.0, 69.0, 64.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 81.0, 80.0, 78.0, 80.0, 80.0, 79.0, 79.0, 78.0, 82.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 127.0, 126.0, 129.0, 127.0, 126.0, 127.0, 128.0, 171.0, 135.0], "value": 132.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 164.0, 168.0, 167.0, 169.0, 167.0, 172.0, 162.0, 164.0, 164.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 68.0, 68.0, 66.0, 69.0, 69.0, 65.0, 65.0, 65.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1526.9, "alertThreshold": 2.0}]} 09:05:07 INFO - Return code: 0 09:05:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:05:07 INFO - # TBPL SUCCESS # 09:05:07 INFO - Running post-action listener: _resource_record_post_action 09:05:07 INFO - ##### 09:05:07 INFO - ##### Finished run-tests step (success) 09:05:07 INFO - ##### 09:05:07 INFO - Running post-run listener: _resource_record_post_run 09:05:07 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 0; Write bytes: 534089728; Read time: 0; Write time: 461428 09:05:07 INFO - TinderboxPrint: CPU usage
13.0% 09:05:07 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:05:07 INFO - TinderboxPrint: I/O write bytes / time
534,089,728 / 461,428 09:05:07 INFO - TinderboxPrint: CPU idle
8,324.5 (87.0%) 09:05:07 INFO - TinderboxPrint: CPU user
1,204.1 (12.6%) 09:05:07 INFO - TinderboxPrint: Swap in / out
0 / 0 09:05:07 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246181888; Read time: 0; Write time: 274636 09:05:07 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 287907840; Read time: 0; Write time: 186792 09:05:08 INFO - Running post-run listener: _upload_blobber_files 09:05:08 INFO - Blob upload gear active. 09:05:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:05:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:05:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:05:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:05:08 INFO - (blobuploader) - INFO - Open directory for files ... 09:05:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:05:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:05:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:09 INFO - (blobuploader) - INFO - Done attempting. 09:05:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:05:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:05:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:10 INFO - (blobuploader) - INFO - Done attempting. 09:05:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:05:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:05:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:13 INFO - (blobuploader) - INFO - Done attempting. 09:05:13 INFO - (blobuploader) - INFO - Iteration through files over. 09:05:13 INFO - Return code: 0 09:05:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:05:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:05:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be397e7d0f5aa7b5a87fdafa5fe56f4bc08123e0dbbf707b2fed455c5d07535318060b4df340cb9f41ee7d71ca966c1ae5d19c346f642fb5cbb3bf524f1f2839", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1947c0a3e9763494077e09e07d6d219df03bb1a329a0601482f642c99238de5e6cc8933251d8446cc4f07a2b3b93ce0c525c2b66bf80d4b6e4e9592c3c960026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e51ea2d1e33306d5d6f081effae4b045cc51018841ba9b72b053c55b86c3d365d729d7f46c7a4cf5c486cc5e8d81a201d1cb0b7a75f7146956a86c1b7c4e60e5"} 09:05:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:05:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:05:13 INFO - Contents: 09:05:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be397e7d0f5aa7b5a87fdafa5fe56f4bc08123e0dbbf707b2fed455c5d07535318060b4df340cb9f41ee7d71ca966c1ae5d19c346f642fb5cbb3bf524f1f2839", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1947c0a3e9763494077e09e07d6d219df03bb1a329a0601482f642c99238de5e6cc8933251d8446cc4f07a2b3b93ce0c525c2b66bf80d4b6e4e9592c3c960026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e51ea2d1e33306d5d6f081effae4b045cc51018841ba9b72b053c55b86c3d365d729d7f46c7a4cf5c486cc5e8d81a201d1cb0b7a75f7146956a86c1b7c4e60e5"} 09:05:13 INFO - Running post-run listener: copy_logs_to_upload_dir 09:05:13 INFO - Copying logs to upload dir... 09:05:13 INFO - mkdir: /builds/slave/test/build/upload/logs 09:05:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.398188 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-07-12 09:05:13.729269) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 09:05:13.732735) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be397e7d0f5aa7b5a87fdafa5fe56f4bc08123e0dbbf707b2fed455c5d07535318060b4df340cb9f41ee7d71ca966c1ae5d19c346f642fb5cbb3bf524f1f2839", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1947c0a3e9763494077e09e07d6d219df03bb1a329a0601482f642c99238de5e6cc8933251d8446cc4f07a2b3b93ce0c525c2b66bf80d4b6e4e9592c3c960026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e51ea2d1e33306d5d6f081effae4b045cc51018841ba9b72b053c55b86c3d365d729d7f46c7a4cf5c486cc5e8d81a201d1cb0b7a75f7146956a86c1b7c4e60e5"} build_url:https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011398 build_url: 'https://queue.taskcluster.net/v1/task/Fp27pcFoQnChnyYoj2T5og/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/Firefox-Non-PGO/sha512/be397e7d0f5aa7b5a87fdafa5fe56f4bc08123e0dbbf707b2fed455c5d07535318060b4df340cb9f41ee7d71ca966c1ae5d19c346f642fb5cbb3bf524f1f2839", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1947c0a3e9763494077e09e07d6d219df03bb1a329a0601482f642c99238de5e6cc8933251d8446cc4f07a2b3b93ce0c525c2b66bf80d4b6e4e9592c3c960026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e51ea2d1e33306d5d6f081effae4b045cc51018841ba9b72b053c55b86c3d365d729d7f46c7a4cf5c486cc5e8d81a201d1cb0b7a75f7146956a86c1b7c4e60e5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 09:05:13.757802) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:05:13.758120) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1468337739.891803-899476394 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004469 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 09:05:13.842805) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 09:05:13.843125) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 09:05:13.843450) ========= ========= Total master_lag: 0.16 =========